builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0180 starttime: 1490220940.66 results: success (0) buildid: 20170322134459 builduid: 013affefb56642618edc21336fe0f180 revision: 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.657407) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.657813) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.658101) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.684520) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.684915) ========= 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.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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-22 15:15:40-- 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% 15.5M=0.001s 2017-03-22 15:15:40 (15.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.283005 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.992491) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:40.992813) ========= 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.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.027355 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.042491) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.042838) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 --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 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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-22 15:15:41,134 truncating revision to first 12 chars 2017-03-22 15:15:41,134 Setting DEBUG logging. 2017-03-22 15:15:41,135 attempt 1/10 2017-03-22 15:15:41,135 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/137343d3973c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-22 15:15:41,413 unpacking tar archive at: mozilla-beta-137343d3973c/testing/mozharness/ program finished with exit code 0 elapsedTime=0.567307 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.633543) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.633843) ========= script_repo_revision: 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.634315) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.634590) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-22 15:15:41.654393) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-22 15:15:41.654687) ========= /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.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:15:41 INFO - MultiFileLogger online at 20170322 15:15:41 in /builds/slave/test 15:15:41 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 15:15:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:15:41 INFO - {'append_to_log': False, 15:15:41 INFO - 'base_work_dir': '/builds/slave/test', 15:15:41 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:15:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:15:41 INFO - 'branch': 'Mozilla-Beta', 15:15:41 INFO - 'buildbot_json_path': 'buildprops.json', 15:15:41 INFO - 'config_files': ('talos/mac_config.py',), 15:15:41 INFO - 'default_actions': ('clobber', 15:15:41 INFO - 'read-buildbot-config', 15:15:41 INFO - 'download-and-extract', 15:15:41 INFO - 'populate-webroot', 15:15:41 INFO - 'create-virtualenv', 15:15:41 INFO - 'install', 15:15:41 INFO - 'run-tests'), 15:15:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:15:41 INFO - 'download_minidump_stackwalk': True, 15:15:41 INFO - 'download_symbols': 'ondemand', 15:15:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:15:41 INFO - 'tooltool.py': '/tools/tooltool.py', 15:15:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:15:41 INFO - '/tools/misc-python/virtualenv.py')}, 15:15:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:15:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:15:41 INFO - 'installer_path': 'installer.exe', 15:15:41 INFO - 'log_level': 'info', 15:15:41 INFO - 'log_name': 'talos', 15:15:41 INFO - 'log_to_console': True, 15:15:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:15:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:15:41 INFO - 'opt_config_files': (), 15:15:41 INFO - 'pip_index': False, 15:15:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:41 INFO - 'cmd': ('bash', 15:15:41 INFO - '-c', 15:15:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:41 INFO - 'enabled': True, 15:15:41 INFO - 'halt_on_failure': False, 15:15:41 INFO - 'name': 'check_screen_resolution'},), 15:15:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:15:41 INFO - 'cmd': ('bash', 15:15:41 INFO - '-c', 15:15:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:15:41 INFO - 'enabled': True, 15:15:41 INFO - 'halt_on_failure': False, 15:15:41 INFO - 'name': 'check_screen_resolution'},), 15:15:41 INFO - 'run_cmd_checks_enabled': True, 15:15:41 INFO - 'sps_profile': False, 15:15:41 INFO - 'sps_profile_interval': 0, 15:15:41 INFO - 'suite': 'dromaeojs-e10s', 15:15:41 INFO - 'system_bits': '32', 15:15:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:15:41 INFO - 'title': 't-yosemite-r7-0180', 15:15:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:15:41 INFO - 'use_talos_json': True, 15:15:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:15:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:15:41 INFO - 'work_dir': 'build'} 15:15:41 INFO - [mozharness: 2017-03-22 22:15:41.857256Z] Running clobber step. 15:15:41 INFO - Running pre-action listener: _resource_record_pre_action 15:15:41 INFO - Running main action method: clobber 15:15:41 INFO - rmtree: /builds/slave/test/build 15:15:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:15:44 INFO - Running post-action listener: _resource_record_post_action 15:15:44 INFO - [mozharness: 2017-03-22 22:15:44.420654Z] Finished clobber step (success) 15:15:44 INFO - [mozharness: 2017-03-22 22:15:44.420823Z] Running read-buildbot-config step. 15:15:44 INFO - Running pre-action listener: _resource_record_pre_action 15:15:44 INFO - Running main action method: read_buildbot_config 15:15:44 INFO - Using buildbot properties: 15:15:44 INFO - { 15:15:44 INFO - "project": "", 15:15:44 INFO - "product": "firefox", 15:15:44 INFO - "script_repo_revision": "production", 15:15:44 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:15:44 INFO - "repository": "", 15:15:44 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:15:44 INFO - "buildid": "20170322134459", 15:15:44 INFO - "pgo_build": "False", 15:15:44 INFO - "basedir": "/builds/slave/test", 15:15:44 INFO - "buildnumber": 22, 15:15:44 INFO - "slavename": "t-yosemite-r7-0180", 15:15:44 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:15:44 INFO - "platform": "yosemite_r7", 15:15:44 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:15:44 INFO - "branch": "mozilla-beta", 15:15:44 INFO - "repo_path": "releases/mozilla-beta", 15:15:44 INFO - "revision": "137343d3973c4187fcc3c5aa0786dc9890c7b3b7", 15:15:44 INFO - "stage_platform": "macosx64", 15:15:44 INFO - "builduid": "013affefb56642618edc21336fe0f180", 15:15:44 INFO - "slavebuilddir": "test" 15:15:44 INFO - } 15:15:44 INFO - Found installer url https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 15:15:44 INFO - Running post-action listener: _resource_record_post_action 15:15:44 INFO - [mozharness: 2017-03-22 22:15:44.424118Z] Finished read-buildbot-config step (success) 15:15:44 INFO - [mozharness: 2017-03-22 22:15:44.424234Z] Running download-and-extract step. 15:15:44 INFO - Running pre-action listener: _resource_record_pre_action 15:15:44 INFO - Running main action method: download_and_extract 15:15:44 INFO - mkdir: /builds/slave/test/build/tests 15:15:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:15:44 INFO - trying https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 15:15:44 INFO - Downloading https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/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 15:15:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/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 15:15:45 INFO - Downloaded 1165 bytes. 15:15:45 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:15:45 INFO - Using the following test package requirements: 15:15:45 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:15:45 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 15:15:45 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 15:15:45 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:15:45 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 15:15:45 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:15:45 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 15:15:45 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 15:15:45 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 15:15:45 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:15:45 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 15:15:45 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 15:15:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:15:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:15:45 INFO - Fetch https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:15:46 INFO - Expected file size: 16108254 15:15:46 INFO - Obtained file size: 16108254 15:15:53 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 15:15:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:15:53 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:15:53 INFO - Fetch https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:15:54 INFO - Expected file size: 16108254 15:15:54 INFO - Obtained file size: 16108254 15:16:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 15:16:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 15:16:00 INFO - Fetch https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 15:16:01 INFO - Expected file size: 14403663 15:16:01 INFO - Obtained file size: 14403663 15:16:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:01 INFO - trying https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:16:01 INFO - Downloading https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 15:16:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/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 15:16:03 INFO - Downloaded 58763031 bytes. 15:16:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:16:03 INFO - mkdir: /builds/slave/test/properties 15:16:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:16:03 INFO - Writing to file /builds/slave/test/properties/build_url 15:16:03 INFO - Contents: 15:16:03 INFO - build_url:https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:16:04 INFO - Running post-action listener: _resource_record_post_action 15:16:04 INFO - Running post-action listener: set_extra_try_arguments 15:16:04 INFO - [mozharness: 2017-03-22 22:16:04.721963Z] Finished download-and-extract step (success) 15:16:04 INFO - [mozharness: 2017-03-22 22:16:04.722112Z] Running populate-webroot step. 15:16:04 INFO - Running pre-action listener: _resource_record_pre_action 15:16:04 INFO - Running main action method: populate_webroot 15:16:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:16:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:16:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:16:04 INFO - '--tppagecycles', 15:16:04 INFO - '7'], 15:16:04 INFO - 'tests': ['tsvgm']}}, 15:16:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:16:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:16:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:16:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:16:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:16:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['damp', 'tps']}, 15:16:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'tests': ['damp', 'tps']}, 15:16:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['dromaeo_dom']}, 15:16:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:16:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['basic_compositor_video']}, 15:16:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:16:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'other-e10s': {'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['a11yr', 15:16:04 INFO - 'ts_paint', 15:16:04 INFO - 'tpaint', 15:16:04 INFO - 'sessionrestore', 15:16:04 INFO - 'sessionrestore_no_auto_restore', 15:16:04 INFO - 'tabpaint']}, 15:16:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['tsvgx', 15:16:04 INFO - 'tsvgr_opacity', 15:16:04 INFO - 'tart', 15:16:04 INFO - 'tscrollx', 15:16:04 INFO - 'cart', 15:16:04 INFO - 'tsvg_static']}, 15:16:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:16:04 INFO - 'tsvgr_opacity', 15:16:04 INFO - 'tart', 15:16:04 INFO - 'tscrollx', 15:16:04 INFO - 'cart', 15:16:04 INFO - 'tsvg_static']}, 15:16:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'talos_options': ['--disable-e10s'], 15:16:04 INFO - 'tests': ['tp5o']}, 15:16:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:16:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'talos_options': ['--disable-e10s', 15:16:04 INFO - '--xperf_path', 15:16:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:04 INFO - 'tests': ['tp5n']}, 15:16:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:16:04 INFO - 'talos_options': ['--xperf_path', 15:16:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:16:04 INFO - 'tests': ['tp5n']}}, 15:16:04 INFO - 'talos.zip': {'path': '', 15:16:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:16:04 INFO - Running post-action listener: _resource_record_post_action 15:16:04 INFO - [mozharness: 2017-03-22 22:16:04.741106Z] Finished populate-webroot step (success) 15:16:04 INFO - [mozharness: 2017-03-22 22:16:04.741243Z] Running create-virtualenv step. 15:16:04 INFO - Running pre-action listener: _resource_record_pre_action 15:16:04 INFO - Running main action method: create_virtualenv 15:16:04 INFO - Creating virtualenv /builds/slave/test/build/venv 15:16:04 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 15:16:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:16:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:16:04 INFO - Using real prefix '/tools/python27' 15:16:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:16:05 INFO - Installing distribute.............................................................................................................................................................................................done. 15:16:08 INFO - Installing pip.................done. 15:16:08 INFO - Return code: 0 15:16:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:16:08 INFO - Reading from file tmpfile_stdout 15:16:08 INFO - Output received: 15:16:08 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 15:16:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:08 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:08 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:16:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:16:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:08 INFO - 'HOME': '/Users/cltbld', 15:16:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:08 INFO - 'LOGNAME': 'cltbld', 15:16:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:08 INFO - 'MOZ_NO_REMOTE': '1', 15:16:08 INFO - 'NO_EM_RESTART': '1', 15:16:08 INFO - 'PAGER': '/bin/cat', 15:16:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:08 INFO - 'PWD': '/builds/slave/test', 15:16:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:08 INFO - 'SHELL': '/bin/bash', 15:16:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:08 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:08 INFO - 'USER': 'cltbld', 15:16:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:08 INFO - 'XPC_FLAGS': '0x0', 15:16:08 INFO - 'XPC_SERVICE_NAME': '0', 15:16:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:09 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 15:16:09 INFO - Cleaning up... 15:16:09 INFO - Return code: 0 15:16:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:16: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')]} 15:16:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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')]} 15:16:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16: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', '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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16: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', 'psutil>=3.1.1'] in /builds/slave/test/build 15:16: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 psutil>=3.1.1 15:16:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:09 INFO - 'HOME': '/Users/cltbld', 15:16:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:09 INFO - 'LOGNAME': 'cltbld', 15:16:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:09 INFO - 'MOZ_NO_REMOTE': '1', 15:16:09 INFO - 'NO_EM_RESTART': '1', 15:16:09 INFO - 'PAGER': '/bin/cat', 15:16:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:09 INFO - 'PWD': '/builds/slave/test', 15:16:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:09 INFO - 'SHELL': '/bin/bash', 15:16:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:09 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:09 INFO - 'USER': 'cltbld', 15:16:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:09 INFO - 'XPC_FLAGS': '0x0', 15:16:09 INFO - 'XPC_SERVICE_NAME': '0', 15:16:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:09 INFO - Downloading/unpacking psutil>=3.1.1 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16: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 15:16:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:16:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:12 INFO - Installing collected packages: psutil 15:16:12 INFO - Running setup.py install for psutil 15:16:12 INFO - building 'psutil._psutil_osx' extension 15:16:12 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 15:16:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 15:16:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 15:16:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 15:16:13 INFO - building 'psutil._psutil_posix' extension 15:16:13 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 15:16:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:16:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:16:13 INFO - ^ 15:16:13 INFO - 1 warning generated. 15:16:13 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 15:16:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:16:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:16:14 INFO - Successfully installed psutil 15:16:14 INFO - Cleaning up... 15:16:14 INFO - Return code: 0 15:16:14 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:16:14 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')]} 15:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:14 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')]} 15:16:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:14 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:14 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 15:16:14 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 15:16:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:14 INFO - 'HOME': '/Users/cltbld', 15:16:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:14 INFO - 'LOGNAME': 'cltbld', 15:16:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:14 INFO - 'MOZ_NO_REMOTE': '1', 15:16:14 INFO - 'NO_EM_RESTART': '1', 15:16:14 INFO - 'PAGER': '/bin/cat', 15:16:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:14 INFO - 'PWD': '/builds/slave/test', 15:16:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:14 INFO - 'SHELL': '/bin/bash', 15:16:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:14 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:14 INFO - 'USER': 'cltbld', 15:16:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:14 INFO - 'XPC_FLAGS': '0x0', 15:16:14 INFO - 'XPC_SERVICE_NAME': '0', 15:16:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:14 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:14 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:14 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:14 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:14 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:16 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:16:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:16:17 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:16:17 INFO - Installing collected packages: mozsystemmonitor 15:16:17 INFO - Running setup.py install for mozsystemmonitor 15:16:17 INFO - Successfully installed mozsystemmonitor 15:16:17 INFO - Cleaning up... 15:16:17 INFO - Return code: 0 15:16:17 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:16:17 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')]} 15:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:17 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')]} 15:16:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:17 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:17 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 15:16:17 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 15:16:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:17 INFO - 'HOME': '/Users/cltbld', 15:16:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:17 INFO - 'LOGNAME': 'cltbld', 15:16:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:17 INFO - 'MOZ_NO_REMOTE': '1', 15:16:17 INFO - 'NO_EM_RESTART': '1', 15:16:17 INFO - 'PAGER': '/bin/cat', 15:16:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:17 INFO - 'PWD': '/builds/slave/test', 15:16:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:17 INFO - 'SHELL': '/bin/bash', 15:16:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:17 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:17 INFO - 'USER': 'cltbld', 15:16:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:17 INFO - 'XPC_FLAGS': '0x0', 15:16:17 INFO - 'XPC_SERVICE_NAME': '0', 15:16:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:17 INFO - Downloading/unpacking jsonschema==2.5.1 15:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:20 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:16:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:16:21 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 15:16:21 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 15:16:21 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 15:16:21 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 15:16:21 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 15:16:21 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 15:16:21 INFO - Downloading functools32-3.2.3-2.tar.gz 15:16:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:16:21 INFO - warning: no files found matching '*.txt' 15:16:21 INFO - no previously-included directories found matching 'build' 15:16:21 INFO - no previously-included directories found matching 'dist' 15:16:21 INFO - no previously-included directories found matching '.git*' 15:16:21 INFO - Installing collected packages: jsonschema, functools32 15:16:21 INFO - Running setup.py install for functools32 15:16:22 INFO - warning: no files found matching '*.txt' 15:16:22 INFO - no previously-included directories found matching 'build' 15:16:22 INFO - no previously-included directories found matching 'dist' 15:16:22 INFO - no previously-included directories found matching '.git*' 15:16:22 INFO - Successfully installed jsonschema functools32 15:16:22 INFO - Cleaning up... 15:16:22 INFO - Return code: 0 15:16:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 15:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 15:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:22 INFO - 'HOME': '/Users/cltbld', 15:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:22 INFO - 'LOGNAME': 'cltbld', 15:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:22 INFO - 'MOZ_NO_REMOTE': '1', 15:16:22 INFO - 'NO_EM_RESTART': '1', 15:16:22 INFO - 'PAGER': '/bin/cat', 15:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:22 INFO - 'PWD': '/builds/slave/test', 15:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:22 INFO - 'SHELL': '/bin/bash', 15:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:22 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:22 INFO - 'USER': 'cltbld', 15:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:22 INFO - 'XPC_FLAGS': '0x0', 15:16:22 INFO - 'XPC_SERVICE_NAME': '0', 15:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:16:22 INFO - Cleaning up... 15:16:22 INFO - Return code: 0 15:16:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 15:16:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 15:16:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:22 INFO - 'HOME': '/Users/cltbld', 15:16:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:22 INFO - 'LOGNAME': 'cltbld', 15:16:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:22 INFO - 'MOZ_NO_REMOTE': '1', 15:16:22 INFO - 'NO_EM_RESTART': '1', 15:16:22 INFO - 'PAGER': '/bin/cat', 15:16:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:22 INFO - 'PWD': '/builds/slave/test', 15:16:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:22 INFO - 'SHELL': '/bin/bash', 15:16:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:22 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:22 INFO - 'USER': 'cltbld', 15:16:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:22 INFO - 'XPC_FLAGS': '0x0', 15:16:22 INFO - 'XPC_SERVICE_NAME': '0', 15:16:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:22 INFO - Downloading/unpacking blobuploader==1.2.4 15:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:26 INFO - Downloading blobuploader-1.2.4.tar.gz 15:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:16:26 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:16:26 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:16:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:16:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:16:27 INFO - Downloading docopt-0.6.1.tar.gz 15:16:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:16:27 INFO - Installing collected packages: blobuploader, requests, docopt 15:16:27 INFO - Running setup.py install for blobuploader 15:16:27 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:16:27 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:16:27 INFO - Running setup.py install for requests 15:16:28 INFO - Running setup.py install for docopt 15:16:28 INFO - Successfully installed blobuploader requests docopt 15:16:28 INFO - Cleaning up... 15:16:28 INFO - Return code: 0 15:16:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:28 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')]} 15:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 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')]} 15:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:28 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:28 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 15:16:28 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 15:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:28 INFO - 'HOME': '/Users/cltbld', 15:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:28 INFO - 'LOGNAME': 'cltbld', 15:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:28 INFO - 'MOZ_NO_REMOTE': '1', 15:16:28 INFO - 'NO_EM_RESTART': '1', 15:16:28 INFO - 'PAGER': '/bin/cat', 15:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:28 INFO - 'PWD': '/builds/slave/test', 15:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:28 INFO - 'SHELL': '/bin/bash', 15:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:28 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:28 INFO - 'USER': 'cltbld', 15:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:28 INFO - 'XPC_FLAGS': '0x0', 15:16:28 INFO - 'XPC_SERVICE_NAME': '0', 15:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-3stiw7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-xMkBBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:28 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ffbEiH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-3071f_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-eHt4vg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-YrSrBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-HOC_mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-lFg8w1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-sE739Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-5DxfUC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-VYfYUV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-M8cWfZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:29 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hPQ6ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-X30btW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8VvWs1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-z9Tigf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:30 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-RhRZlu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:30 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:16:30 INFO - Running setup.py install for manifestparser 15:16:30 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:16:30 INFO - Running setup.py install for mozcrash 15:16:30 INFO - Running setup.py install for mozdebug 15:16:30 INFO - Running setup.py install for mozdevice 15:16:31 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Running setup.py install for mozfile 15:16:31 INFO - Running setup.py install for mozhttpd 15:16:31 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Running setup.py install for mozinfo 15:16:31 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Running setup.py install for mozInstall 15:16:31 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:16:31 INFO - Running setup.py install for mozleak 15:16:31 INFO - Running setup.py install for mozlog 15:16:31 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Running setup.py install for moznetwork 15:16:32 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Running setup.py install for mozprocess 15:16:32 INFO - Running setup.py install for mozprofile 15:16:32 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Running setup.py install for mozrunner 15:16:32 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:16:32 INFO - Running setup.py install for mozscreenshot 15:16:32 INFO - Running setup.py install for moztest 15:16:32 INFO - Running setup.py install for mozversion 15:16:33 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:16:33 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:16:33 INFO - Cleaning up... 15:16:33 INFO - Return code: 0 15:16:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:33 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')]} 15:16:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:33 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')]} 15:16:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:33 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:33 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 15:16:33 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 15:16:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:33 INFO - 'HOME': '/Users/cltbld', 15:16:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:33 INFO - 'LOGNAME': 'cltbld', 15:16:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:33 INFO - 'MOZ_NO_REMOTE': '1', 15:16:33 INFO - 'NO_EM_RESTART': '1', 15:16:33 INFO - 'PAGER': '/bin/cat', 15:16:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:33 INFO - 'PWD': '/builds/slave/test', 15:16:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:33 INFO - 'SHELL': '/bin/bash', 15:16:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:33 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:33 INFO - 'USER': 'cltbld', 15:16:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:33 INFO - 'XPC_FLAGS': '0x0', 15:16:33 INFO - 'XPC_SERVICE_NAME': '0', 15:16:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-G6fbxf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:16:33 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)) 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Qqdj80-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:16:33 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)) 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ZWEd8A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:16:33 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)) 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-m235ol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:16:33 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)) 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-H30yls-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:16:33 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)) 15:16:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:16:33 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-8Ej_ff-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-hlosbS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-Jllz6y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-uznmwP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-uOKYnt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-XV8x4N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-ro1edb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-sdx2fz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-JP4u9d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-DHcC96-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:16:34 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)) 15:16:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:16:34 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-EmxBQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:16:35 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)) 15:16:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:16:35 INFO - Running setup.py (path:/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/pip-BUHtTw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:16:35 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)) 15:16:35 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)) 15:16:35 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)) 15:16:35 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)) 15:16:35 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)) 15:16:35 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)) 15:16:35 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:35 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 15:16:37 INFO - Downloading blessings-1.6.tar.gz 15:16:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:16:37 INFO - Installing collected packages: blessings 15:16:37 INFO - Running setup.py install for blessings 15:16:38 INFO - Successfully installed blessings 15:16:38 INFO - Cleaning up... 15:16:38 INFO - Return code: 0 15:16:38 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:16:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:38 INFO - Reading from file tmpfile_stdout 15:16:38 INFO - Current package versions: 15:16:38 INFO - blessings == 1.6 15:16:38 INFO - blobuploader == 1.2.4 15:16:38 INFO - docopt == 0.6.1 15:16:38 INFO - functools32 == 3.2.3-2 15:16:38 INFO - jsonschema == 2.5.1 15:16:38 INFO - manifestparser == 1.1 15:16:38 INFO - mozInstall == 1.12 15:16:38 INFO - mozcrash == 1.0 15:16:38 INFO - mozdebug == 0.1 15:16:38 INFO - mozdevice == 0.49 15:16:38 INFO - mozfile == 1.2 15:16:38 INFO - mozhttpd == 0.7 15:16:38 INFO - mozinfo == 0.9 15:16:38 INFO - mozleak == 0.1 15:16:38 INFO - mozlog == 3.4 15:16:38 INFO - moznetwork == 0.27 15:16:38 INFO - mozprocess == 0.24 15:16:38 INFO - mozprofile == 0.28 15:16:38 INFO - mozrunner == 6.13 15:16:38 INFO - mozscreenshot == 0.1 15:16:38 INFO - mozsystemmonitor == 0.3 15:16:38 INFO - moztest == 0.8 15:16:38 INFO - mozversion == 1.4 15:16:38 INFO - psutil == 3.1.1 15:16:38 INFO - requests == 1.2.3 15:16:38 INFO - wsgiref == 0.1.2 15:16:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:16:38 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')]} 15:16:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:38 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')]} 15:16:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:39 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 15:16:39 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 15:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:39 INFO - 'HOME': '/Users/cltbld', 15:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:39 INFO - 'LOGNAME': 'cltbld', 15:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:39 INFO - 'MOZ_NO_REMOTE': '1', 15:16:39 INFO - 'NO_EM_RESTART': '1', 15:16:39 INFO - 'PAGER': '/bin/cat', 15:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:39 INFO - 'PWD': '/builds/slave/test', 15:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:39 INFO - 'SHELL': '/bin/bash', 15:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:39 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:39 INFO - 'USER': 'cltbld', 15:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:39 INFO - 'XPC_FLAGS': '0x0', 15:16:39 INFO - 'XPC_SERVICE_NAME': '0', 15:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 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)) 15:16:39 INFO - Cleaning up... 15:16:39 INFO - Return code: 0 15:16:39 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:16:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:16:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:16:39 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 0x10854ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10854d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8b23c838c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, '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.50vpQjghNn/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.EH9Q8QJzMG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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 15:16:39 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 15:16:39 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 15:16:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:39 INFO - 'HOME': '/Users/cltbld', 15:16:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:39 INFO - 'LOGNAME': 'cltbld', 15:16:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:39 INFO - 'MOZ_NO_REMOTE': '1', 15:16:39 INFO - 'NO_EM_RESTART': '1', 15:16:39 INFO - 'PAGER': '/bin/cat', 15:16:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:39 INFO - 'PWD': '/builds/slave/test', 15:16:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:39 INFO - 'SHELL': '/bin/bash', 15:16:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:39 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:39 INFO - 'USER': 'cltbld', 15:16:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:39 INFO - 'XPC_FLAGS': '0x0', 15:16:39 INFO - 'XPC_SERVICE_NAME': '0', 15:16:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:16:40 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:16:40 INFO - Cleaning up... 15:16:40 INFO - Return code: 0 15:16:40 INFO - Running post-action listener: _resource_record_post_action 15:16:40 INFO - Running post-action listener: _start_resource_monitoring 15:16:40 INFO - Starting resource monitoring. 15:16:40 INFO - [mozharness: 2017-03-22 22:16:40.210903Z] Finished create-virtualenv step (success) 15:16:40 INFO - [mozharness: 2017-03-22 22:16:40.211731Z] Running install step. 15:16:40 INFO - Running pre-action listener: _resource_record_pre_action 15:16:40 INFO - Running main action method: install 15:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:16:40 INFO - Reading from file tmpfile_stdout 15:16:40 INFO - Detecting whether we're running mozinstall >=1.0... 15:16:40 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:16:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:16:40 INFO - Reading from file tmpfile_stdout 15:16:40 INFO - Output received: 15:16:40 INFO - Usage: mozinstall [options] installer 15:16:40 INFO - Options: 15:16:40 INFO - -h, --help show this help message and exit 15:16:40 INFO - -d DEST, --destination=DEST 15:16:40 INFO - Directory to install application into. [default: 15:16:40 INFO - "/builds/slave/test"] 15:16:40 INFO - --app=APP Application being installed. [default: firefox] 15:16:40 INFO - mkdir: /builds/slave/test/build/application 15:16:40 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'] 15:16:40 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 15:16:55 INFO - Reading from file tmpfile_stdout 15:16:55 INFO - Output received: 15:16:55 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:16:55 INFO - Running post-action listener: _resource_record_post_action 15:16:55 INFO - [mozharness: 2017-03-22 22:16:55.169672Z] Finished install step (success) 15:16:55 INFO - [mozharness: 2017-03-22 22:16:55.169820Z] Running run-tests step. 15:16:55 INFO - Running pre-action listener: _resource_record_pre_action 15:16:55 INFO - Running main action method: run_tests 15:16:55 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:16:55 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:16:55 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:16:55 INFO - 2017-03-22 15:16:55.184 screenresolution[1642:7412] starting screenresolution argv=screenresolution get 15:16:55 INFO - 2017-03-22 15:16:55.187 screenresolution[1642:7412] Display 0: 1600x1200x32@60 15:16:55 INFO - 2017-03-22 15:16:55.193 screenresolution[1643:7414] starting screenresolution argv=screenresolution list 15:16:55 INFO - Available Modes on Display 0 15:16:55 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:16:55 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:16:55 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:16:55 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:16:55 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:16:55 INFO - Intel Iris: 15:16:55 INFO - Chipset Model: Intel Iris 15:16:55 INFO - Type: GPU 15:16:55 INFO - Bus: Built-In 15:16:55 INFO - VRAM (Dynamic, Max): 1536 MB 15:16:55 INFO - Vendor: Intel (0x8086) 15:16:55 INFO - Device ID: 0x0a2e 15:16:55 INFO - Revision ID: 0x0009 15:16:55 INFO - Displays: 15:16:55 INFO - Display: 15:16:55 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:16:55 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:16:55 INFO - Display Serial Number: 1600x1200 60 15:16:55 INFO - Main Display: Yes 15:16:55 INFO - Mirror: Off 15:16:55 INFO - Online: Yes 15:16:55 INFO - Rotation: Supported 15:16:55 INFO - Return code: 0 15:16:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:16:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:16:55 INFO - Python 2.7.3 15:16:55 INFO - Return code: 0 15:16:55 INFO - grabbing minidump binary from tooltool 15:16:55 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')]} 15:16:55 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 0x108328d30>, '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 0x10824fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10831dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:16:55 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 15:16:55 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 15:16:55 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 15:16:55 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:16:55 INFO - Return code: 0 15:16:55 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:16:55 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:16:55 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:16:55 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:16:55 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:16:55 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/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 15:16:55 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/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0180 --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 15:16:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.50vpQjghNn/Render', 15:16:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:16:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:16:55 INFO - 'HOME': '/Users/cltbld', 15:16:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:16:55 INFO - 'LOGNAME': 'cltbld', 15:16:55 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:16:55 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:16:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:16:55 INFO - 'MOZ_NO_REMOTE': '1', 15:16:55 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:16:55 INFO - 'NO_EM_RESTART': '1', 15:16:55 INFO - 'PAGER': '/bin/cat', 15:16:55 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:16:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:16:55 INFO - 'PWD': '/builds/slave/test', 15:16:55 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:16:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:16:55 INFO - 'SHELL': '/bin/bash', 15:16:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.EH9Q8QJzMG/Listeners', 15:16:55 INFO - 'TMPDIR': '/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/', 15:16:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:16:55 INFO - 'USER': 'cltbld', 15:16:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:16:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:16:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:16:55 INFO - 'XPC_FLAGS': '0x0', 15:16:55 INFO - 'XPC_SERVICE_NAME': '0', 15:16:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:16:55 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/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0180', '--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 15:16:55 INFO - mozversion application_buildid: 20170322134459 15:16:55 INFO - mozversion application_changeset: 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 15:16:55 INFO - mozversion application_display_name: Firefox 15:16:55 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:16:55 INFO - mozversion application_name: Firefox 15:16:55 INFO - mozversion application_remotingname: firefox 15:16:55 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:16:55 INFO - mozversion application_vendor: Mozilla 15:16:55 INFO - mozversion application_version: 53.0 15:16:55 INFO - mozversion platform_buildid: 20170322134459 15:16:55 INFO - mozversion platform_changeset: 137343d3973c4187fcc3c5aa0786dc9890c7b3b7 15:16:55 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:16:55 INFO - mozversion platform_version: 53.0 15:16:55 INFO - using testdate: 1490221015 15:16:55 INFO - actual date: 1490221015 15:16:55 INFO - starting webserver on 'localhost:49250' 15:16:55 INFO - SUITE-START | Running 2 tests 15:16:55 INFO - TEST-START | dromaeo_css 15:16:55 INFO - operating with platform_type : mac_ 15:16:55 INFO - Initialising browser for dromaeo_css test... 15:16:55 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpcEP9ej/profile http://localhost:49250/getInfo.html) 15:16:58 INFO - PROCESS | 1653 | 2017-03-22 15:16:58.596 plugin-container[1656:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f4f, name = 'com.apple.tsm.portname' 15:16:58 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:16:58 INFO - PROCESS | 1653 | 2017-03-22 15:16:58.597 plugin-container[1656:7587] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x940b, name = 'com.apple.CFPasteboardClient' 15:16:58 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:16:58 INFO - PROCESS | 1653 | 2017-03-22 15:16:58.597 plugin-container[1656:7587] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:16:58 INFO - PROCESS | 1653 | __metrics Screen width/height:1600/1200 15:16:58 INFO - PROCESS | 1653 | colorDepth:24 15:16:58 INFO - PROCESS | 1653 | Browser inner width/height: 1024/689 15:16:58 INFO - PROCESS | 1653 | __metrics 15:16:58 ERROR - PROCESS | 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:16:59 INFO - TEST-INFO | 1653: exit 0 15:16:59 INFO - Browser initialized. 15:16:59 INFO - Running cycle 1/1 for dromaeo_css test... 15:16:59 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpcEP9ej/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:17:01 INFO - PROCESS | 1657 | 2017-03-22 15:17:01.690 plugin-container[1658:7714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833f, name = 'com.apple.tsm.portname' 15:17:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:01 INFO - PROCESS | 1657 | 2017-03-22 15:17:01.691 plugin-container[1658:7714] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 15:17:01 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:17:01 INFO - PROCESS | 1657 | 2017-03-22 15:17:01.691 plugin-container[1658:7714] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:17:11 INFO - PROCESS | 1657 | RSS: Main: 172879872 15:17:11 INFO - PROCESS | 1657 | 15:20:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49250/tests/dromaeo/cssquery-ext.html) 15:20:14 INFO - PROCESS | 1657 | RSS: Main: 188887040 15:20:14 INFO - PROCESS | 1657 | 15:23:17 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-ext.html (next: http://localhost:49250/tests/dromaeo/cssquery-jquery.html) 15:23:17 INFO - PROCESS | 1657 | RSS: Main: 201117696 15:23:17 INFO - PROCESS | 1657 | 15:26:19 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49250/tests/dromaeo/cssquery-mootools.html) 15:26:20 INFO - PROCESS | 1657 | RSS: Main: 203829248 15:26:20 INFO - PROCESS | 1657 | 15:29:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49250/tests/dromaeo/cssquery-prototype.html) 15:29:23 INFO - PROCESS | 1657 | RSS: Main: 206917632 15:29:23 INFO - PROCESS | 1657 | 15:32:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49250/tests/dromaeo/cssquery-yui.html) 15:32:26 INFO - PROCESS | 1657 | RSS: Main: 208097280 15:32:26 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49250/tests/dromaeo/cssquery-yui.html (next: http://localhost:49250/tests/dromaeo/cssquery-dojo.html) 15:35:29 INFO - PROCESS | 1657 | RSS: Main: 208347136 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | __start_tp_report 15:35:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 15:35:29 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 15:35:29 INFO - PROCESS | 1657 | |i|pagename|runs| 15:35:29 INFO - PROCESS | 1657 | |0;dojo.html;3520.2783429396973;3523.92952140957;3545;3576.81964951566;3579.874704385347;5891.410858914106;5932.911006334898;5938.821835344936;5989.850253743648;6039.18471006414;5992.28092628884;6005.969970150154;6053.303870054945;6072.453479186872;6100.145979562853;5337.2794672719265;5351.438098999617;5434.701091439969;5493.945060549383;5534.557235421156;5219.42586315505;5366;5471.7264136793;5477.616566840323;5496.5053145216925;5278.10272253714;5334;5379.300690910176;5414;5422.15956526735;5271.551918086967;5274.314339135926;5323.414424413311;5327.840164795062;5348.491893270133;5253.921191182136;5264.15773476242;5286.735663216824;5289.86775330616;5313.519644632733;5789.218455508494;5800.361960184377;5857.85355366115;5865.413458654143;5918.733656985446;6228.657423841687;6234.563580549363;6251.937480625181;6298.307186209513;6334;6339.619622822645;6344.777932772377;6352.904706429407;6358.078078678612;6363.649999250039;6087.873743357493;6096.634201947809;6116.29662588808;6124.142620033199;6127.5404344674325;6210.472109870621;6267.341929097379;6376.649284289409;6397.584157029778;6412.973924172204;6286.9056964145575;6292.307846136921;6297.307296197415;6313.337099604475;6317.652529110851;6350.65071421067;6358.33237510064;6370.713317900706;6371.585846919936;6375.171227740364;6200.472959798378;6215.254169499689;6255.311915689271;6256.374362563707;6261.904166770797;5355.973220133875;6061.363556826558;6264.091706702594;6286.057091436322;6297.244330680348;4248.468941382327;5092.032513822436;5107.61692873036;5107.693538387709;5136.049830781318;4431.268840641323;4498.235299999008;4544.727316361029;4554.0891821635805;4596.310553416947;4843.75781210938;6141.508679305645;6146.108814221912;6195.16365290689;6287.7738840926495;6362.459190968727;6369.20384951881;6380.202474690664;6389.073584330246;6397.6801159941815;5379.515843574097;5867.530597552186;6047;6076.726547305293;6082.726277317532;5683.744231509592;5689.573282003867;5697.116946873241;5737.053386191315;5752.36723960364;6587.47300215972;6619.3711597397205;6620.304867988888;6624.072629831828;6672.566283191577;6217.626942383471;6226.128342032029;6258;6333.65164915934;6399.200099987502;6502.057201705821;6604.867902641824;6625.536212465274;6633.2040155181685;6652.068710380648;6280.277768056732;6297.149884765498;6312.084747711464;6318.178636777373;6318.368163183645;6183.783567575113;6232.626042437469;6353.5870168438905;6369.426751592379;6375.075614035846;5175.197844333984;6209.782657606962;6235.750569977332;6261.812145635638;6294.527910406646;5197.7141257231215;6253.093301471352;6276.215473065867;6289.245290565162;6289.937100628935;4828.686135401188;5963;6131.570790044654;6223.875522489434;6286.182796236643;4479.328100784908;6209.751609935552;6220.626762394089;6234.220722409699;6286.497080233662;6252.749890004531;6263.686815659108;6304.18045654062;6311.936880631135;6318.08387783778 15:35:29 INFO - PROCESS | 1657 | |1;ext.html;479.2475812973632;482.36568911880863;501.3157040639528;557.3868744381181;578.9160571717103;39548.40677389838;42840.143197136116;42893.14213715728;42970.570294297046;43151.56848431515;43500.12999740011;43936.780316098455;44279.11441771163;44313.335299970444;44570.88572785682;9004.954975225115;9223.308251881102;9629.662961796355;9863.95068024659;9882.9505852471;10241.795164096713;11913.463894124741;12227;12287.508499660047;12400.751984960296;11933.16467847251;12018;12071.698207544794;12132.514699412013;12173.087018473605;9808.852867206997;11889;11894.881051189464;11946.104042196826;11954.641360759191;11888.989435897958;11992.880071199264;12067.577634782829;12070.939645301805;12084.818727719092;42504;42700.57299427028;42811.92970175739;42865;42870.57129428697;3515.402381595135;4032.980820935647;4059.411385349137;4212.283911735012;4335.154644844257;30058.54912176319;30190.24524386886;30347.69652303471;30419.543706844415;32521.186970325696;3318.8506517206783;3496.35317466267;3512.3502152101687;3535.6287589803214;3558.181618227796;418384;419893.9005304954;421465;423736;424378;64395.03407448892;64650.676746615965;64809.703805923615;64821.351786482475;65085.349146508874;68296.6585167071;68536.65731671409;68630;68668.62662746717;68720.3127968714;17717.379891703727;17988.100594970198;18010.729839052423;18026.63946721058;18043.278268869097;12570.245785252731;12918.935405322913;13484.460621575223;13552;13717.725645487035;17799.288028478717;17883.37408190707;17907.835990660566;17973.370932017577;17980.460586182435;6314.778982735674;7023.403010744144;7074.575525468385;7086.787396378117;7156.642167891585;894.1594900793234;899.9505027223565;906.9047749986156;931.4783721116197;936.6393938333654;30891.38217235643;31027.844860775553;31047.758089676612;31194.844025779727;31440.37119257602;919.2416256588341;1033.9017793309624;1068.636663534402;1114.9575147237358;1238.504598160743;11639.53441862316;14313;17142.142892855307;17647.823521764876;17648.382306619205;608715;612790;619966;621365;621722;60016.69991650014;63505.36494635087;63621.68189159025;63800.68099659472;63805;1454.4473100221849;1472.7349077165998;1475.8081449411509;1511.9017263877813;1513.583764464759;27012.864935675196;32804.85183018583;32820.01539953805;32892.50661240036;33321.83339083289;39811;52890.206646899554;53785.731071344395;53808;53985;506564;511676;512290;513255.433722829;513816;39992;40448.79775601103;41579.16841663211;41858;41896.79051604722;40526.39210411787;48168.5183148164;48644.756776215894;48761.02477950492;49481.752591236815;30201.395972080874;36018.37917293676;36196.190190490895;38827.61172388353;39653.00867478336;36579.817100914326;38531.42202867015;39581.80209098936;40051.79974100111;40374.990625234605;35756.64243357533;36677.449838251916;38904;39264.2515023957;39322.01694957649;32853.83573082119;34976.475352870235;35711.64288357083;35728.82135589409;35949.28101438009;25783.226503204933;26076.73923260743;26126.477470450864;26672.733272667025;26799.06203282876 15:35:29 INFO - PROCESS | 1657 | |2;jquery.html;355.2770112820411;400.5133762478588;408.50978825409516;408.56895974746647;424.9164630193007;20202.19191232351;21540;21551.353459396203;21963.341099766993;22110.33668989929;21821.67267490985;22112.115515379406;22351.441213969658;22415;22458.98934547945;5710.942890571093;5990.071538911454;6013.428724271209;6024.819255422322;6029.577929544934;5727.799527016556;5813.622114562561;5843.415658434165;5895.145203945425;5959.791407300745;5758.078707406816;5845.7953971611005;5855.970720146393;5856.063029915214;5870;5648.378678345379;5847.590668653196;5882.4705776480105;5905;5922.615030023035;5232.372115346145;5234.816781412632;5238.423773384925;5254.842354729364;5256.080185967478;24492;25646.48707025849;26037;26297.3425664358;26473.47053058947;2655.8937642494275;2693.946121077587;2716.212298433452;2718.972810271892;2752.1055656911585;10302.02130797573;10341.586336546528;10406.531706073245;10739.570417183304;10743.301685390425;1826.9451916442529;1877.6150889067708;1887.5186827359073;1907.1036612791966;1930.1314408516084;137384;139869.3006534961;141433.29283353518;142739;143310;12083.516659333704;12162.939185304016;12250.75498490043;12288;12306.138570300172;16178.75731864023;16192.433264835674;16234.67530649404;16249.212586615462;16256.918715406584;8688.913110868936;8808.383413161146;8853.424527405698;8895.911040889509;8949.642014319485;7014.614196219257;7292.817679558054;8448.831023379498;8597.441166324168;8745.737627871174;4852.660313778072;4948.950510494921;4964.032013757283;5020.698758074527;5027.4721154279005;1698.4649835302087;1748.370586588827;2058.3310424111924;2067.8345732341377;2070.1615845582564;1024.472396715692;1031.1439409063655;1049.8372752223418;1063.292910214714;1083.566573370658;21827.908604569708;22063;22153.00311485987;22228.777712222993;22355;805.3932404852357;1052.3843551522446;1052.8736551613856;1094.179365475893;1102.3385968419045;10858.239923205458;11428.599999000162;11477.54089836414;11500.53997840077;11501.539938402537;750806;765468;780867;786205;797497;161456;166355;166947.16526417533;167289.16355418146;169486.15256923882;1013.2046343620223;1026.071405378134;1049.197364016528;1083.6748975307376;1091.7161538000178;18933.431997040112;19657.901710491355;19693.60612787765;19719;19756.209751609775;53179.734101329246;57025.85948281094;63680.68159659172;63794.04308935277;65557.67221163864;69907.65046174737;77015.07462313487;94912.10175796584;95238;95630.52184739032;6562.5406221565945;7347.485676002629;7660.5403675779635;8019.6391162398995;8654.4807311559;11654.359010254517;13159.736805264032;13164.670883227613;13209.735805284034;13517.662058448224;8233.50598964064;11291.717707057389;11430.142739294752;11751.236169648946;11894.881051189723;11329.433528323716;11450.82823757661;11581.88418115808;11907.702307442383;12035.097367697634;10127.848082278919;11708.590199342982;11833.704157396134;11877.287362758263;12051.397430128283;7105.8630619100695;8404.579771011302;8713.782155446164;8783.472991620523;8851.203391694778;10856.945715271373;10924.945375273073;11514.136439767219;11622.244554103956;11952.940235298767 15:35:29 INFO - PROCESS | 1657 | |3;mootools.html;1401.6215621782112;1462.839087700353;1469.772185311277;1472.9410823567057;1476.4684713503143;7692.9230707692905;7951.681932722684;8252.79368015799;8671.39300248982;8714.651413943435;8114.756557303291;8469.830603387929;8501.702440414587;8552.70065547706;8566.05773364931;2371.419002344424;2382.857028578282;2411.360989337827;2417.7944874685672;2423.53952748978;2053.2095143369834;2414.082648593532;2420.298113547069;2432.416220107179;2451.7915977141965;2358.13927916311;2393.3179043972473;2408.6989126359204;2425.7695518925675;2426.454047839231;2011.8390528757664;2375.643653451979;2378.0725517048363;2402.0872068613817;2406.0135344508753;1904.2858927902037;2047.3550831488033;2393.8324317297797;2424.066734307287;2427.963580546293;8516.574171291411;8575.1853573911;8580.570971451403;8583.613737381771;8594.699185528476;5116.462771408986;5165.251038599382;5305.965336759334;5335.51980321769;5347.491988261109;3440.535527703753;3527.9647203528148;3644.2893635741043;3667.8899633010788;3670.3393389189937;1216.951321947121;1247.7566874459485;1290.6257185416218;1299.9610011699665;1303.7001489657432;11050.447477626087;11054.502547385306;11096.833547496793;11100.945410186101;11102.111831053497;4636.198489339351;4720.929186062212;4773.474917759044;4846.442659094249;4850.878728031827;9523.952380238194;9556.092171243721;9617.2306215504;9681.612735490502;9691.660791872251;5391.110466773017;5399.946000539944;5400.7569659364635;5409.215663728737;5414.675119492843;4429.955700443019;4541.931871021938;4883.926741098886;4913.11563918491;4973.52751488608;3972.6424621784176;3997.920041599152;4070.3283958146576;4082.9591704083173;4121.175764847043;1309.0640192262579;1309.2733532889338;1312.8949684025256;1318.5714642741148;1322.444573279225;806.1334065879087;864.2394692670409;907.804821963281;909.486140330711;928.2527565309855;5039.269305950616;5670.234518340054;5788.247527821356;5812.186293918855;6133.6319820810895;827.0034608296987;914.2685851318919;980.352967041749;1002.9648962286285;1026.4046852825343;3702.66675999156;3741.3452645787424;3875.0893540018074;3982.7012974026484;3994.6404823566018;8578.18507241811;9905;9978.800423991624;10170.694879153638;10287.53706083213;10142.442165680959;10191.7452063699;10198.98010198989;10227.539760710786;10267.486625668687;7583.734569290048;7972.3622110231045;7999.440039197317;8097.109317975018;8103.837923241502;5274.604404669742;5781.190633311256;5945.762169513344;5966.194563734014;6002.039673652369;8134.87797683047;9051;9056.637734490547;9136.680216192668;9346.906530934604;9322.95338523303;9339.719808405758;9447.905520944703;9456.621735130793;9487.857682134636;6736.360045795642;6927.89608155868;7071.540349877241;7156.355927966508;7175.2465991070185;7965.203479651988;8175.223353781381;8187.918120818954;8195.71315003972;8206.138355472829;5946.316173639913;6583.572067815577;6615.437687796399;6867.690953906987;7138.85722285541;7158.248383919614;7198.6400679964745;7208.855822883618;7211.459140564374;7265.0405528987885;6119.724612392364;6234.314225435289;6262.46769024638;6317.652529110943;6374.521910856612;5035.647504674637;5059.139946209079;5085.821996230262;5089.821856235162;5120.206368012964;4938.726160919043;5059.772310246121;5131.640785145004;5152.866369398726;5195.532402083831 15:35:29 INFO - PROCESS | 1657 | |4;prototype.html;317.2797749109521;385.47190349221563;386.1832224844455;389.1652405590011;391.874600127959;4687.976560117195;4774.331593576893;4829.420469543656;5589.217509548666;5659.717014149297;4825.383496528659;4916.410030796309;5062.240663900417;5243.767714587069;5610.877824435119;3580.53453051103;3850.7111966602615;4045.332520134189;4394.670399720026;4404;4011.5386730525925;4324.97837510812;4421;4432.490263619677;4436.489803672586;3652.9452058219017;4404.581564751359;4405.929213269774;4408.889777755566;4465;3435.278591495789;3815.79802362255;4133.222714232878;4241.1093670329265;4446.888827779299;3150.8499397719665;3279.884839154675;3335.8498867550775;3533.840977156034;3640.2173532690335;5402.621816472849;5431.728413579344;5433.402325744165;5450.5367043801325;5458.645188062763;3969.821358038895;4388.49532303786;4712.269598212282;4814.350062741555;4858.927116093226;4901.338319326881;5361.839144825662;5621.578381621354;5925;5986.371430999726;3377.7804442711144;4020.979895100535;4026.53694825093;4043.696722745806;4082.755034697928;5406;5684.914726279109;6029.336772954972;6264.906026409608;6266.934621114421;3905.941410878839;4094.0788322627172;4097.651699605507;4112.943528235882;4119.505659320901;3661.469086982426;3855.0169706724605;4178.895527611773;4195.517515485758;4196.61511701144;3607;4065;4327.723321620129;4863.416390033219;4898.730569818623;4932.558383600141;5420.349558053059;5694.857628559319;5917.881642367214;5927.140564618193;1193.826895100223;1199.9760004799855;1240.4046057892263;1335.5525898824003;1343.5767733164023;845.7843249971341;852.7697521669238;855.9728326008815;859.4113032572708;903.8508646073324;4544.2501987171745;5097.929434818656;5379.838604841861;5385.919211211835;5481.835544933659;4612.815487380467;5564.4713752193475;5885.911711324334;5901.436119428355;5908.881822363614;4832.202686556749;5288.2596436498925;5347.144456886879;5355.7054362009685;5696;2496.0530785986957;2610.699769526491;3057.755379569629;3077.9846100769355;3096.829674367886;6123.4488895999575;6133.362654058708;6152.661603611755;6157.353477884847;6171.753129874754;4901.877453063702;5670.319561652545;5749.8562535936935;6015.864368149331;6085.817425477243;4966.30010642074;5027.924581131286;5771.989901767174;6032.849178770566;6221.160143380676;4918.778654960463;5388.410418926348;5546.060587888425;5555.805546805843;6052.727627256872;5011.298418221379;5144.806982309406;5444.700541470257;5497.87293604802;5545.670818256262;5290.756672182111;6298.811035668938;6376.8984812149165;6411.871762564816;6418.229812422539;3390.983045084759;3428.7942723435676;3788.2802267569073;4058.9188216236103;4113.526944401432;5125;6000.789972350947;6020.8795824084145;6072.295899501212;6081.756729730761;5945.3757355477055;5957.851053723692;5981.521478281814;5988.341282459013;6009.609375390585;5585.413531579288;5674.205611214516;5901.970490147522;5905.4685078343155;5951.642901425928;4374.58441448062;4873.001034787931;5473.343198816167;5515.979543784413;5878.2358293421585;5009.699418034929;5919.260092488439;5938.6436813791315;5951.036158067741;5971.671558064349;5581.5255703265675;5619.80330688424;5765.798197063083;5814.767409303749;5830.82507524775 15:35:29 INFO - PROCESS | 1657 | |5;yui.html;640.2828831708484;700.3136925812706;746.6266866566716;762.272030211148;808.7695006923026;14747.705045899076;15371.231438428089;15373.154476503787;15431.768523472143;17184.742228866577;16177.433789817329;16757.91621041896;17254.82745172548;17448.040357780315;17467.65064698705;874.3617159473604;907.5552979040256;908.0783005249676;908.8954770201412;913.6254135804321;696.3176087434292;893.4371346051978;898.074982767747;898.5237823953315;898.8471959766855;763.7975936376864;917.614601867214;946.9668561600346;958.8753462049924;963.1283688262134;942.3356533643764;948.7722946492827;951.0822056715268;952.8761261036055;954.2080073538946;687.1753895325579;705.6577559883452;711.8078118907918;713.6824113269597;714.4820005496026;15033.699326013528;15475.226238688021;15684.921575392164;15800;15876.603084922854;14784.260786960716;15050.32273547693;15127.848721512864;15173.772393414107;15210.16344101074;11428.71428214293;11492.540298388054;11729.354885481294;11888.702782430422;11951;3336.382769187708;3869.496965394509;3922.215556888605;4073.185362927397;4074.1444296697728;108610.37084443739;109025;109386;109425.81148377068;109844.4507777464;11524.308541487537;11622.128340374506;11638.825417618742;11863.050955923678;12047.39763011846;24504.87747561251;24611.753882461304;24925.5014899701;24994.375140621265;25100.49799004046;8050.959745201237;8065.1934806518875;8070.596470176585;8076.636551355203;8211.137830527829;4241.618254357061;4338.783060846945;4341.891452713644;4450.910981780346;4682.882927926761;3592.856285748541;3695.667389934865;3697.90755231116;3700.278445703062;3703.5926048134684;679.4564348521262;681.5910453727723;690.8169335126196;699.8460338725574;714.3320994869813;688.9931100689029;722.797616667334;725.4414101142091;741.4105785900223;759.51770625652;9384.8592271116;9818.576306435669;10630.521626526825;10918.23572349928;11130.944345278383;601.4406601860312;669.718718138383;730.481358235648;740.7777666699968;755.2598453515585;4592.747398893093;5050.217216331474;5061.038402703474;5131.923021154686;5133.178691409356;73999;76405.61797191089;108115.45942270238;108856;111167;91079.81220657245;93699.53150234206;94620;95192.57211141838;97160.51419742857;74755.62622186854;76715.23284767193;82263;84360;84862.15137848665;9783.804323913624;9909.702708918743;9972.789724763248;10042.196624270187;10073.294869359075;48207;48436.51563484461;48667.026659465904;49220.261696073874;49295;58184;71620.92568611371;72012.55974880369;73503.52992940218;74330.62834685792;13536.729265414833;13880.375383107583;14141.858581414053;14372.928135359256;14461.421543138156;14922;15351.923240383727;15565.92217038953;15584.766228506809;15657.138857362954;13732;13940.372683229076;14143.292835357985;14188.858111419167;14738.26308684583;13446.394912229169;13877.925745563432;13973.650658733206;14186.716265674835;14226.288685565472;13397.263150526815;13970.441182352999;13981.510647127301;14006.154581578017;14148.938829587616;11075;11404.372759498307;11465.713357165803;11673.941630292135;11776.823347649963;10261.384316941007;11228.66314010581;11295;11520.329552214715;11602.593909213138 15:35:29 INFO - PROCESS | 1657 | __end_tp_report 15:35:29 INFO - PROCESS | 1657 | __start_cc_report 15:35:29 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,383 15:35:29 INFO - PROCESS | 1657 | __end_cc_report 15:35:29 INFO - PROCESS | 1657 | __startTimestamp1490222129410__endTimestamp 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | ------- Summary: start ------- 15:35:29 INFO - PROCESS | 1657 | Number of tests: 6 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#0] dojo.html Cycles:165 Average:5874.71 Median:6195.16 stddev:669.61 (10.8%) stddev-sans-first:645.69 15:35:29 INFO - PROCESS | 1657 | Values: 3520.3 3523.9 3545.0 3576.8 3579.9 5891.4 5932.9 5938.8 5989.9 6039.2 5992.3 6006.0 6053.3 6072.5 6100.1 5337.3 5351.4 5434.7 5493.9 5534.6 5219.4 5366.0 5471.7 5477.6 5496.5 5278.1 5334.0 5379.3 5414.0 5422.2 5271.6 5274.3 5323.4 5327.8 5348.5 5253.9 5264.2 5286.7 5289.9 5313.5 5789.2 5800.4 5857.9 5865.4 5918.7 6228.7 6234.6 6251.9 6298.3 6334.0 6339.6 6344.8 6352.9 6358.1 6363.6 6087.9 6096.6 6116.3 6124.1 6127.5 6210.5 6267.3 6376.6 6397.6 6413.0 6286.9 6292.3 6297.3 6313.3 6317.7 6350.7 6358.3 6370.7 6371.6 6375.2 6200.5 6215.3 6255.3 6256.4 6261.9 5356.0 6061.4 6264.1 6286.1 6297.2 4248.5 5092.0 5107.6 5107.7 5136.0 4431.3 4498.2 4544.7 4554.1 4596.3 4843.8 6141.5 6146.1 6195.2 6287.8 6362.5 6369.2 6380.2 6389.1 6397.7 5379.5 5867.5 6047.0 6076.7 6082.7 5683.7 5689.6 5697.1 5737.1 5752.4 6587.5 6619.4 6620.3 6624.1 6672.6 6217.6 6226.1 6258.0 6333.7 6399.2 6502.1 6604.9 6625.5 6633.2 6652.1 6280.3 6297.1 6312.1 6318.2 6318.4 6183.8 6232.6 6353.6 6369.4 6375.1 5175.2 6209.8 6235.8 6261.8 6294.5 5197.7 6253.1 6276.2 6289.2 6289.9 4828.7 5963.0 6131.6 6223.9 6286.2 4479.3 6209.8 6220.6 6234.2 6286.5 6252.7 6263.7 6304.2 6311.9 6318.1 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#1] ext.html Cycles:180 Average:67300.87 Median:39565.10 stddev:139115.83 (351.6%) stddev-sans-first:139415.61 15:35:29 INFO - PROCESS | 1657 | Values: 479.2 482.4 501.3 557.4 578.9 39548.4 42840.1 42893.1 42970.6 43151.6 43500.1 43936.8 44279.1 44313.3 44570.9 9005.0 9223.3 9629.7 9864.0 9883.0 10241.8 11913.5 12227.0 12287.5 12400.8 11933.2 12018.0 12071.7 12132.5 12173.1 9808.9 11889.0 11894.9 11946.1 11954.6 11889.0 11992.9 12067.6 12070.9 12084.8 42504.0 42700.6 42811.9 42865.0 42870.6 3515.4 4033.0 4059.4 4212.3 4335.2 30058.5 30190.2 30347.7 30419.5 32521.2 3318.9 3496.4 3512.4 3535.6 3558.2 418384.0 419893.9 421465.0 423736.0 424378.0 64395.0 64650.7 64809.7 64821.4 65085.3 68296.7 68536.7 68630.0 68668.6 68720.3 17717.4 17988.1 18010.7 18026.6 18043.3 12570.2 12918.9 13484.5 13552.0 13717.7 17799.3 17883.4 17907.8 17973.4 17980.5 6314.8 7023.4 7074.6 7086.8 7156.6 894.2 900.0 906.9 931.5 936.6 30891.4 31027.8 31047.8 31194.8 31440.4 919.2 1033.9 1068.6 1115.0 1238.5 11639.5 14313.0 17142.1 17647.8 17648.4 608715.0 612790.0 619966.0 621365.0 621722.0 60016.7 63505.4 63621.7 63800.7 63805.0 1454.4 1472.7 1475.8 1511.9 1513.6 27012.9 32804.9 32820.0 32892.5 33321.8 39811.0 52890.2 53785.7 53808.0 53985.0 506564.0 511676.0 512290.0 513255.4 513816.0 39992.0 40448.8 41579.2 41858.0 41896.8 40526.4 48168.5 48644.8 48761.0 49481.8 30201.4 36018.4 36196.2 38827.6 39653.0 36579.8 38531.4 39581.8 40051.8 40375.0 35756.6 36677.4 38904.0 39264.3 39322.0 32853.8 34976.5 35711.6 35728.8 35949.3 25783.2 26076.7 26126.5 26672.7 26799.1 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#2] jquery.html Cycles:180 Average:42419.78 Median:22111.23 stddev:129576.36 (586.0%) stddev-sans-first:129901.36 15:35:29 INFO - PROCESS | 1657 | Values: 355.3 400.5 408.5 408.6 424.9 20202.2 21540.0 21551.4 21963.3 22110.3 21821.7 22112.1 22351.4 22415.0 22459.0 5710.9 5990.1 6013.4 6024.8 6029.6 5727.8 5813.6 5843.4 5895.1 5959.8 5758.1 5845.8 5856.0 5856.1 5870.0 5648.4 5847.6 5882.5 5905.0 5922.6 5232.4 5234.8 5238.4 5254.8 5256.1 24492.0 25646.5 26037.0 26297.3 26473.5 2655.9 2693.9 2716.2 2719.0 2752.1 10302.0 10341.6 10406.5 10739.6 10743.3 1826.9 1877.6 1887.5 1907.1 1930.1 137384.0 139869.3 141433.3 142739.0 143310.0 12083.5 12162.9 12250.8 12288.0 12306.1 16178.8 16192.4 16234.7 16249.2 16256.9 8688.9 8808.4 8853.4 8895.9 8949.6 7014.6 7292.8 8448.8 8597.4 8745.7 4852.7 4949.0 4964.0 5020.7 5027.5 1698.5 1748.4 2058.3 2067.8 2070.2 1024.5 1031.1 1049.8 1063.3 1083.6 21827.9 22063.0 22153.0 22228.8 22355.0 805.4 1052.4 1052.9 1094.2 1102.3 10858.2 11428.6 11477.5 11500.5 11501.5 750806.0 765468.0 780867.0 786205.0 797497.0 161456.0 166355.0 166947.2 167289.2 169486.2 1013.2 1026.1 1049.2 1083.7 1091.7 18933.4 19657.9 19693.6 19719.0 19756.2 53179.7 57025.9 63680.7 63794.0 65557.7 69907.7 77015.1 94912.1 95238.0 95630.5 6562.5 7347.5 7660.5 8019.6 8654.5 11654.4 13159.7 13164.7 13209.7 13517.7 8233.5 11291.7 11430.1 11751.2 11894.9 11329.4 11450.8 11581.9 11907.7 12035.1 10127.8 11708.6 11833.7 11877.3 12051.4 7105.9 8404.6 8713.8 8783.5 8851.2 10856.9 10924.9 11514.1 11622.2 11952.9 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#3] mootools.html Cycles:180 Average:5512.09 Median:5180.39 stddev:2973.77 (57.4%) stddev-sans-first:2966.06 15:35:29 INFO - PROCESS | 1657 | Values: 1401.6 1462.8 1469.8 1472.9 1476.5 7692.9 7951.7 8252.8 8671.4 8714.7 8114.8 8469.8 8501.7 8552.7 8566.1 2371.4 2382.9 2411.4 2417.8 2423.5 2053.2 2414.1 2420.3 2432.4 2451.8 2358.1 2393.3 2408.7 2425.8 2426.5 2011.8 2375.6 2378.1 2402.1 2406.0 1904.3 2047.4 2393.8 2424.1 2428.0 8516.6 8575.2 8580.6 8583.6 8594.7 5116.5 5165.3 5306.0 5335.5 5347.5 3440.5 3528.0 3644.3 3667.9 3670.3 1217.0 1247.8 1290.6 1300.0 1303.7 11050.4 11054.5 11096.8 11100.9 11102.1 4636.2 4720.9 4773.5 4846.4 4850.9 9524.0 9556.1 9617.2 9681.6 9691.7 5391.1 5399.9 5400.8 5409.2 5414.7 4430.0 4541.9 4883.9 4913.1 4973.5 3972.6 3997.9 4070.3 4083.0 4121.2 1309.1 1309.3 1312.9 1318.6 1322.4 806.1 864.2 907.8 909.5 928.3 5039.3 5670.2 5788.2 5812.2 6133.6 827.0 914.3 980.4 1003.0 1026.4 3702.7 3741.3 3875.1 3982.7 3994.6 8578.2 9905.0 9978.8 10170.7 10287.5 10142.4 10191.7 10199.0 10227.5 10267.5 7583.7 7972.4 7999.4 8097.1 8103.8 5274.6 5781.2 5945.8 5966.2 6002.0 8134.9 9051.0 9056.6 9136.7 9346.9 9323.0 9339.7 9447.9 9456.6 9487.9 6736.4 6927.9 7071.5 7156.4 7175.2 7965.2 8175.2 8187.9 8195.7 8206.1 5946.3 6583.6 6615.4 6867.7 7138.9 7158.2 7198.6 7208.9 7211.5 7265.0 6119.7 6234.3 6262.5 6317.7 6374.5 5035.6 5059.1 5085.8 5089.8 5120.2 4938.7 5059.8 5131.6 5152.9 5195.5 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#4] prototype.html Cycles:180 Average:4656.41 Median:5134.90 stddev:1461.57 (28.5%) stddev-sans-first:1428.93 15:35:29 INFO - PROCESS | 1657 | Values: 317.3 385.5 386.2 389.2 391.9 4688.0 4774.3 4829.4 5589.2 5659.7 4825.4 4916.4 5062.2 5243.8 5610.9 3580.5 3850.7 4045.3 4394.7 4404.0 4011.5 4325.0 4421.0 4432.5 4436.5 3652.9 4404.6 4405.9 4408.9 4465.0 3435.3 3815.8 4133.2 4241.1 4446.9 3150.8 3279.9 3335.8 3533.8 3640.2 5402.6 5431.7 5433.4 5450.5 5458.6 3969.8 4388.5 4712.3 4814.4 4858.9 4901.3 5361.8 5621.6 5925.0 5986.4 3377.8 4021.0 4026.5 4043.7 4082.8 5406.0 5684.9 6029.3 6264.9 6266.9 3905.9 4094.1 4097.7 4112.9 4119.5 3661.5 3855.0 4178.9 4195.5 4196.6 3607.0 4065.0 4327.7 4863.4 4898.7 4932.6 5420.3 5694.9 5917.9 5927.1 1193.8 1200.0 1240.4 1335.6 1343.6 845.8 852.8 856.0 859.4 903.9 4544.3 5097.9 5379.8 5385.9 5481.8 4612.8 5564.5 5885.9 5901.4 5908.9 4832.2 5288.3 5347.1 5355.7 5696.0 2496.1 2610.7 3057.8 3078.0 3096.8 6123.4 6133.4 6152.7 6157.4 6171.8 4901.9 5670.3 5749.9 6015.9 6085.8 4966.3 5027.9 5772.0 6032.8 6221.2 4918.8 5388.4 5546.1 5555.8 6052.7 5011.3 5144.8 5444.7 5497.9 5545.7 5290.8 6298.8 6376.9 6411.9 6418.2 3391.0 3428.8 3788.3 4058.9 4113.5 5125.0 6000.8 6020.9 6072.3 6081.8 5945.4 5957.9 5981.5 5988.3 6009.6 5585.4 5674.2 5902.0 5905.5 5951.6 4374.6 4873.0 5473.3 5516.0 5878.2 5009.7 5919.3 5938.6 5951.0 5971.7 5581.5 5619.8 5765.8 5814.8 5830.8 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - PROCESS | 1657 | [#5] yui.html Cycles:180 Average:21023.51 Median:3997.70 stddev:29765.44 (744.6%) stddev-sans-first:29809.59 15:35:29 INFO - PROCESS | 1657 | Values: 640.3 700.3 746.6 762.3 808.8 14747.7 15371.2 15373.2 15431.8 17184.7 16177.4 16757.9 17254.8 17448.0 17467.7 874.4 907.6 908.1 908.9 913.6 696.3 893.4 898.1 898.5 898.8 763.8 917.6 947.0 958.9 963.1 942.3 948.8 951.1 952.9 954.2 687.2 705.7 711.8 713.7 714.5 15033.7 15475.2 15684.9 15800.0 15876.6 14784.3 15050.3 15127.8 15173.8 15210.2 11428.7 11492.5 11729.4 11888.7 11951.0 3336.4 3869.5 3922.2 4073.2 4074.1 108610.4 109025.0 109386.0 109425.8 109844.5 11524.3 11622.1 11638.8 11863.1 12047.4 24504.9 24611.8 24925.5 24994.4 25100.5 8051.0 8065.2 8070.6 8076.6 8211.1 4241.6 4338.8 4341.9 4450.9 4682.9 3592.9 3695.7 3697.9 3700.3 3703.6 679.5 681.6 690.8 699.8 714.3 689.0 722.8 725.4 741.4 759.5 9384.9 9818.6 10630.5 10918.2 11130.9 601.4 669.7 730.5 740.8 755.3 4592.7 5050.2 5061.0 5131.9 5133.2 73999.0 76405.6 108115.5 108856.0 111167.0 91079.8 93699.5 94620.0 95192.6 97160.5 74755.6 76715.2 82263.0 84360.0 84862.2 9783.8 9909.7 9972.8 10042.2 10073.3 48207.0 48436.5 48667.0 49220.3 49295.0 58184.0 71620.9 72012.6 73503.5 74330.6 13536.7 13880.4 14141.9 14372.9 14461.4 14922.0 15351.9 15565.9 15584.8 15657.1 13732.0 13940.4 14143.3 14188.9 14738.3 13446.4 13877.9 13973.7 14186.7 14226.3 13397.3 13970.4 13981.5 14006.2 14148.9 11075.0 11404.4 11465.7 11673.9 11776.8 10261.4 11228.7 11295.0 11520.3 11602.6 15:35:29 INFO - PROCESS | 1657 | -------- Summary: end -------- 15:35:29 INFO - PROCESS | 1657 | 15:35:29 INFO - TEST-INFO | 1657: exit 0 15:35:30 INFO - TEST-OK | dromaeo_css | took 1114226ms 15:35:30 INFO - TEST-START | kraken 15:35:30 INFO - operating with platform_type : mac_ 15:35:30 INFO - Initialising browser for kraken test... 15:35:30 INFO - TEST-INFO | started process 1670 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpkdV_pd/profile http://localhost:49250/getInfo.html) 15:35:32 INFO - PROCESS | 1670 | 2017-03-22 15:35:32.590 plugin-container[1672:9921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833b, name = 'com.apple.tsm.portname' 15:35:32 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:35:32 INFO - PROCESS | 1670 | 2017-03-22 15:35:32.590 plugin-container[1672:9921] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:35:32 INFO - PROCESS | 1670 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:35:32 INFO - PROCESS | 1670 | 2017-03-22 15:35:32.590 plugin-container[1672:9921] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:35:32 INFO - PROCESS | 1670 | __metrics Screen width/height:1600/1200 15:35:32 INFO - PROCESS | 1670 | colorDepth:24 15:35:32 INFO - PROCESS | 1670 | Browser inner width/height: 1024/689 15:35:32 INFO - PROCESS | 1670 | __metrics 15:35:32 ERROR - PROCESS | 1670 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:35:33 INFO - TEST-INFO | 1670: exit 0 15:35:33 INFO - Browser initialized. 15:35:33 INFO - Running cycle 1/1 for kraken test... 15:35:33 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/T/tmpkdV_pd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:35:35 INFO - PROCESS | 1673 | 2017-03-22 15:35:35.825 plugin-container[1674:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x833b, name = 'com.apple.tsm.portname' 15:35:35 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:35:35 INFO - PROCESS | 1673 | 2017-03-22 15:35:35.826 plugin-container[1674:10045] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4513, name = 'com.apple.CFPasteboardClient' 15:35:35 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:35:35 INFO - PROCESS | 1673 | 2017-03-22 15:35:35.826 plugin-container[1674:10045] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:35:45 INFO - PROCESS | 1673 | RSS: Main: 172998656 15:35:45 INFO - PROCESS | 1673 | 15:35:48 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection) 15:35:49 INFO - PROCESS | 1673 | RSS: Main: 185815040 15:35:49 INFO - PROCESS | 1673 | 15:35:51 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-dft) 15:35:52 INFO - PROCESS | 1673 | RSS: Main: 193794048 15:35:52 INFO - PROCESS | 1673 | 15:35:55 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-fft) 15:35:56 INFO - PROCESS | 1673 | RSS: Main: 197689344 15:35:56 INFO - PROCESS | 1673 | 15:35:58 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator) 15:35:59 INFO - PROCESS | 1673 | RSS: Main: 199012352 15:35:59 INFO - PROCESS | 1673 | 15:36:01 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:36:01 INFO - PROCESS | 1673 | RSS: Main: 198922240 15:36:01 INFO - PROCESS | 1673 | 15:36:04 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom) 15:36:05 INFO - PROCESS | 1673 | RSS: Main: 202989568 15:36:05 INFO - PROCESS | 1673 | 15:36:08 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate) 15:36:09 INFO - PROCESS | 1673 | RSS: Main: 202240000 15:36:09 INFO - PROCESS | 1673 | 15:36:12 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial) 15:36:12 INFO - PROCESS | 1673 | RSS: Main: 202850304 15:36:12 INFO - PROCESS | 1673 | 15:36:14 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:36:15 INFO - PROCESS | 1673 | RSS: Main: 202252288 15:36:15 INFO - PROCESS | 1673 | 15:36:17 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:36:17 INFO - PROCESS | 1673 | RSS: Main: 202248192 15:36:17 INFO - PROCESS | 1673 | 15:36:19 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:36:20 INFO - PROCESS | 1673 | RSS: Main: 202145792 15:36:20 INFO - PROCESS | 1673 | 15:36:23 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:36:24 INFO - PROCESS | 1673 | RSS: Main: 202575872 15:36:24 INFO - PROCESS | 1673 | 15:36:26 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:36:27 INFO - PROCESS | 1673 | RSS: Main: 202547200 15:36:27 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | Cycle 1(1): loaded http://localhost:49250/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49250/tests/kraken/driver.html?testName=ai-astar) 15:36:29 INFO - PROCESS | 1673 | RSS: Main: 204541952 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | __start_tp_report 15:36:29 INFO - PROCESS | 1673 | _x_x_mozilla_page_load 15:36:29 INFO - PROCESS | 1673 | _x_x_mozilla_page_load_details 15:36:29 INFO - PROCESS | 1673 | |i|pagename|runs| 15:36:29 INFO - PROCESS | 1673 | |0;ai-astar;115;124;119;120;122;120;129;180;162;119 15:36:29 INFO - PROCESS | 1673 | |1;audio-beat-detection;180;116;120;183;144;114;113;144;160;112 15:36:29 INFO - PROCESS | 1673 | |2;audio-dft;195;204;195;202;227;220;223;273;204;198 15:36:29 INFO - PROCESS | 1673 | |3;audio-fft;99;83;87;75;86;86;88;83;76;58 15:36:29 INFO - PROCESS | 1673 | |4;audio-oscillator;80;72;83;72;70;72;82;69;71;81 15:36:29 INFO - PROCESS | 1673 | |5;imaging-gaussian-blur;89;80;138;119;89;103;90;85;90;129 15:36:29 INFO - PROCESS | 1673 | |6;imaging-darkroom;157;140;99;154;175;100;99;99;100;101 15:36:29 INFO - PROCESS | 1673 | |7;imaging-desaturate;100;95;92;91;92;91;92;92;92;111 15:36:29 INFO - PROCESS | 1673 | |8;json-parse-financial;57;59;59;73;81;53;58;84;95;95 15:36:29 INFO - PROCESS | 1673 | |9;json-stringify-tinderbox;68;74;77;71;87;58;66;60;67;71 15:36:29 INFO - PROCESS | 1673 | |10;stanford-crypto-aes;82;86;86;82;81;85;84;85;78;74 15:36:29 INFO - PROCESS | 1673 | |11;stanford-crypto-ccm;138;132;132;139;134;133;138;140;185;151 15:36:29 INFO - PROCESS | 1673 | |12;stanford-crypto-pbkdf2;168;167;165;166;167;163;167;173;169;168 15:36:29 INFO - PROCESS | 1673 | |13;stanford-crypto-sha256-iterative;66;64;64;63;65;66;69;62;64;73 15:36:29 INFO - PROCESS | 1673 | __end_tp_report 15:36:29 INFO - PROCESS | 1673 | __start_cc_report 15:36:29 INFO - PROCESS | 1673 | _x_x_mozilla_cycle_collect,946 15:36:29 INFO - PROCESS | 1673 | __end_cc_report 15:36:29 INFO - PROCESS | 1673 | __startTimestamp1490222189372__endTimestamp 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | ------- Summary: start ------- 15:36:29 INFO - PROCESS | 1673 | Number of tests: 14 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#0] ai-astar Cycles:10 Average:131.00 Median:123.00 stddev:21.81 (17.7%) stddev-sans-first:22.35 15:36:29 INFO - PROCESS | 1673 | Values: 115.0 124.0 119.0 120.0 122.0 120.0 129.0 180.0 162.0 119.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:144.00 stddev:27.94 (19.4%) stddev-sans-first:25.30 15:36:29 INFO - PROCESS | 1673 | Values: 180.0 116.0 120.0 183.0 144.0 114.0 113.0 144.0 160.0 112.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#2] audio-dft Cycles:10 Average:214.10 Median:212.00 stddev:23.78 (11.2%) stddev-sans-first:24.20 15:36:29 INFO - PROCESS | 1673 | Values: 195.0 204.0 195.0 202.0 227.0 220.0 223.0 273.0 204.0 198.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#3] audio-fft Cycles:10 Average:82.10 Median:86.00 stddev:10.77 (12.5%) stddev-sans-first:9.54 15:36:29 INFO - PROCESS | 1673 | Values: 99.0 83.0 87.0 75.0 86.0 86.0 88.0 83.0 76.0 58.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#4] audio-oscillator Cycles:10 Average:75.20 Median:76.00 stddev:5.55 (7.3%) stddev-sans-first:5.61 15:36:29 INFO - PROCESS | 1673 | Values: 80.0 72.0 83.0 72.0 70.0 72.0 82.0 69.0 71.0 81.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#5] imaging-gaussian-blur Cycles:10 Average:101.20 Median:87.00 stddev:20.30 (23.3%) stddev-sans-first:21.04 15:36:29 INFO - PROCESS | 1673 | Values: 89.0 80.0 138.0 119.0 89.0 103.0 90.0 85.0 90.0 129.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#6] imaging-darkroom Cycles:10 Average:122.40 Median:166.00 stddev:30.51 (18.4%) stddev-sans-first:29.68 15:36:29 INFO - PROCESS | 1673 | Values: 157.0 140.0 99.0 154.0 175.0 100.0 99.0 99.0 100.0 101.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#7] imaging-desaturate Cycles:10 Average:94.80 Median:92.00 stddev:6.30 (6.9%) stddev-sans-first:6.40 15:36:29 INFO - PROCESS | 1673 | Values: 100.0 95.0 92.0 91.0 92.0 91.0 92.0 92.0 92.0 111.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#8] json-parse-financial Cycles:10 Average:71.40 Median:77.00 stddev:16.33 (21.2%) stddev-sans-first:16.47 15:36:29 INFO - PROCESS | 1673 | Values: 57.0 59.0 59.0 73.0 81.0 53.0 58.0 84.0 95.0 95.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.00 stddev:8.36 (11.8%) stddev-sans-first:8.84 15:36:29 INFO - PROCESS | 1673 | Values: 68.0 74.0 77.0 71.0 87.0 58.0 66.0 60.0 67.0 71.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#10] stanford-crypto-aes Cycles:10 Average:82.30 Median:84.50 stddev:3.86 (4.6%) stddev-sans-first:4.09 15:36:29 INFO - PROCESS | 1673 | Values: 82.0 86.0 86.0 82.0 81.0 85.0 84.0 85.0 78.0 74.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#11] stanford-crypto-ccm Cycles:10 Average:142.20 Median:138.50 stddev:16.05 (11.6%) stddev-sans-first:16.96 15:36:29 INFO - PROCESS | 1673 | Values: 138.0 132.0 132.0 139.0 134.0 133.0 138.0 140.0 185.0 151.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.30 Median:167.50 stddev:2.63 (1.6%) stddev-sans-first:2.77 15:36:29 INFO - PROCESS | 1673 | Values: 168.0 167.0 165.0 166.0 167.0 163.0 167.0 173.0 169.0 168.0 15:36:29 INFO - PROCESS | 1673 | 15:36:29 INFO - PROCESS | 1673 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.60 Median:65.50 stddev:3.24 (4.9%) stddev-sans-first:3.43 15:36:29 INFO - PROCESS | 1673 | Values: 66.0 64.0 64.0 63.0 65.0 66.0 69.0 62.0 64.0 73.0 15:36:29 INFO - PROCESS | 1673 | -------- Summary: end -------- 15:36:29 INFO - PROCESS | 1673 | 15:36:30 INFO - TEST-INFO | 1673: exit 0 15:36:30 INFO - TEST-OK | kraken | took 60170ms 15:36:30 INFO - SUITE-END | took 1174s 15:36:30 INFO - Completed test suite (00:19:34) 15:36:30 INFO - javascript benchmark 15:36:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7539.931266543713, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3520.2783429396973, 3523.92952140957, 3545.0, 3576.81964951566, 3579.874704385347, 5891.410858914106, 5932.911006334898, 5938.821835344936, 5989.850253743648, 6039.18471006414, 5992.28092628884, 6005.969970150154, 6053.303870054945, 6072.453479186872, 6100.145979562853, 5337.2794672719265, 5351.438098999617, 5434.701091439969, 5493.945060549383, 5534.557235421156, 5219.42586315505, 5366.0, 5471.7264136793, 5477.616566840323, 5496.5053145216925, 5278.10272253714, 5334.0, 5379.300690910176, 5414.0, 5422.15956526735, 5271.551918086967, 5274.314339135926, 5323.414424413311, 5327.840164795062, 5348.491893270133, 5253.921191182136, 5264.15773476242, 5286.735663216824, 5289.86775330616, 5313.519644632733, 5789.218455508494, 5800.361960184377, 5857.85355366115, 5865.413458654143, 5918.733656985446, 6228.657423841687, 6234.563580549363, 6251.937480625181, 6298.307186209513, 6334.0, 6339.619622822645, 6344.777932772377, 6352.904706429407, 6358.078078678612, 6363.649999250039, 6087.873743357493, 6096.634201947809, 6116.29662588808, 6124.142620033199, 6127.5404344674325, 6210.472109870621, 6267.341929097379, 6376.649284289409, 6397.584157029778, 6412.973924172204, 6286.9056964145575, 6292.307846136921, 6297.307296197415, 6313.337099604475, 6317.652529110851, 6350.65071421067, 6358.33237510064, 6370.713317900706, 6371.585846919936, 6375.171227740364, 6200.472959798378, 6215.254169499689, 6255.311915689271, 6256.374362563707, 6261.904166770797, 5355.973220133875, 6061.363556826558, 6264.091706702594, 6286.057091436322, 6297.244330680348, 4248.468941382327, 5092.032513822436, 5107.61692873036, 5107.693538387709, 5136.049830781318, 4431.268840641323, 4498.235299999008, 4544.727316361029, 4554.0891821635805, 4596.310553416947, 4843.75781210938, 6141.508679305645, 6146.108814221912, 6195.16365290689, 6287.7738840926495, 6362.459190968727, 6369.20384951881, 6380.202474690664, 6389.073584330246, 6397.6801159941815, 5379.515843574097, 5867.530597552186, 6047.0, 6076.726547305293, 6082.726277317532, 5683.744231509592, 5689.573282003867, 5697.116946873241, 5737.053386191315, 5752.36723960364, 6587.47300215972, 6619.3711597397205, 6620.304867988888, 6624.072629831828, 6672.566283191577, 6217.626942383471, 6226.128342032029, 6258.0, 6333.65164915934, 6399.200099987502, 6502.057201705821, 6604.867902641824, 6625.536212465274, 6633.2040155181685, 6652.068710380648, 6280.277768056732, 6297.149884765498, 6312.084747711464, 6318.178636777373, 6318.368163183645, 6183.783567575113, 6232.626042437469, 6353.5870168438905, 6369.426751592379, 6375.075614035846, 5175.197844333984, 6209.782657606962, 6235.750569977332, 6261.812145635638, 6294.527910406646, 5197.7141257231215, 6253.093301471352, 6276.215473065867, 6289.245290565162, 6289.937100628935, 4828.686135401188, 5963.0, 6131.570790044654, 6223.875522489434, 6286.182796236643, 4479.328100784908, 6209.751609935552, 6220.626762394089, 6234.220722409699, 6286.497080233662, 6252.749890004531, 6263.686815659108, 6304.18045654062, 6311.936880631135, 6318.08387783778], "value": 5835.464225277918, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [479.2475812973632, 482.36568911880863, 501.3157040639528, 557.3868744381181, 578.9160571717103, 39548.40677389838, 42840.143197136116, 42893.14213715728, 42970.570294297046, 43151.56848431515, 43500.12999740011, 43936.780316098455, 44279.11441771163, 44313.335299970444, 44570.88572785682, 9004.954975225115, 9223.308251881102, 9629.662961796355, 9863.95068024659, 9882.9505852471, 10241.795164096713, 11913.463894124741, 12227.0, 12287.508499660047, 12400.751984960296, 11933.16467847251, 12018.0, 12071.698207544794, 12132.514699412013, 12173.087018473605, 9808.852867206997, 11889.0, 11894.881051189464, 11946.104042196826, 11954.641360759191, 11888.989435897958, 11992.880071199264, 12067.577634782829, 12070.939645301805, 12084.818727719092, 42504.0, 42700.57299427028, 42811.92970175739, 42865.0, 42870.57129428697, 3515.402381595135, 4032.980820935647, 4059.411385349137, 4212.283911735012, 4335.154644844257, 30058.54912176319, 30190.24524386886, 30347.69652303471, 30419.543706844415, 32521.186970325696, 3318.8506517206783, 3496.35317466267, 3512.3502152101687, 3535.6287589803214, 3558.181618227796, 418384.0, 419893.9005304954, 421465.0, 423736.0, 424378.0, 64395.03407448892, 64650.676746615965, 64809.703805923615, 64821.351786482475, 65085.349146508874, 68296.6585167071, 68536.65731671409, 68630.0, 68668.62662746717, 68720.3127968714, 17717.379891703727, 17988.100594970198, 18010.729839052423, 18026.63946721058, 18043.278268869097, 12570.245785252731, 12918.935405322913, 13484.460621575223, 13552.0, 13717.725645487035, 17799.288028478717, 17883.37408190707, 17907.835990660566, 17973.370932017577, 17980.460586182435, 6314.778982735674, 7023.403010744144, 7074.575525468385, 7086.787396378117, 7156.642167891585, 894.1594900793234, 899.9505027223565, 906.9047749986156, 931.4783721116197, 936.6393938333654, 30891.38217235643, 31027.844860775553, 31047.758089676612, 31194.844025779727, 31440.37119257602, 919.2416256588341, 1033.9017793309624, 1068.636663534402, 1114.9575147237358, 1238.504598160743, 11639.53441862316, 14313.0, 17142.142892855307, 17647.823521764876, 17648.382306619205, 608715.0, 612790.0, 619966.0, 621365.0, 621722.0, 60016.69991650014, 63505.36494635087, 63621.68189159025, 63800.68099659472, 63805.0, 1454.4473100221849, 1472.7349077165998, 1475.8081449411509, 1511.9017263877813, 1513.583764464759, 27012.864935675196, 32804.85183018583, 32820.01539953805, 32892.50661240036, 33321.83339083289, 39811.0, 52890.206646899554, 53785.731071344395, 53808.0, 53985.0, 506564.0, 511676.0, 512290.0, 513255.433722829, 513816.0, 39992.0, 40448.79775601103, 41579.16841663211, 41858.0, 41896.79051604722, 40526.39210411787, 48168.5183148164, 48644.756776215894, 48761.02477950492, 49481.752591236815, 30201.395972080874, 36018.37917293676, 36196.190190490895, 38827.61172388353, 39653.00867478336, 36579.817100914326, 38531.42202867015, 39581.80209098936, 40051.79974100111, 40374.990625234605, 35756.64243357533, 36677.449838251916, 38904.0, 39264.2515023957, 39322.01694957649, 32853.83573082119, 34976.475352870235, 35711.64288357083, 35728.82135589409, 35949.28101438009, 25783.226503204933, 26076.73923260743, 26126.477470450864, 26672.733272667025, 26799.06203282876], "value": 21014.423036456457, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [355.2770112820411, 400.5133762478588, 408.50978825409516, 408.56895974746647, 424.9164630193007, 20202.19191232351, 21540.0, 21551.353459396203, 21963.341099766993, 22110.33668989929, 21821.67267490985, 22112.115515379406, 22351.441213969658, 22415.0, 22458.98934547945, 5710.942890571093, 5990.071538911454, 6013.428724271209, 6024.819255422322, 6029.577929544934, 5727.799527016556, 5813.622114562561, 5843.415658434165, 5895.145203945425, 5959.791407300745, 5758.078707406816, 5845.7953971611005, 5855.970720146393, 5856.063029915214, 5870.0, 5648.378678345379, 5847.590668653196, 5882.4705776480105, 5905.0, 5922.615030023035, 5232.372115346145, 5234.816781412632, 5238.423773384925, 5254.842354729364, 5256.080185967478, 24492.0, 25646.48707025849, 26037.0, 26297.3425664358, 26473.47053058947, 2655.8937642494275, 2693.946121077587, 2716.212298433452, 2718.972810271892, 2752.1055656911585, 10302.02130797573, 10341.586336546528, 10406.531706073245, 10739.570417183304, 10743.301685390425, 1826.9451916442529, 1877.6150889067708, 1887.5186827359073, 1907.1036612791966, 1930.1314408516084, 137384.0, 139869.3006534961, 141433.29283353518, 142739.0, 143310.0, 12083.516659333704, 12162.939185304016, 12250.75498490043, 12288.0, 12306.138570300172, 16178.75731864023, 16192.433264835674, 16234.67530649404, 16249.212586615462, 16256.918715406584, 8688.913110868936, 8808.383413161146, 8853.424527405698, 8895.911040889509, 8949.642014319485, 7014.614196219257, 7292.817679558054, 8448.831023379498, 8597.441166324168, 8745.737627871174, 4852.660313778072, 4948.950510494921, 4964.032013757283, 5020.698758074527, 5027.4721154279005, 1698.4649835302087, 1748.370586588827, 2058.3310424111924, 2067.8345732341377, 2070.1615845582564, 1024.472396715692, 1031.1439409063655, 1049.8372752223418, 1063.292910214714, 1083.566573370658, 21827.908604569708, 22063.0, 22153.00311485987, 22228.777712222993, 22355.0, 805.3932404852357, 1052.3843551522446, 1052.8736551613856, 1094.179365475893, 1102.3385968419045, 10858.239923205458, 11428.599999000162, 11477.54089836414, 11500.53997840077, 11501.539938402537, 750806.0, 765468.0, 780867.0, 786205.0, 797497.0, 161456.0, 166355.0, 166947.16526417533, 167289.16355418146, 169486.15256923882, 1013.2046343620223, 1026.071405378134, 1049.197364016528, 1083.6748975307376, 1091.7161538000178, 18933.431997040112, 19657.901710491355, 19693.60612787765, 19719.0, 19756.209751609775, 53179.734101329246, 57025.85948281094, 63680.68159659172, 63794.04308935277, 65557.67221163864, 69907.65046174737, 77015.07462313487, 94912.10175796584, 95238.0, 95630.52184739032, 6562.5406221565945, 7347.485676002629, 7660.5403675779635, 8019.6391162398995, 8654.4807311559, 11654.359010254517, 13159.736805264032, 13164.670883227613, 13209.735805284034, 13517.662058448224, 8233.50598964064, 11291.717707057389, 11430.142739294752, 11751.236169648946, 11894.881051189723, 11329.433528323716, 11450.82823757661, 11581.88418115808, 11907.702307442383, 12035.097367697634, 10127.848082278919, 11708.590199342982, 11833.704157396134, 11877.287362758263, 12051.397430128283, 7105.8630619100695, 8404.579771011302, 8713.782155446164, 8783.472991620523, 8851.203391694778, 10856.945715271373, 10924.945375273073, 11514.136439767219, 11622.244554103956, 11952.940235298767], "value": 10136.238378312222, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1401.6215621782112, 1462.839087700353, 1469.772185311277, 1472.9410823567057, 1476.4684713503143, 7692.9230707692905, 7951.681932722684, 8252.79368015799, 8671.39300248982, 8714.651413943435, 8114.756557303291, 8469.830603387929, 8501.702440414587, 8552.70065547706, 8566.05773364931, 2371.419002344424, 2382.857028578282, 2411.360989337827, 2417.7944874685672, 2423.53952748978, 2053.2095143369834, 2414.082648593532, 2420.298113547069, 2432.416220107179, 2451.7915977141965, 2358.13927916311, 2393.3179043972473, 2408.6989126359204, 2425.7695518925675, 2426.454047839231, 2011.8390528757664, 2375.643653451979, 2378.0725517048363, 2402.0872068613817, 2406.0135344508753, 1904.2858927902037, 2047.3550831488033, 2393.8324317297797, 2424.066734307287, 2427.963580546293, 8516.574171291411, 8575.1853573911, 8580.570971451403, 8583.613737381771, 8594.699185528476, 5116.462771408986, 5165.251038599382, 5305.965336759334, 5335.51980321769, 5347.491988261109, 3440.535527703753, 3527.9647203528148, 3644.2893635741043, 3667.8899633010788, 3670.3393389189937, 1216.951321947121, 1247.7566874459485, 1290.6257185416218, 1299.9610011699665, 1303.7001489657432, 11050.447477626087, 11054.502547385306, 11096.833547496793, 11100.945410186101, 11102.111831053497, 4636.198489339351, 4720.929186062212, 4773.474917759044, 4846.442659094249, 4850.878728031827, 9523.952380238194, 9556.092171243721, 9617.2306215504, 9681.612735490502, 9691.660791872251, 5391.110466773017, 5399.946000539944, 5400.7569659364635, 5409.215663728737, 5414.675119492843, 4429.955700443019, 4541.931871021938, 4883.926741098886, 4913.11563918491, 4973.52751488608, 3972.6424621784176, 3997.920041599152, 4070.3283958146576, 4082.9591704083173, 4121.175764847043, 1309.0640192262579, 1309.2733532889338, 1312.8949684025256, 1318.5714642741148, 1322.444573279225, 806.1334065879087, 864.2394692670409, 907.804821963281, 909.486140330711, 928.2527565309855, 5039.269305950616, 5670.234518340054, 5788.247527821356, 5812.186293918855, 6133.6319820810895, 827.0034608296987, 914.2685851318919, 980.352967041749, 1002.9648962286285, 1026.4046852825343, 3702.66675999156, 3741.3452645787424, 3875.0893540018074, 3982.7012974026484, 3994.6404823566018, 8578.18507241811, 9905.0, 9978.800423991624, 10170.694879153638, 10287.53706083213, 10142.442165680959, 10191.7452063699, 10198.98010198989, 10227.539760710786, 10267.486625668687, 7583.734569290048, 7972.3622110231045, 7999.440039197317, 8097.109317975018, 8103.837923241502, 5274.604404669742, 5781.190633311256, 5945.762169513344, 5966.194563734014, 6002.039673652369, 8134.87797683047, 9051.0, 9056.637734490547, 9136.680216192668, 9346.906530934604, 9322.95338523303, 9339.719808405758, 9447.905520944703, 9456.621735130793, 9487.857682134636, 6736.360045795642, 6927.89608155868, 7071.540349877241, 7156.355927966508, 7175.2465991070185, 7965.203479651988, 8175.223353781381, 8187.918120818954, 8195.71315003972, 8206.138355472829, 5946.316173639913, 6583.572067815577, 6615.437687796399, 6867.690953906987, 7138.85722285541, 7158.248383919614, 7198.6400679964745, 7208.855822883618, 7211.459140564374, 7265.0405528987885, 6119.724612392364, 6234.314225435289, 6262.46769024638, 6317.652529110943, 6374.521910856612, 5035.647504674637, 5059.139946209079, 5085.821996230262, 5089.821856235162, 5120.206368012964, 4938.726160919043, 5059.772310246121, 5131.640785145004, 5152.866369398726, 5195.532402083831], "value": 4497.960295797331, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [317.2797749109521, 385.47190349221563, 386.1832224844455, 389.1652405590011, 391.874600127959, 4687.976560117195, 4774.331593576893, 4829.420469543656, 5589.217509548666, 5659.717014149297, 4825.383496528659, 4916.410030796309, 5062.240663900417, 5243.767714587069, 5610.877824435119, 3580.53453051103, 3850.7111966602615, 4045.332520134189, 4394.670399720026, 4404.0, 4011.5386730525925, 4324.97837510812, 4421.0, 4432.490263619677, 4436.489803672586, 3652.9452058219017, 4404.581564751359, 4405.929213269774, 4408.889777755566, 4465.0, 3435.278591495789, 3815.79802362255, 4133.222714232878, 4241.1093670329265, 4446.888827779299, 3150.8499397719665, 3279.884839154675, 3335.8498867550775, 3533.840977156034, 3640.2173532690335, 5402.621816472849, 5431.728413579344, 5433.402325744165, 5450.5367043801325, 5458.645188062763, 3969.821358038895, 4388.49532303786, 4712.269598212282, 4814.350062741555, 4858.927116093226, 4901.338319326881, 5361.839144825662, 5621.578381621354, 5925.0, 5986.371430999726, 3377.7804442711144, 4020.979895100535, 4026.53694825093, 4043.696722745806, 4082.755034697928, 5406.0, 5684.914726279109, 6029.336772954972, 6264.906026409608, 6266.934621114421, 3905.941410878839, 4094.0788322627172, 4097.651699605507, 4112.943528235882, 4119.505659320901, 3661.469086982426, 3855.0169706724605, 4178.895527611773, 4195.517515485758, 4196.61511701144, 3607.0, 4065.0, 4327.723321620129, 4863.416390033219, 4898.730569818623, 4932.558383600141, 5420.349558053059, 5694.857628559319, 5917.881642367214, 5927.140564618193, 1193.826895100223, 1199.9760004799855, 1240.4046057892263, 1335.5525898824003, 1343.5767733164023, 845.7843249971341, 852.7697521669238, 855.9728326008815, 859.4113032572708, 903.8508646073324, 4544.2501987171745, 5097.929434818656, 5379.838604841861, 5385.919211211835, 5481.835544933659, 4612.815487380467, 5564.4713752193475, 5885.911711324334, 5901.436119428355, 5908.881822363614, 4832.202686556749, 5288.2596436498925, 5347.144456886879, 5355.7054362009685, 5696.0, 2496.0530785986957, 2610.699769526491, 3057.755379569629, 3077.9846100769355, 3096.829674367886, 6123.4488895999575, 6133.362654058708, 6152.661603611755, 6157.353477884847, 6171.753129874754, 4901.877453063702, 5670.319561652545, 5749.8562535936935, 6015.864368149331, 6085.817425477243, 4966.30010642074, 5027.924581131286, 5771.989901767174, 6032.849178770566, 6221.160143380676, 4918.778654960463, 5388.410418926348, 5546.060587888425, 5555.805546805843, 6052.727627256872, 5011.298418221379, 5144.806982309406, 5444.700541470257, 5497.87293604802, 5545.670818256262, 5290.756672182111, 6298.811035668938, 6376.8984812149165, 6411.871762564816, 6418.229812422539, 3390.983045084759, 3428.7942723435676, 3788.2802267569073, 4058.9188216236103, 4113.526944401432, 5125.0, 6000.789972350947, 6020.8795824084145, 6072.295899501212, 6081.756729730761, 5945.3757355477055, 5957.851053723692, 5981.521478281814, 5988.341282459013, 6009.609375390585, 5585.413531579288, 5674.205611214516, 5901.970490147522, 5905.4685078343155, 5951.642901425928, 4374.58441448062, 4873.001034787931, 5473.343198816167, 5515.979543784413, 5878.2358293421585, 5009.699418034929, 5919.260092488439, 5938.6436813791315, 5951.036158067741, 5971.671558064349, 5581.5255703265675, 5619.80330688424, 5765.798197063083, 5814.767409303749, 5830.82507524775], "value": 4203.1741586966145, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [640.2828831708484, 700.3136925812706, 746.6266866566716, 762.272030211148, 808.7695006923026, 14747.705045899076, 15371.231438428089, 15373.154476503787, 15431.768523472143, 17184.742228866577, 16177.433789817329, 16757.91621041896, 17254.82745172548, 17448.040357780315, 17467.65064698705, 874.3617159473604, 907.5552979040256, 908.0783005249676, 908.8954770201412, 913.6254135804321, 696.3176087434292, 893.4371346051978, 898.074982767747, 898.5237823953315, 898.8471959766855, 763.7975936376864, 917.614601867214, 946.9668561600346, 958.8753462049924, 963.1283688262134, 942.3356533643764, 948.7722946492827, 951.0822056715268, 952.8761261036055, 954.2080073538946, 687.1753895325579, 705.6577559883452, 711.8078118907918, 713.6824113269597, 714.4820005496026, 15033.699326013528, 15475.226238688021, 15684.921575392164, 15800.0, 15876.603084922854, 14784.260786960716, 15050.32273547693, 15127.848721512864, 15173.772393414107, 15210.16344101074, 11428.71428214293, 11492.540298388054, 11729.354885481294, 11888.702782430422, 11951.0, 3336.382769187708, 3869.496965394509, 3922.215556888605, 4073.185362927397, 4074.1444296697728, 108610.37084443739, 109025.0, 109386.0, 109425.81148377068, 109844.4507777464, 11524.308541487537, 11622.128340374506, 11638.825417618742, 11863.050955923678, 12047.39763011846, 24504.87747561251, 24611.753882461304, 24925.5014899701, 24994.375140621265, 25100.49799004046, 8050.959745201237, 8065.1934806518875, 8070.596470176585, 8076.636551355203, 8211.137830527829, 4241.618254357061, 4338.783060846945, 4341.891452713644, 4450.910981780346, 4682.882927926761, 3592.856285748541, 3695.667389934865, 3697.90755231116, 3700.278445703062, 3703.5926048134684, 679.4564348521262, 681.5910453727723, 690.8169335126196, 699.8460338725574, 714.3320994869813, 688.9931100689029, 722.797616667334, 725.4414101142091, 741.4105785900223, 759.51770625652, 9384.8592271116, 9818.576306435669, 10630.521626526825, 10918.23572349928, 11130.944345278383, 601.4406601860312, 669.718718138383, 730.481358235648, 740.7777666699968, 755.2598453515585, 4592.747398893093, 5050.217216331474, 5061.038402703474, 5131.923021154686, 5133.178691409356, 73999.0, 76405.61797191089, 108115.45942270238, 108856.0, 111167.0, 91079.81220657245, 93699.53150234206, 94620.0, 95192.57211141838, 97160.51419742857, 74755.62622186854, 76715.23284767193, 82263.0, 84360.0, 84862.15137848665, 9783.804323913624, 9909.702708918743, 9972.789724763248, 10042.196624270187, 10073.294869359075, 48207.0, 48436.51563484461, 48667.026659465904, 49220.261696073874, 49295.0, 58184.0, 71620.92568611371, 72012.55974880369, 73503.52992940218, 74330.62834685792, 13536.729265414833, 13880.375383107583, 14141.858581414053, 14372.928135359256, 14461.421543138156, 14922.0, 15351.923240383727, 15565.92217038953, 15584.766228506809, 15657.138857362954, 13732.0, 13940.372683229076, 14143.292835357985, 14188.858111419167, 14738.26308684583, 13446.394912229169, 13877.925745563432, 13973.650658733206, 14186.716265674835, 14226.288685565472, 13397.263150526815, 13970.441182352999, 13981.510647127301, 14006.154581578017, 14148.938829587616, 11075.0, 11404.372759498307, 11465.713357165803, 11673.941630292135, 11776.823347649963, 10261.384316941007, 11228.66314010581, 11295.0, 11520.329552214715, 11602.593909213138], "value": 7817.967562913226, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1558.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 124.0, 119.0, 120.0, 122.0, 120.0, 129.0, 180.0, 162.0, 119.0], "value": 131.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 116.0, 120.0, 183.0, 144.0, 114.0, 113.0, 144.0, 160.0, 112.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [195.0, 204.0, 195.0, 202.0, 227.0, 220.0, 223.0, 273.0, 204.0, 198.0], "value": 214.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 83.0, 87.0, 75.0, 86.0, 86.0, 88.0, 83.0, 76.0, 58.0], "value": 82.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 72.0, 83.0, 72.0, 70.0, 72.0, 82.0, 69.0, 71.0, 81.0], "value": 75.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 80.0, 138.0, 119.0, 89.0, 103.0, 90.0, 85.0, 90.0, 129.0], "value": 101.2, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 140.0, 99.0, 154.0, 175.0, 100.0, 99.0, 99.0, 100.0, 101.0], "value": 122.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 95.0, 92.0, 91.0, 92.0, 91.0, 92.0, 92.0, 92.0, 111.0], "value": 94.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 59.0, 59.0, 73.0, 81.0, 53.0, 58.0, 84.0, 95.0, 95.0], "value": 71.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 77.0, 71.0, 87.0, 58.0, 66.0, 60.0, 67.0, 71.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 86.0, 86.0, 82.0, 81.0, 85.0, 84.0, 85.0, 78.0, 74.0], "value": 82.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 132.0, 132.0, 139.0, 134.0, 133.0, 138.0, 140.0, 185.0, 151.0], "value": 142.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 167.0, 165.0, 166.0, 167.0, 163.0, 167.0, 173.0, 169.0, 168.0], "value": 167.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 64.0, 64.0, 63.0, 65.0, 66.0, 69.0, 62.0, 64.0, 73.0], "value": 65.6, "unit": "score"}]}]} 15:36:30 INFO - Return code: 0 15:36:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:36:30 ERROR - # TBPL SUCCESS # 15:36:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:36:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:36:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:36:30 INFO - 2017-03-22 15:36:30.710 screenresolution[1678:10422] starting screenresolution argv=screenresolution get 15:36:30 INFO - 2017-03-22 15:36:30.712 screenresolution[1678:10422] Display 0: 1600x1200x32@60 15:36:30 INFO - 2017-03-22 15:36:30.718 screenresolution[1679:10425] starting screenresolution argv=screenresolution list 15:36:30 INFO - Available Modes on Display 0 15:36:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:36:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:36:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:36:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:36:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:36:30 INFO - Intel Iris: 15:36:30 INFO - Chipset Model: Intel Iris 15:36:30 INFO - Type: GPU 15:36:30 INFO - Bus: Built-In 15:36:30 INFO - VRAM (Dynamic, Max): 1536 MB 15:36:30 INFO - Vendor: Intel (0x8086) 15:36:30 INFO - Device ID: 0x0a2e 15:36:30 INFO - Revision ID: 0x0009 15:36:30 INFO - Displays: 15:36:30 INFO - Display: 15:36:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:36:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:36:30 INFO - Display Serial Number: 1600x1200 60 15:36:30 INFO - Main Display: Yes 15:36:30 INFO - Mirror: Off 15:36:30 INFO - Online: Yes 15:36:30 INFO - Rotation: Supported 15:36:30 INFO - Return code: 0 15:36:30 INFO - Running post-action listener: _resource_record_post_action 15:36:30 INFO - [mozharness: 2017-03-22 22:36:30.955363Z] Finished run-tests step (success) 15:36:30 INFO - Running post-run listener: _resource_record_post_run 15:36:31 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 40368128; Write bytes: 424662528; Read time: 387; Write time: 1605 15:36:31 INFO - TinderboxPrint: CPU usage
54.3% 15:36:31 INFO - TinderboxPrint: I/O read bytes / time
40,368,128 / 387 15:36:31 INFO - TinderboxPrint: I/O write bytes / time
424,662,528 / 1,605 15:36:31 INFO - TinderboxPrint: CPU idle
2,178.1 (45.7%) 15:36:31 INFO - TinderboxPrint: CPU system
962.7 (20.2%) 15:36:31 INFO - TinderboxPrint: CPU user
1,620.9 (34.0%) 15:36:31 INFO - TinderboxPrint: Swap in / out
162,549,760 / 0 15:36:31 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109992448; Write bytes: 102338560; Read time: 10032; Write time: 183 15:36:31 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 39438336; Write bytes: 301839872; Read time: 374; Write time: 1365 15:36:31 INFO - Running post-run listener: _upload_blobber_files 15:36:31 INFO - Blob upload gear active. 15:36:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:36:31 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 15:36:31 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'] 15:36:31 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 15:36:31 INFO - (blobuploader) - INFO - Open directory for files ... 15:36:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:36:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:36:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:33 INFO - (blobuploader) - INFO - Done attempting. 15:36:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:36:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:36:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:34 INFO - (blobuploader) - INFO - Done attempting. 15:36:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:36:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:36:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:35 INFO - (blobuploader) - INFO - Done attempting. 15:36:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:36:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:36:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:36:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:36:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:36:37 INFO - (blobuploader) - INFO - Done attempting. 15:36:37 INFO - (blobuploader) - INFO - Iteration through files over. 15:36:37 INFO - Return code: 0 15:36:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:36:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:36:37 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a353d372d1cda9aac572b224ae7369d15d296ee77137f941ed60f123bf26267ba1498f3067f1497dee33dd476c93b227e38ae8ef7a88a9802cccecc9b2db1fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a8814b97c0ad62264ac2371cfdbf363f7d394018e67382b260a63e78e5833046e84de6f1639bc4d9ff61f68177744acf2f4b5946237fb7a956994079d5a46d0c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c648cbb68e744adb17fd24421acd6276bfb29ded6e1155e068ef9dce3eb6f76b7e6f67aebf8b3ba63a7712165b33bfb1b059d816d831af6820774ccae3a50b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea6105435c565542660f16813e87f4ed87d55acaaf70d6204bb1f8af15299e4301a06074fba738abc3d1ff69dc7c80da11cc32c6b65d280e98c1e667fef2c913"} 15:36:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:36:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:36:37 INFO - Contents: 15:36:37 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1a353d372d1cda9aac572b224ae7369d15d296ee77137f941ed60f123bf26267ba1498f3067f1497dee33dd476c93b227e38ae8ef7a88a9802cccecc9b2db1fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a8814b97c0ad62264ac2371cfdbf363f7d394018e67382b260a63e78e5833046e84de6f1639bc4d9ff61f68177744acf2f4b5946237fb7a956994079d5a46d0c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c648cbb68e744adb17fd24421acd6276bfb29ded6e1155e068ef9dce3eb6f76b7e6f67aebf8b3ba63a7712165b33bfb1b059d816d831af6820774ccae3a50b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea6105435c565542660f16813e87f4ed87d55acaaf70d6204bb1f8af15299e4301a06074fba738abc3d1ff69dc7c80da11cc32c6b65d280e98c1e667fef2c913"} 15:36:37 INFO - Running post-run listener: copy_logs_to_upload_dir 15:36:37 INFO - Copying logs to upload dir... 15:36:37 INFO - mkdir: /builds/slave/test/build/upload/logs 15:36:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1256.301993 ========= master_lag: -0.16 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 56 secs) (at 2017-03-22 15:36:37.792677) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 15:36:37.796492) ========= 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.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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/1a353d372d1cda9aac572b224ae7369d15d296ee77137f941ed60f123bf26267ba1498f3067f1497dee33dd476c93b227e38ae8ef7a88a9802cccecc9b2db1fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a8814b97c0ad62264ac2371cfdbf363f7d394018e67382b260a63e78e5833046e84de6f1639bc4d9ff61f68177744acf2f4b5946237fb7a956994079d5a46d0c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c648cbb68e744adb17fd24421acd6276bfb29ded6e1155e068ef9dce3eb6f76b7e6f67aebf8b3ba63a7712165b33bfb1b059d816d831af6820774ccae3a50b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea6105435c565542660f16813e87f4ed87d55acaaf70d6204bb1f8af15299e4301a06074fba738abc3d1ff69dc7c80da11cc32c6b65d280e98c1e667fef2c913"} build_url:https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011108 build_url: 'https://queue.taskcluster.net/v1/task/UDkR3vurS4WgHucZeHdK3Q/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/1a353d372d1cda9aac572b224ae7369d15d296ee77137f941ed60f123bf26267ba1498f3067f1497dee33dd476c93b227e38ae8ef7a88a9802cccecc9b2db1fb", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a8814b97c0ad62264ac2371cfdbf363f7d394018e67382b260a63e78e5833046e84de6f1639bc4d9ff61f68177744acf2f4b5946237fb7a956994079d5a46d0c", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/c648cbb68e744adb17fd24421acd6276bfb29ded6e1155e068ef9dce3eb6f76b7e6f67aebf8b3ba63a7712165b33bfb1b059d816d831af6820774ccae3a50b7d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/ea6105435c565542660f16813e87f4ed87d55acaaf70d6204bb1f8af15299e4301a06074fba738abc3d1ff69dc7c80da11cc32c6b65d280e98c1e667fef2c913"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-22 15:36:37.834850) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:36:37.835652) ========= 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.50vpQjghNn/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.EH9Q8QJzMG/Listeners TMPDIR=/var/folders/ls/6dq3sb3j12l2f5_326_ys_8c00000w/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.005108 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-22 15:36:37.888850) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 15:36:37.889608) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-22 15:36:37.890390) ========= ========= Total master_lag: -0.02 =========