builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0317 starttime: 1494059840.64 results: success (0) buildid: 20170506004004 builduid: 7925e2385d0942f0aa906da61ef44c37 revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:20.645136) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:20.646048) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:20.646320) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:20.673792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:20.674161) ========= 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.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-05-06 01:37:20-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 19.0M=0.001s 2017-05-06 01:37:21 (19.0 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.628399 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:21.330237) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:21.330534) ========= 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.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.026813 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:21.405979) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-06 01:37:21.406313) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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-05-06 01:37:21,514 truncating revision to first 12 chars 2017-05-06 01:37:21,514 Setting DEBUG logging. 2017-05-06 01:37:21,514 attempt 1/10 2017-05-06 01:37:21,514 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/94b7e538af7d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-06 01:37:22,253 unpacking tar archive at: mozilla-aurora-94b7e538af7d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.013327 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-06 01:37:22.444111) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:22.444423) ========= script_repo_revision: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:22.444809) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:22.445072) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-06 01:37:22.466196) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-06 01:37:22.466571) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:37:22 INFO - MultiFileLogger online at 20170506 01:37:22 in /builds/slave/test 01:37:22 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 01:37:22 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 01:37:22 INFO - {'append_to_log': False, 01:37:22 INFO - 'base_work_dir': '/builds/slave/test', 01:37:22 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 01:37:22 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 01:37:22 INFO - 'branch': 'Mozilla-Aurora', 01:37:22 INFO - 'buildbot_json_path': 'buildprops.json', 01:37:22 INFO - 'config_files': ('talos/mac_config.py',), 01:37:22 INFO - 'default_actions': ('clobber', 01:37:22 INFO - 'read-buildbot-config', 01:37:22 INFO - 'download-and-extract', 01:37:22 INFO - 'populate-webroot', 01:37:22 INFO - 'create-virtualenv', 01:37:22 INFO - 'install', 01:37:22 INFO - 'run-tests'), 01:37:22 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 01:37:22 INFO - 'download_minidump_stackwalk': True, 01:37:22 INFO - 'download_symbols': 'ondemand', 01:37:22 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 01:37:22 INFO - 'tooltool.py': '/tools/tooltool.py', 01:37:22 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 01:37:22 INFO - '/tools/misc-python/virtualenv.py')}, 01:37:22 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 01:37:22 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 01:37:22 INFO - 'gecko_profile': False, 01:37:22 INFO - 'gecko_profile_interval': 0, 01:37:22 INFO - 'installer_path': 'installer.exe', 01:37:22 INFO - 'log_level': 'info', 01:37:22 INFO - 'log_name': 'talos', 01:37:22 INFO - 'log_to_console': True, 01:37:22 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 01:37:22 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 01:37:22 INFO - 'opt_config_files': (), 01:37:22 INFO - 'pip_index': False, 01:37:22 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:22 INFO - 'cmd': ('bash', 01:37:22 INFO - '-c', 01:37:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:22 INFO - 'enabled': True, 01:37:22 INFO - 'halt_on_failure': False, 01:37:22 INFO - 'name': 'check_screen_resolution'},), 01:37:22 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 01:37:22 INFO - 'cmd': ('bash', 01:37:22 INFO - '-c', 01:37:22 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 01:37:22 INFO - 'enabled': True, 01:37:22 INFO - 'halt_on_failure': False, 01:37:22 INFO - 'name': 'check_screen_resolution'},), 01:37:22 INFO - 'run_cmd_checks_enabled': True, 01:37:22 INFO - 'suite': 'dromaeojs', 01:37:22 INFO - 'system_bits': '32', 01:37:22 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 01:37:22 INFO - 'title': 't-yosemite-r7-0317', 01:37:22 INFO - 'tooltool_cache': '/builds/tooltool_cache', 01:37:22 INFO - 'use_talos_json': True, 01:37:22 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 01:37:22 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 01:37:22 INFO - 'work_dir': 'build'} 01:37:22 INFO - [mozharness: 2017-05-06 08:37:22.679321Z] Running clobber step. 01:37:22 INFO - Running pre-action listener: _resource_record_pre_action 01:37:22 INFO - Running main action method: clobber 01:37:22 INFO - rmtree: /builds/slave/test/build 01:37:22 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 01:37:25 INFO - Running post-action listener: _resource_record_post_action 01:37:25 INFO - [mozharness: 2017-05-06 08:37:25.172087Z] Finished clobber step (success) 01:37:25 INFO - [mozharness: 2017-05-06 08:37:25.172249Z] Running read-buildbot-config step. 01:37:25 INFO - Running pre-action listener: _resource_record_pre_action 01:37:25 INFO - Running main action method: read_buildbot_config 01:37:25 INFO - Using buildbot properties: 01:37:25 INFO - { 01:37:25 INFO - "project": "", 01:37:25 INFO - "product": "firefox", 01:37:25 INFO - "script_repo_revision": "production", 01:37:25 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 01:37:25 INFO - "repository": "", 01:37:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 01:37:25 INFO - "buildid": "20170506004004", 01:37:25 INFO - "stage_platform": "macosx64", 01:37:25 INFO - "basedir": "/builds/slave/test", 01:37:25 INFO - "nightly_build": "True", 01:37:25 INFO - "buildnumber": 0, 01:37:25 INFO - "slavename": "t-yosemite-r7-0317", 01:37:25 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 01:37:25 INFO - "platform": "yosemite_r7", 01:37:25 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 01:37:25 INFO - "branch": "mozilla-aurora", 01:37:25 INFO - "repo_path": "releases/mozilla-aurora", 01:37:25 INFO - "revision": "94b7e538af7dbb9a6567cad9125c2f5411e7fb80", 01:37:25 INFO - "pgo_build": "False", 01:37:25 INFO - "builduid": "7925e2385d0942f0aa906da61ef44c37", 01:37:25 INFO - "slavebuilddir": "test" 01:37:25 INFO - } 01:37:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg. 01:37:25 INFO - Running post-action listener: _resource_record_post_action 01:37:25 INFO - [mozharness: 2017-05-06 08:37:25.175694Z] Finished read-buildbot-config step (success) 01:37:25 INFO - [mozharness: 2017-05-06 08:37:25.175810Z] Running download-and-extract step. 01:37:25 INFO - Running pre-action listener: _resource_record_pre_action 01:37:25 INFO - Running main action method: download_and_extract 01:37:25 INFO - mkdir: /builds/slave/test/build/tests 01:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:37:25 INFO - trying https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json'}, attempt #1 01:37:26 INFO - Downloaded 1199 bytes. 01:37:26 INFO - Reading from file /builds/slave/test/build/firefox-54.0a2.en-US.mac.test_packages.json 01:37:26 INFO - Using the following test package requirements: 01:37:26 INFO - {u'common': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:37:26 INFO - u'cppunittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.cppunittest.tests.zip'], 01:37:26 INFO - u'gtest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.gtest.tests.zip'], 01:37:26 INFO - u'jittest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'jsshell-mac.zip'], 01:37:26 INFO - u'mochitest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.mochitest.tests.zip'], 01:37:26 INFO - u'mozbase': [u'firefox-54.0a2.en-US.mac.common.tests.zip'], 01:37:26 INFO - u'reftest': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.reftest.tests.zip'], 01:37:26 INFO - u'talos': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.talos.tests.zip'], 01:37:26 INFO - u'web-platform': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.web-platform.tests.zip'], 01:37:26 INFO - u'xpcshell': [u'firefox-54.0a2.en-US.mac.common.tests.zip', 01:37:26 INFO - u'firefox-54.0a2.en-US.mac.xpcshell.tests.zip']} 01:37:26 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip'] for test suite category: common 01:37:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:37:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:37:26 INFO - Fetch https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:37:28 INFO - Expected file size: 51792704 01:37:28 INFO - Obtained file size: 51792704 01:37:43 INFO - Downloading packages: [u'firefox-54.0a2.en-US.mac.common.tests.zip', u'firefox-54.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 01:37:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip 01:37:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip'}, attempt #1 01:37:43 INFO - Fetch https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.common.tests.zip into memory 01:37:45 INFO - Expected file size: 51792704 01:37:45 INFO - Obtained file size: 51792704 01:38:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip 01:38:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip'}, attempt #1 01:38:00 INFO - Fetch https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.talos.tests.zip into memory 01:38:01 INFO - Expected file size: 14404424 01:38:01 INFO - Obtained file size: 14404424 01:38: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')]} 01:38:01 INFO - trying https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:01 INFO - Downloading https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg 01:38:01 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg'}, attempt #1 01:38:03 INFO - Downloaded 74779136 bytes. 01:38:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:03 INFO - mkdir: /builds/slave/test/properties 01:38:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 01:38:03 INFO - Writing to file /builds/slave/test/properties/build_url 01:38:03 INFO - Contents: 01:38:03 INFO - build_url:https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg 01:38:04 INFO - Running post-action listener: _resource_record_post_action 01:38:04 INFO - Running post-action listener: set_extra_try_arguments 01:38:04 INFO - [mozharness: 2017-05-06 08:38:04.943131Z] Finished download-and-extract step (success) 01:38:04 INFO - [mozharness: 2017-05-06 08:38:04.943266Z] Running populate-webroot step. 01:38:04 INFO - Running pre-action listener: _resource_record_pre_action 01:38:04 INFO - Running main action method: populate_webroot 01:38:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 01:38:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 01:38:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 01:38:04 INFO - '--tppagecycles', 01:38:04 INFO - '7'], 01:38:04 INFO - 'tests': ['tsvgm']}}, 01:38:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 01:38:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 01:38:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 01:38:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 01:38:04 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:04 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 01:38:04 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['damp', 'tps']}, 01:38:04 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'tests': ['damp', 'tps']}, 01:38:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['dromaeo_dom']}, 01:38:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 01:38:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['basic_compositor_video']}, 01:38:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 01:38:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'other-e10s': {'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['a11yr', 01:38:04 INFO - 'ts_paint', 01:38:04 INFO - 'tpaint', 01:38:04 INFO - 'sessionrestore', 01:38:04 INFO - 'sessionrestore_no_auto_restore', 01:38:04 INFO - 'tabpaint']}, 01:38:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['tsvgx', 01:38:04 INFO - 'tsvgr_opacity', 01:38:04 INFO - 'tart', 01:38:04 INFO - 'tscrollx', 01:38:04 INFO - 'cart', 01:38:04 INFO - 'tsvg_static']}, 01:38:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 01:38:04 INFO - 'tsvgr_opacity', 01:38:04 INFO - 'tart', 01:38:04 INFO - 'tscrollx', 01:38:04 INFO - 'cart', 01:38:04 INFO - 'tsvg_static']}, 01:38:04 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'talos_options': ['--disable-e10s'], 01:38:04 INFO - 'tests': ['tp5o']}, 01:38:04 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 01:38:04 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'talos_options': ['--disable-e10s', 01:38:04 INFO - '--xperf_path', 01:38:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:04 INFO - 'tests': ['tp5n']}, 01:38:04 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 01:38:04 INFO - 'talos_options': ['--xperf_path', 01:38:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 01:38:04 INFO - 'tests': ['tp5n']}}, 01:38:04 INFO - 'talos.zip': {'path': '', 01:38:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 01:38:04 INFO - Running post-action listener: _resource_record_post_action 01:38:04 INFO - [mozharness: 2017-05-06 08:38:04.961728Z] Finished populate-webroot step (success) 01:38:04 INFO - [mozharness: 2017-05-06 08:38:04.961841Z] Running create-virtualenv step. 01:38:04 INFO - Running pre-action listener: _resource_record_pre_action 01:38:04 INFO - Running main action method: create_virtualenv 01:38:04 INFO - Creating virtualenv /builds/slave/test/build/venv 01:38: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 01:38:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 01:38:05 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 01:38:05 INFO - Using real prefix '/tools/python27' 01:38:05 INFO - New python executable in /builds/slave/test/build/venv/bin/python 01:38:05 INFO - Installing distribute.............................................................................................................................................................................................done. 01:38:08 INFO - Installing pip.................done. 01:38:08 INFO - Return code: 0 01:38:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 01:38:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 01:38:08 INFO - Reading from file tmpfile_stdout 01:38:08 INFO - Output received: 01:38: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) 01:38:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38: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 01:38: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 01:38:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:08 INFO - 'HOME': '/Users/cltbld', 01:38:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:08 INFO - 'LOGNAME': 'cltbld', 01:38:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:08 INFO - 'MOZ_NO_REMOTE': '1', 01:38:08 INFO - 'NO_EM_RESTART': '1', 01:38:08 INFO - 'PAGER': '/bin/cat', 01:38:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:08 INFO - 'PWD': '/builds/slave/test', 01:38:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:08 INFO - 'SHELL': '/bin/bash', 01:38:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:08 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:08 INFO - 'USER': 'cltbld', 01:38:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:08 INFO - 'XPC_FLAGS': '0x0', 01:38:08 INFO - 'XPC_SERVICE_NAME': '0', 01:38:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38: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 01:38:09 INFO - Cleaning up... 01:38:09 INFO - Return code: 0 01:38:09 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38: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 01:38: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 01:38:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:09 INFO - 'HOME': '/Users/cltbld', 01:38:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:09 INFO - 'LOGNAME': 'cltbld', 01:38:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:09 INFO - 'MOZ_NO_REMOTE': '1', 01:38:09 INFO - 'NO_EM_RESTART': '1', 01:38:09 INFO - 'PAGER': '/bin/cat', 01:38:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:09 INFO - 'PWD': '/builds/slave/test', 01:38:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:09 INFO - 'SHELL': '/bin/bash', 01:38:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:09 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:09 INFO - 'USER': 'cltbld', 01:38:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:09 INFO - 'XPC_FLAGS': '0x0', 01:38:09 INFO - 'XPC_SERVICE_NAME': '0', 01:38:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:09 INFO - Downloading/unpacking psutil>=3.1.1 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38: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 01:38:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 01:38:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:12 INFO - Installing collected packages: psutil 01:38:12 INFO - Running setup.py install for psutil 01:38:12 INFO - building 'psutil._psutil_osx' extension 01:38: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 01:38: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_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 01:38: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/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 01:38: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 01:38:13 INFO - building 'psutil._psutil_posix' extension 01:38: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 01:38:13 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 01:38:13 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 01:38:13 INFO - ^ 01:38:13 INFO - 1 warning generated. 01:38: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 01:38:13 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 01:38:13 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 01:38:13 INFO - Successfully installed psutil 01:38:13 INFO - Cleaning up... 01:38:13 INFO - Return code: 0 01:38:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 01:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 01:38:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 01:38:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:13 INFO - 'HOME': '/Users/cltbld', 01:38:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:13 INFO - 'LOGNAME': 'cltbld', 01:38:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:13 INFO - 'MOZ_NO_REMOTE': '1', 01:38:13 INFO - 'NO_EM_RESTART': '1', 01:38:13 INFO - 'PAGER': '/bin/cat', 01:38:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:13 INFO - 'PWD': '/builds/slave/test', 01:38:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:13 INFO - 'SHELL': '/bin/bash', 01:38:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:13 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:13 INFO - 'USER': 'cltbld', 01:38:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:13 INFO - 'XPC_FLAGS': '0x0', 01:38:13 INFO - 'XPC_SERVICE_NAME': '0', 01:38:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 01:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 01:38:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 01:38:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 01:38:15 INFO - Installing collected packages: mozsystemmonitor 01:38:15 INFO - Running setup.py install for mozsystemmonitor 01:38:15 INFO - Successfully installed mozsystemmonitor 01:38:15 INFO - Cleaning up... 01:38:15 INFO - Return code: 0 01:38:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 01:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:15 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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:15 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 01:38:15 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 01:38:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:15 INFO - 'HOME': '/Users/cltbld', 01:38:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:15 INFO - 'LOGNAME': 'cltbld', 01:38:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:15 INFO - 'MOZ_NO_REMOTE': '1', 01:38:15 INFO - 'NO_EM_RESTART': '1', 01:38:15 INFO - 'PAGER': '/bin/cat', 01:38:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:15 INFO - 'PWD': '/builds/slave/test', 01:38:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:15 INFO - 'SHELL': '/bin/bash', 01:38:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:15 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:15 INFO - 'USER': 'cltbld', 01:38:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:15 INFO - 'XPC_FLAGS': '0x0', 01:38:15 INFO - 'XPC_SERVICE_NAME': '0', 01:38:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:15 INFO - Downloading/unpacking jsonschema==2.5.1 01:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:15 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:15 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:15 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:15 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:16 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 01:38:16 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 01:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:17 INFO - Downloading functools32-3.2.3-2.tar.gz 01:38:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 01:38:17 INFO - warning: no files found matching '*.txt' 01:38:17 INFO - no previously-included directories found matching 'build' 01:38:17 INFO - no previously-included directories found matching 'dist' 01:38:17 INFO - no previously-included directories found matching '.git*' 01:38:17 INFO - Installing collected packages: jsonschema, functools32 01:38:17 INFO - Running setup.py install for functools32 01:38:17 INFO - warning: no files found matching '*.txt' 01:38:17 INFO - no previously-included directories found matching 'build' 01:38:17 INFO - no previously-included directories found matching 'dist' 01:38:17 INFO - no previously-included directories found matching '.git*' 01:38:17 INFO - Successfully installed jsonschema functools32 01:38:17 INFO - Cleaning up... 01:38:17 INFO - Return code: 0 01:38:17 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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', '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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 01:38: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 functools32==3.2.3-2 01:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:17 INFO - 'HOME': '/Users/cltbld', 01:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:17 INFO - 'LOGNAME': 'cltbld', 01:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:17 INFO - 'MOZ_NO_REMOTE': '1', 01:38:17 INFO - 'NO_EM_RESTART': '1', 01:38:17 INFO - 'PAGER': '/bin/cat', 01:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:17 INFO - 'PWD': '/builds/slave/test', 01:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:17 INFO - 'SHELL': '/bin/bash', 01:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:17 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:17 INFO - 'USER': 'cltbld', 01:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:17 INFO - 'XPC_FLAGS': '0x0', 01:38:17 INFO - 'XPC_SERVICE_NAME': '0', 01:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:17 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 01:38:17 INFO - Cleaning up... 01:38:17 INFO - Return code: 0 01:38:17 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 01:38: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')]} 01:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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')]} 01:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38: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', '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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 01:38: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 blobuploader==1.2.4 01:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:17 INFO - 'HOME': '/Users/cltbld', 01:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:17 INFO - 'LOGNAME': 'cltbld', 01:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:17 INFO - 'MOZ_NO_REMOTE': '1', 01:38:17 INFO - 'NO_EM_RESTART': '1', 01:38:17 INFO - 'PAGER': '/bin/cat', 01:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:17 INFO - 'PWD': '/builds/slave/test', 01:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:17 INFO - 'SHELL': '/bin/bash', 01:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:17 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:17 INFO - 'USER': 'cltbld', 01:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:17 INFO - 'XPC_FLAGS': '0x0', 01:38:17 INFO - 'XPC_SERVICE_NAME': '0', 01:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:18 INFO - Downloading/unpacking blobuploader==1.2.4 01:38:18 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 01:38:18 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 01:38:18 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 01:38:18 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 01:38:18 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 01:38:18 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 01:38:19 INFO - Downloading blobuploader-1.2.4.tar.gz 01:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 01:38:19 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 01:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:19 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:19 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:19 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:19 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 01:38:20 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 01:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 01:38:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 01:38:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 01:38:20 INFO - Downloading docopt-0.6.1.tar.gz 01:38:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 01:38:20 INFO - Installing collected packages: blobuploader, requests, docopt 01:38:20 INFO - Running setup.py install for blobuploader 01:38:20 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 01:38:20 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 01:38:20 INFO - Running setup.py install for requests 01:38:21 INFO - Running setup.py install for docopt 01:38:21 INFO - Successfully installed blobuploader requests docopt 01:38:21 INFO - Cleaning up... 01:38:21 INFO - Return code: 0 01:38:21 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:21 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:38:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:38:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:21 INFO - 'HOME': '/Users/cltbld', 01:38:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:21 INFO - 'LOGNAME': 'cltbld', 01:38:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:21 INFO - 'MOZ_NO_REMOTE': '1', 01:38:21 INFO - 'NO_EM_RESTART': '1', 01:38:21 INFO - 'PAGER': '/bin/cat', 01:38:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:21 INFO - 'PWD': '/builds/slave/test', 01:38:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:21 INFO - 'SHELL': '/bin/bash', 01:38:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:21 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:21 INFO - 'USER': 'cltbld', 01:38:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:21 INFO - 'XPC_FLAGS': '0x0', 01:38:21 INFO - 'XPC_SERVICE_NAME': '0', 01:38:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:38:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-v7fIM0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:38:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-YzZAVb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:38:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-99A4Nh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:38:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-nC6YaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:38:21 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:38:21 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uwS2jr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-SnCeZN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-GCwlR8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-uGXD1a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-3iP9pz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-3DDn2Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-NqF9sq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-9bGuIy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-6TxoC0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Dr3LWG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:22 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:22 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8wU2FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:23 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-5Y0kk4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:23 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-SnmJr4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:23 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 01:38:23 INFO - Running setup.py install for manifestparser 01:38:23 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 01:38:23 INFO - Running setup.py install for mozcrash 01:38:23 INFO - Running setup.py install for mozdebug 01:38:23 INFO - Running setup.py install for mozdevice 01:38:23 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 01:38:23 INFO - Installing dm script to /builds/slave/test/build/venv/bin 01:38:23 INFO - Running setup.py install for mozfile 01:38:24 INFO - Running setup.py install for mozhttpd 01:38:24 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Running setup.py install for mozinfo 01:38:24 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Running setup.py install for mozInstall 01:38:24 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Running setup.py install for mozleak 01:38:24 INFO - Running setup.py install for mozlog 01:38:24 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Running setup.py install for moznetwork 01:38:24 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 01:38:24 INFO - Running setup.py install for mozprocess 01:38:25 INFO - Running setup.py install for mozprofile 01:38:25 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 01:38:25 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 01:38:25 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 01:38:25 INFO - Running setup.py install for mozrunner 01:38:25 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 01:38:25 INFO - Running setup.py install for mozscreenshot 01:38:25 INFO - Running setup.py install for moztest 01:38:25 INFO - Running setup.py install for mozversion 01:38:25 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 01:38:25 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 01:38:25 INFO - Cleaning up... 01:38:25 INFO - Return code: 0 01:38:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 01:38:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 01:38:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:25 INFO - 'HOME': '/Users/cltbld', 01:38:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:25 INFO - 'LOGNAME': 'cltbld', 01:38:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:25 INFO - 'MOZ_NO_REMOTE': '1', 01:38:25 INFO - 'NO_EM_RESTART': '1', 01:38:25 INFO - 'PAGER': '/bin/cat', 01:38:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:25 INFO - 'PWD': '/builds/slave/test', 01:38:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:25 INFO - 'SHELL': '/bin/bash', 01:38:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:25 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:25 INFO - 'USER': 'cltbld', 01:38:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:25 INFO - 'XPC_FLAGS': '0x0', 01:38:25 INFO - 'XPC_SERVICE_NAME': '0', 01:38:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-PTZwxN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lLLiAI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-XC_nVB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-8fVVE0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-KaEFfz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-okY_Jm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-XrOMxA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 01:38:26 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 01:38:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 01:38:26 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-BQNBfG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-3tiIod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-JUkiK8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-Dj6mwp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-aWpcW2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-miKGtH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-u0IXe1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-1FgJn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-X23Uol-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 01:38:27 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 01:38:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 01:38:27 INFO - Running setup.py (path:/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/pip-lBMUjI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 01:38:27 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)) 01:38:27 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)) 01:38:27 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)) 01:38:27 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)) 01:38:27 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)) 01:38:27 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)) 01:38:27 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 01:38:27 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 01:38:27 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 01:38:27 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 01:38:27 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 01:38:27 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 01:38:27 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 01:38:29 INFO - Downloading blessings-1.6.tar.gz 01:38:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 01:38:29 INFO - Installing collected packages: blessings 01:38:29 INFO - Running setup.py install for blessings 01:38:29 INFO - Successfully installed blessings 01:38:29 INFO - Cleaning up... 01:38:29 INFO - Return code: 0 01:38:29 INFO - Done creating virtualenv /builds/slave/test/build/venv. 01:38:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:29 INFO - Reading from file tmpfile_stdout 01:38:29 INFO - Current package versions: 01:38:29 INFO - blessings == 1.6 01:38:29 INFO - blobuploader == 1.2.4 01:38:29 INFO - docopt == 0.6.1 01:38:29 INFO - functools32 == 3.2.3-2 01:38:29 INFO - jsonschema == 2.5.1 01:38:29 INFO - manifestparser == 1.1 01:38:29 INFO - mozInstall == 1.12 01:38:29 INFO - mozcrash == 1.0 01:38:29 INFO - mozdebug == 0.1 01:38:29 INFO - mozdevice == 0.49 01:38:29 INFO - mozfile == 1.2 01:38:29 INFO - mozhttpd == 0.7 01:38:29 INFO - mozinfo == 0.9 01:38:29 INFO - mozleak == 0.1 01:38:29 INFO - mozlog == 3.4 01:38:29 INFO - moznetwork == 0.27 01:38:29 INFO - mozprocess == 0.25 01:38:29 INFO - mozprofile == 0.28 01:38:29 INFO - mozrunner == 6.13 01:38:29 INFO - mozscreenshot == 0.1 01:38:29 INFO - mozsystemmonitor == 0.3 01:38:29 INFO - moztest == 0.8 01:38:29 INFO - mozversion == 1.4 01:38:29 INFO - psutil == 3.1.1 01:38:29 INFO - requests == 1.2.3 01:38:29 INFO - wsgiref == 0.1.2 01:38:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 01:38:29 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')]} 01:38:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 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')]} 01:38:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:29 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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:29 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 01:38:29 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 01:38:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:29 INFO - 'HOME': '/Users/cltbld', 01:38:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:29 INFO - 'LOGNAME': 'cltbld', 01:38:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:29 INFO - 'MOZ_NO_REMOTE': '1', 01:38:29 INFO - 'NO_EM_RESTART': '1', 01:38:29 INFO - 'PAGER': '/bin/cat', 01:38:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:29 INFO - 'PWD': '/builds/slave/test', 01:38:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:29 INFO - 'SHELL': '/bin/bash', 01:38:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:29 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:29 INFO - 'USER': 'cltbld', 01:38:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:29 INFO - 'XPC_FLAGS': '0x0', 01:38:29 INFO - 'XPC_SERVICE_NAME': '0', 01:38:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 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)) 01:38:30 INFO - Cleaning up... 01:38:30 INFO - Return code: 0 01:38:30 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 01:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 01:38:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 01:38:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 01:38:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 01:38:30 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 0x10e093b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10e096768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb433d853f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, '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.WiyF59NIUA/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.bJhPxZQVML/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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 01:38:30 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 01:38:30 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 01:38:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:30 INFO - 'HOME': '/Users/cltbld', 01:38:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:30 INFO - 'LOGNAME': 'cltbld', 01:38:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:30 INFO - 'MOZ_NO_REMOTE': '1', 01:38:30 INFO - 'NO_EM_RESTART': '1', 01:38:30 INFO - 'PAGER': '/bin/cat', 01:38:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:30 INFO - 'PWD': '/builds/slave/test', 01:38:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:30 INFO - 'SHELL': '/bin/bash', 01:38:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:30 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:30 INFO - 'USER': 'cltbld', 01:38:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:30 INFO - 'XPC_FLAGS': '0x0', 01:38:30 INFO - 'XPC_SERVICE_NAME': '0', 01:38:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 01:38:30 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 01:38:30 INFO - Cleaning up... 01:38:30 INFO - Return code: 0 01:38:30 INFO - Running post-action listener: _resource_record_post_action 01:38:30 INFO - Running post-action listener: _start_resource_monitoring 01:38:30 INFO - Starting resource monitoring. 01:38:30 INFO - [mozharness: 2017-05-06 08:38:30.547740Z] Finished create-virtualenv step (success) 01:38:30 INFO - [mozharness: 2017-05-06 08:38:30.548771Z] Running install step. 01:38:30 INFO - Running pre-action listener: _resource_record_pre_action 01:38:30 INFO - Running main action method: install 01:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 01:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 01:38:30 INFO - Reading from file tmpfile_stdout 01:38:30 INFO - Detecting whether we're running mozinstall >=1.0... 01:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 01:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 01:38:30 INFO - Reading from file tmpfile_stdout 01:38:30 INFO - Output received: 01:38:30 INFO - Usage: mozinstall [options] installer 01:38:30 INFO - Options: 01:38:30 INFO - -h, --help show this help message and exit 01:38:30 INFO - -d DEST, --destination=DEST 01:38:30 INFO - Directory to install application into. [default: 01:38:30 INFO - "/builds/slave/test"] 01:38:30 INFO - --app=APP Application being installed. [default: firefox] 01:38:30 INFO - mkdir: /builds/slave/test/build/application 01:38:30 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 01:38:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 01:38:51 INFO - Reading from file tmpfile_stdout 01:38:51 INFO - Output received: 01:38:51 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 01:38:51 INFO - Running post-action listener: _resource_record_post_action 01:38:51 INFO - [mozharness: 2017-05-06 08:38:51.296373Z] Finished install step (success) 01:38:51 INFO - [mozharness: 2017-05-06 08:38:51.296526Z] Running run-tests step. 01:38:51 INFO - Running pre-action listener: _resource_record_pre_action 01:38:51 INFO - Running main action method: run_tests 01:38:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:38:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:38:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:38:51 INFO - 2017-05-06 01:38:51.311 screenresolution[1646:11635] starting screenresolution argv=screenresolution get 01:38:51 INFO - 2017-05-06 01:38:51.313 screenresolution[1646:11635] Display 0: 1600x1200x32@60 01:38:51 INFO - 2017-05-06 01:38:51.319 screenresolution[1647:11638] starting screenresolution argv=screenresolution list 01:38:51 INFO - Available Modes on Display 0 01:38:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:38:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:38:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:38:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:38:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:38:51 INFO - Intel Iris: 01:38:51 INFO - Chipset Model: Intel Iris 01:38:51 INFO - Type: GPU 01:38:51 INFO - Bus: Built-In 01:38:51 INFO - VRAM (Dynamic, Max): 1536 MB 01:38:51 INFO - Vendor: Intel (0x8086) 01:38:51 INFO - Device ID: 0x0a2e 01:38:51 INFO - Revision ID: 0x0009 01:38:51 INFO - Displays: 01:38:51 INFO - Display: 01:38:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:38:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:38:51 INFO - Display Serial Number: 1600x1200 60 01:38:51 INFO - Main Display: Yes 01:38:51 INFO - Mirror: Off 01:38:51 INFO - Online: Yes 01:38:51 INFO - Rotation: Supported 01:38:51 INFO - Return code: 0 01:38:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 01:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 01:38:51 INFO - Python 2.7.3 01:38:51 INFO - Return code: 0 01:38:51 INFO - grabbing minidump binary from tooltool 01:38:51 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')]} 01:38:51 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 0x10de71d30>, '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 0x10dd98db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10de66be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 01:38:51 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 01:38:51 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 01:38:51 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 01:38:51 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 01:38:51 INFO - Return code: 0 01:38:51 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 01:38:51 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 01:38:51 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 01:38:51 INFO - ENV: RUST_BACKTRACE is now 1 01:38:51 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 01:38:51 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 01:38:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 01:38:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0317 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 01:38:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.WiyF59NIUA/Render', 01:38:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 01:38:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 01:38:51 INFO - 'HOME': '/Users/cltbld', 01:38:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 01:38:51 INFO - 'LOGNAME': 'cltbld', 01:38:51 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 01:38:51 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 01:38:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 01:38:51 INFO - 'MOZ_NO_REMOTE': '1', 01:38:51 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 01:38:51 INFO - 'NO_EM_RESTART': '1', 01:38:51 INFO - 'PAGER': '/bin/cat', 01:38:51 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 01:38:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 01:38:51 INFO - 'PWD': '/builds/slave/test', 01:38:51 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 01:38:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 01:38:51 INFO - 'RUST_BACKTRACE': '1', 01:38:51 INFO - 'SHELL': '/bin/bash', 01:38:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.bJhPxZQVML/Listeners', 01:38:51 INFO - 'TMPDIR': '/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/', 01:38:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 01:38:51 INFO - 'USER': 'cltbld', 01:38:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 01:38:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 01:38:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 01:38:51 INFO - 'XPC_FLAGS': '0x0', 01:38:51 INFO - 'XPC_SERVICE_NAME': '0', 01:38:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 01:38:51 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0317', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 01:38:51 INFO - mozversion application_buildid: 20170506004004 01:38:51 INFO - mozversion application_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:38:51 INFO - mozversion application_display_name: FirefoxDeveloperEdition 01:38:51 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 01:38:51 INFO - mozversion application_name: Firefox 01:38:51 INFO - mozversion application_remotingname: firefox-dev 01:38:51 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:51 INFO - mozversion application_vendor: Mozilla 01:38:51 INFO - mozversion application_version: 54.0a2 01:38:51 INFO - mozversion platform_buildid: 20170506004004 01:38:51 INFO - mozversion platform_changeset: 94b7e538af7dbb9a6567cad9125c2f5411e7fb80 01:38:51 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 01:38:51 INFO - mozversion platform_version: 54.0a2 01:38:51 INFO - using testdate: 1494059931 01:38:51 INFO - actual date: 1494059931 01:38:51 INFO - starting webserver on 'localhost:49245' 01:38:51 INFO - SUITE-START | Running 2 tests 01:38:51 INFO - TEST-START | dromaeo_css 01:38:51 INFO - operating with platform_type : mac_ 01:38:51 INFO - Initialising browser for dromaeo_css test... 01:38:51 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpC_Mr8x/profile http://localhost:49245/getInfo.html) 01:38:54 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 01:38:54 INFO - PROCESS | 1657 | colorDepth:24 01:38:54 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 01:38:54 INFO - PROCESS | 1657 | __metrics 01:38:54 INFO - PROCESS | 1657 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:38:54 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:38:55 INFO - TEST-INFO | 1657: exit 0 01:38:55 INFO - Browser initialized. 01:38:55 INFO - Running cycle 1/1 for dromaeo_css test... 01:38:55 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpC_Mr8x/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:39:07 INFO - PROCESS | 1662 | RSS: Main: 210780160 01:39:07 INFO - PROCESS | 1662 | 01:42:11 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 01:42:14 INFO - PROCESS | 1662 | RSS: Main: 412401664 01:42:14 INFO - PROCESS | 1662 | 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | A coding exception was thrown and uncaught in a Task. 01:43:57 INFO - PROCESS | 1662 | 01:43:57 ERROR - PROCESS | 1662 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:57 INFO - PROCESS | 1662 | Full stack: 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | A coding exception was thrown and uncaught in a Task. 01:43:57 INFO - PROCESS | 1662 | 01:43:57 ERROR - PROCESS | 1662 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:57 INFO - PROCESS | 1662 | Full stack: 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | A coding exception was thrown and uncaught in a Task. 01:43:57 INFO - PROCESS | 1662 | 01:43:57 ERROR - PROCESS | 1662 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:57 INFO - PROCESS | 1662 | Full stack: 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | ************************* 01:43:57 INFO - PROCESS | 1662 | A coding exception was thrown and uncaught in a Task. 01:43:57 INFO - PROCESS | 1662 | 01:43:57 ERROR - PROCESS | 1662 | Full message: TypeError: NetworkError when attempting to fetch resource. 01:43:57 INFO - PROCESS | 1662 | Full stack: 01:43:57 INFO - PROCESS | 1662 | ************************* 01:45:17 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 01:45:18 INFO - PROCESS | 1662 | RSS: Main: 351985664 01:45:18 INFO - PROCESS | 1662 | 01:48:20 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 01:48:21 INFO - PROCESS | 1662 | RSS: Main: 325451776 01:48:21 INFO - PROCESS | 1662 | 01:51:23 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 01:51:24 INFO - PROCESS | 1662 | RSS: Main: 325963776 01:51:24 INFO - PROCESS | 1662 | 01:54:27 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 01:54:28 INFO - PROCESS | 1662 | RSS: Main: 320241664 01:54:28 INFO - PROCESS | 1662 | 01:57:31 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 01:57:31 INFO - PROCESS | 1662 | RSS: Main: 532733952 01:57:31 INFO - PROCESS | 1662 | 01:57:31 INFO - PROCESS | 1662 | __start_tp_report 01:57:31 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 01:57:31 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 01:57:31 INFO - PROCESS | 1662 | |i|pagename|runs| 01:57:31 INFO - PROCESS | 1662 | |0;dojo.html;3165.715085642292;3371.3425881953;3398.1844357354225;3399.881004164855;3447.4828775683645;5831.970840145798;5851.268591426072;5863.501602363794;5866.73599688014;5925.903707814057;5859.736311865966;5917.467427931485;5924.763009479627;5934.703264836762;5937.762489500415;5292.761825717842;5314.521693047625;5366.597505187107;5400.621956463049;5434.619576629638;5240.8689782755355;5244.108501554745;5417.945820541803;5418.160185171305;5427.0773968425265;5270.367555893279;5311.389190243113;5312.4687531246955;5314.468553144674;5327.946720532803;5302.575793936495;5303.7348132593215;5304.734763261821;5322.148456246982;5325.467453254663;5138.332016837825;5265.104932161504;5266.631335806495;5270.998510283034;5312.734363281859;5860.120981852713;5863;5868.354481007086;5870.530357571427;5875.559333049996;6213.782517611862;6238.251409830804;6243.65659888706;6244.5316601254635;6256.6871656417;6258.593191442542;6290.276618188921;6291.465225455841;6310.179676642053;6345.841353966188;5142.125838607446;6010.429009244115;6025.3372129065765;6029.276486821565;6068.696565171768;6389.6805159741825;6418.19772528434;6426.678666066678;6438.484921206339;6440.0661904023655;6118.847028824315;6133.4264757247465;6177.4749146323065;6238.75044998195;6271.435570798559;6320.62076275425;6325.5255855811;6333.778317758856;6349.206349206349;6349.714262858182;6330.556861019684;6350.269718982284;6355.586886852339;6371.585846919936;6389.265234498;6177.382261773787;6207.751689932443;6281.78013769525;6304.148939893055;6332.303446620868;5042.873928151826;5063.215201643751;5114.744262786846;5118.872028199325;5120.513551212629;4515.503294637587;4535.931961020588;4547.47704014036;4559.293309537027;4569.040501494657;5829.941700583025;5881.5000724938;5913.113033045078;5917.053271476543;5928.970355148197;6242.219722534684;6314.463270621956;6351.079093531412;6356;6363.140975968185;5879.647221166743;5882.441168089024;5910.379410161957;5914.4381283777975;5915.37888521699;5432.158015507603;5529.917051244234;5604.047311956977;5605.7197140142825;5625.915611265835;5892.263467066617;6248.9062664059165;6447.4197322241225;6501.6424096673245;6520.347965203442;6260.154879091265;6301;6319.494440444845;6324.272708638566;6337.6197428154455;6516.348365163446;6533.771318003847;6546.279909210079;6553.803385898431;6562.573432726857;6314.305426403183;6363.490920726424;6365.745370185141;6372.681365931778;6376.713047912948;5301.708406037705;6067.696615169136;6291.433770960635;6317.399847014526;6324.146240257691;6208.596441231305;6246.531510136849;6334.778282760081;6343.651099189589;6345.619262844059;6264.404881536247;6304.5271604628915;6327.588706734047;6336.7148478319505;6337.081123237197;6178.536609754196;6179.907301390393;6200.348963358782;6205.5345849060595;6219.067139929047;5506.311711036121;6098.237720284965;6137.693115344304;6153.569250152446;6233.065040244;5893.322267939242;5893.793717219876;6121.361519882331;6139.508839292936;6155.692215389302 01:57:31 INFO - PROCESS | 1662 | |1;ext.html;792.3304807437715;812.8821320908467;866.7226487523991;879.5558243087244;966.6568368229283;28268.434631307387;37520.87437376883;37640.62359376405;38811.22377552447;38846.22307553847;37263.25473490536;37457.625423745754;37465.81267093668;37517.812410937906;37612.68355607554;7327.157376901671;7495.175530691647;7702.152763196058;7846.215378462165;11042.3374597524;11029.61396351128;11155.163362747786;11169.385683787388;11181.161412894025;11198.552057917674;11159.609413670523;11162.386068766215;11176.776464470706;11186.496607652674;11223.102151827876;11115.277506962062;11141.164412669043;11144.498497567629;11145.888541114648;11161.330320180734;10865.29375590592;10913.83629245562;10920.344779313187;10929.289596176222;10935.617253396173;31915.202119946956;38619;38791.41812872809;38826;38869;3143.748500120008;3803.581606023335;3823.4073718573386;3836.1943991761486;3854.7687138771485;26230.73769262302;26892.731072689414;28147.15558533247;28640.283992900135;28807.711922880713;3143.858526366319;3173.2701478660038;3201.695838895301;3275.394052100369;3277.901662950115;346342;348094;348175.25912370527;348581;348623;41604;42473.57526424758;44396.77801611036;52223.47776522262;52450.47549524456;53893.73053134788;54195;55937;56370.718146409825;56474;14777.704445911237;14836.3323650436;14854.70290594182;14858.702825943421;14897.851021489863;12785.80821287663;12790.9360453199;12809;12847.486100555872;12890.582035976035;13357.732845343038;13369.197848129143;13389.129706569236;13401.530946417024;13556.915446764235;5885.911711324334;5939.851503712442;5955.34491205967;5968.134620480006;5969.522438204933;1065.8188108021654;1090.2259395828892;1095.1676725688376;1119.6193294279983;1164.551647615674;26040.479190416085;26273.39174739639;26654.733452665612;26766.46467070648;26893.462130757667;1067.3649178738638;1154.5554961339944;1207.5773479282357;1252.956146534867;1254.8368712067372;13698.726025479635;13708.971827112808;13859.514916977858;13953.930230348784;14019.929900350433;486913;488846;489405;490352;490772;55036.72481637566;55480.44519554834;55569.61075973133;55599.16601250984;55624.609384764895;1407.90848594841;1442.816890150067;1454.7599646058281;1459.569427019032;1469.808924839764;21667.674984875553;25235.369115772253;26179.607305890808;26206;26272.474550509265;46180.845478863295;46591.53408465872;46684.066318672754;46720;46843.76578117088;424873;428135;428148;428519;429496;28612.42775144527;29126.56310155392;29222;33859.661403386635;35710.928672139875;42654.573454266305;43135.35296970611;44151;44727;44759;29470.115896523137;30303.69696303097;30841.383172336875;30993.69006309998;31092;30071.699283007765;30078.699213008465;30468.695313046584;30894;31148.22129446782;32582.83708581442;36207.275854482235;36356;36361;36414.63585364218;25435.982560698107;26419.339516512242;28242.011529596366;28858.27854303659;29122.27194320159;21848.672269915645;22443.88778056099;22819.429514261614;23109.88445057764;23209.419764506023 01:57:31 INFO - PROCESS | 1662 | |2;jquery.html;384.2507111133291;408.6097776623323;417.42603919610536;419.3939757051061;443.1668463289016;11185.944070279647;14571.27143642819;20470.795292047074;20601.27895523657;20973.47566310843;18318.633627327446;21814.127434902624;22094.88952555235;22685.659715104288;22735.31794046177;6433.485321174307;6437.678116094176;6558.8683677804665;6558.8688226235445;6640.236372817116;4054.2296963576828;6472.2880483147055;6585.407313341799;6605.57063790852;6608.801735947929;4298.849540266091;4384.649228061763;7357.668904899292;7430.777076687681;7457.81355466115;7234.204237533867;7255.419566434673;7436.293552112541;7437.367823734989;7479.962600186991;7181.425485961111;7204.747833825843;7209.855802883965;7231.746888858917;7240.058792357013;24329.9051542681;25531.106411275516;25667.614985775228;26005.609915851277;26318.342041448926;2794.483020641188;3133.6082989626298;3154.3691261747513;3177.841107944617;3205.182678416989;14918.328675209641;15851.207439627973;16257.187140643038;16952.491425257263;17014.65970680592;2038.2967876082876;2072.5647614001077;2078.906449209789;2084.3017589107667;2097.1716172112083;150329;155474;156441;156457;157548;15842.683146337009;15968.36126554948;19884.204631814853;20147.29484468066;20177.697334539993;30649.693503064686;30751.23121921925;30779.692203077684;30781.076567703003;30807.22981925425;10011.149052330633;10108.39349639024;10148.137408320228;10258.384496930208;10380.32527885685;7144.214136444987;8551.315894728406;8573.356998225157;8577.356698247657;8707.12928707137;9032.32257580671;9149.771255718528;9203.539823008823;9317.72046838596;9338.859917101248;3839.462475253467;3902.180542086137;3930.4693866328253;3938.9803050985133;3944.599667118132;857.352698712468;939.5490164720835;1006.8187726209206;1026.9075783179549;1083.8807731149568;18960.715589266172;19773.406797796088;21703.02336394866;22071.558568828532;22160.667589986162;833.4332653795356;914.6478605736839;928.2110206324705;940.6801687426308;953.594722243044;10817.242793004414;11149.99650031501;11292.887071129348;11445.141614378956;11644.650660480029;788962;797856;800174;808702;812795;112357;147209;151188;164804.7039059212;167638;1159.2701776750837;1187.145255416099;1222.6148763139583;1226.8834460726216;1232.3653318540958;19684.01579921027;19785.703214451507;20218.393448196577;20268.493287667927;20282.695759563914;65294.67352663206;65353.67323163354;65548.37806487878;65835;66025;90085;92354.53822731112;92892.53553732188;97134;100308.99691002996;11074.283486059674;11532.94233528827;11656.475458604213;11744.119191060876;11910.34493102853;12603.68490787701;12856.935715321737;12884.162529435556;13163.934180329037;13203.801942971055;11402.460667809739;11428.771424571629;11556.804207200605;11618.70953226176;11812.232204906653;10480.68557943263;10806.513706883052;10839;11158.944205278922;11175.776484470103;11072.224944253825;11299.717507062389;11387.943060284646;11430.657080287605;11454.713632159262;11462.598809041643;11482.82775758381;11570.016548593463;11602.129840262183;11811.586594469152;10946.124310055337;11342.092632589538;11565.653030409101;11570.305781653127;11634.76730465403 01:57:31 INFO - PROCESS | 1662 | |3;mootools.html;1547.2650491016761;1555.6266496040953;1575.6218507558183;1578.4633224703603;1584.207896051974;10335.069843714064;10897.128229741622;10907.781844363108;10911;10916.235863489559;10122.34204776689;10433.42616156113;10505.21210909181;10545.156387489002;10629.52167152478;2317.768223177681;2349.3304408243653;2350.764923507644;2354.0113152476;2356.1400088967575;2267.1044937249776;2338.9298321050396;2353.9293821185393;2360.4216966843146;2362.126013375054;2284.2804516577226;2338.053088499152;2347.7182738071456;2348.612478940973;2352.482453860148;2103.7685854555984;2306.8269879759087;2325.232673217834;2330.8368414211013;2337.7662233776655;1839.8804077734903;2082.1671331467383;2231.2079211879873;2232.7767223277706;2239.7648246934164;10633.734156646145;10731.141508679288;10746.247762656618;10764.946175269073;10782.838257426145;5371.865703357447;5539.639923404966;5540.418256083093;5550.555955523549;5556.91664625031;3217.951730724041;3316.1875340541724;3494.3011397720556;3496.5454490916272;3498.2129020970333;1116.7040734205443;1117.8546788917388;1122.4948773052158;1135.9716007099805;1169.228309315856;11076.723081922935;13563.321833908265;13869.791953120812;14893.957422980397;14937.402503899832;4342.392065110887;4507.887302817456;4535.342375355555;4537.251353526697;4576.794044268016;11377.943110284396;11401.087912966943;11408.657740267805;11421.029212517029;11429.714257143638;4722.811087556528;4929.580985616191;5437.8368648940595;5441.537469315073;5444.891102177934;4868.756562171877;5084.720340381315;5117.053345131163;5127.743612819344;5128.1794912813775;3590.335787879251;3737.121776382548;3760.2103558252525;3807.8286477108595;3821.3312670283144;964.7636329099415;964.9662261820943;972.7081875437341;978.2076518020284;981.9459929703792;789.8617741895146;799.1688643810372;812.29736278119;819.8893149424871;821.8685010398308;6178.876422471525;6225.968870155621;6408.423241908251;6408.487321014401;6416.711247993851;831.98336033279;835.8871552340478;929.0755698080453;975.5707488704948;1010.4998025977042;3962.465067215889;4090.877273681794;4151.792410379529;4170.70805043644;4243.936340954888;9225.40034897743;13222.471101155847;13233.139845909986;13248.867511324956;13262.336883155804;13142.211467311992;13160.210387376788;13177.604671859859;13197.868021319857;13282.933585332205;9581.233501319877;9820.4107753535;9865.654702085392;10027.899721002843;10063.49682515886;5487.890242195213;6279.089532017923;6300.464460520907;6387.457066149429;6454;10853.945730271298;11571.305721656727;12080.21478603923;12214.938925305316;12228.75542489163;11031.39327336972;11386.886131138583;11544.960953513888;12222.511099555919;12229.75540489203;6880.827979300558;7995.800104997422;8232.300254478198;8281;8452.577371131296;10085.596576136872;10096.848547271944;10223.488825558841;10224.079832815083;10237.436940968319;8539.359548033797;8739.475631462132;8790.033096359268;8824.3381746368;8831.69089081879;8381;8399.28606068491;8441.451305665112;8497.532635705096;8507.191816777644;7239.239879812544;7745.496542724705;7771.4559980800805;7817;7822.804429889071;5608.158776183605;5694.544436445157;5736.397678243724;5823.330317013598;5863.120531920246;5752.13717942312;5781.277340332458;5785.392533783892;5842;5949.851253718692 01:57:31 INFO - PROCESS | 1662 | |4;prototype.html;360.9133807886107;373.5610657477465;374.6272458903388;378.11521040764615;382.96361845624654;4745.477997420286;5043.621728370373;5122.718250496221;5160.612954028444;5166.424240606618;4476.6418686505085;4964.453910069889;4975.179095449246;4996.1756310208775;5056.938043010963;3559.4660800879947;3645;3959.198564653076;4162.793719328761;4185.727927684691;3715.8262416486386;4153.68847336451;4177.498700155986;4185.623293903548;4285.742855428669;3675.9339791460443;4087.7547347159116;4179.331306990882;4202.642775364083;4242.172776308621;3610.339243947787;3797.1076796952693;3999.500062492174;4151.647109995654;4162.646175075122;3238.611366636019;3761.5298087739034;3778.1310298631192;3795.7532760370764;3798.2213646202463;4397.789613582071;4643.326717625925;4696.365990591261;4848.5151484851585;4916.877078073041;3864.835692012482;4050.189962007581;4142.523609784853;4348.760818155;4767.976160119212;4742.099001189763;5092.770825312869;5187.8184263550975;5275.733823882279;5292.814751483718;4260.7656578888145;4285.507166675841;4314.633256173229;4317.309230523101;4317.525072242051;4580.839670611513;4750.477447480775;5247.2653828464045;5342.358916929993;5605.691686957257;3801.467794508771;3942.704297177723;4119.340905455173;4323.567643235714;4326.697131200785;4250.341197114452;4391.155714599812;4400.44994375703;4428.31361139024;4444.644428445717;4636.188666983265;4724.4094488188975;4739.156506096384;4804.95195048052;4815.91641880574;5015.824446144367;5307.363116425976;5336.626436149432;5347.731709376655;5356.6608347913025;1275.1647670775658;1311.2984553263957;1370.1778932640536;1506.006036016224;1508.8868334874928;929.5584597316356;1077.186724023373;1099.274478843959;1129.5199540195383;1134.880837512066;4060.939085913714;4510.368548403226;4552.453705555355;4617.376654151713;4995;4686.461056978423;4874.658773885794;4941.135301322254;5533.972330138404;5603.243562119062;4367.912641747211;4661.766911654404;4919.77860996256;4937.382827146606;5041.773120209599;2461.54461424637;2463.95072098557;2824.180987513639;2865.298001989526;2870.296777289577;4933.506649335038;4951.722207784387;5100.132977393852;5235.554977826851;5656.236408084938;4817.759112044384;4889.841217515555;5616.69108199053;5618.831435056954;5680.415410987991;4816.807327706852;4861.538153875376;5185.510954741745;5522.475364840334;5586.748596313176;4838.959623680926;5170.327857378667;5278.152646573669;5438.809641662523;5465.781368745205;4655.3249778782565;5186.559142472781;5342.465753424626;5402.162664786964;5427.1045277528765;5194.909069095381;5607.467290607386;5645.9153112702525;5663.292088488939;5677.24653543023;3885.6502914737807;4083.387491876242;4231.703780735319;4304.708587423823;4320.697551171514;4583.564561366665;4651.255739130032;5280.234366016983;5390.892182156256;5556.888862222814;4834.927476087792;5034.798608055637;5132.640715149903;5317.8404647860625;5364.678119312854;4956.182229932165;5278.017919875411;5283.762230699551;5295.47850679294;5304.681719096867;4443.822247110079;4451.265541185668;4655.813767449264;4792.976035119802;5239.580833533384;5107.0634385227895;5299.417064122867;5314.4951229633125;5328.280682107866;5333.240065753712;4798.376211092535;4862.90274194507;4915.95084049155;5184.733617820749;5215.157739032161 01:57:31 INFO - PROCESS | 1662 | |5;yui.html;755.3051192902533;760.0840986611134;789.3487872505177;803.1205829616571;818.1940788323502;15007.324670389831;15786.526404207863;15813.920930395361;15816.051036937777;15846.762298565516;14461.710765784677;14550;14616.488422905173;14616.707665846703;15086.6982660347;908.1372695938851;929.6932012435914;929.9070092990714;969.0551712080735;976.4775844922967;956.2350119904083;963.2390411574847;971.5870754929163;972.367960825462;972.7713987212999;868.6264906090379;1014.06705830636;1016.959321627134;1017.0947856407803;1017.5471914997829;1002.0330381182122;1003.8092762375198;1003.9498025098752;1004.1013293102706;1005.9597616095348;747.7794050755041;747.7906186267853;748.1209578745007;749.3293502315452;750.2909750285982;15608.531744047697;15636.687266254725;15647.530574082683;16508.25712842925;16724.58188545284;13889.861101388959;13943.721125577431;14139.505117320845;14139.717205655828;14149.71700565983;11706.707332316675;11735.823962640567;11881.702957426047;11903.642890713292;11966.760664786743;3572.4284114541547;3617.113807117273;3669.7247706422127;3687.9262414751556;3815.08437974884;107659;107726;109244;109696;109782;10434.739131521772;10448.738781530523;10609.522571484302;11049.723756905982;11105.167112466597;21820.563588728455;22256.55486890253;22304.665430018562;22327.553448930932;22411.551768964855;6424.775132870327;7508.7371941982865;7584.658690358947;7673;7812.765617031499;4271.10306835568;4283.250431174532;4352.7823608819435;4379.861236078643;4431;3300.339932013559;3480.965190348115;3547.840347184383;3555.7866528008403;3581.3195492856275;619.845038740315;631.6399652198295;632.137132813706;632.9430351268408;643.5495153392644;670.9127813384229;672.9596224226563;685.9314068593201;695.9025736396909;733.9889901651479;9703.905449638996;10158.441285729208;10309.484525773682;10329.535170917326;10453.216008799218;676.3270545806954;692.5221597097986;716.5378330976491;737.8044818123094;737.9520331178456;4846.3699719037;4958.776855041532;4972.850814475499;4999.175136102503;5003.72479513623;87568;88856;103335.96664033414;105383;109993;64104;81304;85224;85260.14739852646;86363;74481;76931.07672308237;77212.61393692995;77752.61123694346;79243.60378198187;8781.956090219508;9523.904760952579;9710.077542633438;9722.611095556098;9743.464109473764;44743.55256447524;45094.32358514691;47100.528994710985;49712;50407.4877753668;51886.740566296925;61439.38560614337;64347.678261608395;64967.35032649613;67078.66460667666;12394;12614.058256297663;12712.872871271169;12790.74418511606;13118.672033199246;13908.582742517741;14585.635359116108;14659.853401465849;14668.119912805267;15058.54824355271;13029.739405211652;13572.04995650295;13812.654683632989;13881.958853085856;14173.574792756233;13230.47078116903;13386.19682819034;13588.1847089171;13780.242086685328;14033.29833508341;12191.817122742988;13255;13381.66545836362;13418.060735748404;13525.458981640624;9163.083691630784;9479.715608531755;9945.353566881224;10047.246456515644;10260.640877569249;8698.565071746514;10677.572897084341;10708.678739637824;11309.886901130883;11473.827892581785 01:57:31 INFO - PROCESS | 1662 | __end_tp_report 01:57:31 INFO - PROCESS | 1662 | __start_cc_report 01:57:31 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2135 01:57:31 INFO - PROCESS | 1662 | __end_cc_report 01:57:32 INFO - PROCESS | 1662 | __startTimestamp1494061051983__endTimestamp 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | ------- Summary: start ------- 01:57:32 INFO - PROCESS | 1662 | Number of tests: 6 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5858.51 Median:6118.85 stddev:653.10 (10.7%) stddev-sans-first:620.00 01:57:32 INFO - PROCESS | 1662 | Values: 3165.7 3371.3 3398.2 3399.9 3447.5 5832.0 5851.3 5863.5 5866.7 5925.9 5859.7 5917.5 5924.8 5934.7 5937.8 5292.8 5314.5 5366.6 5400.6 5434.6 5240.9 5244.1 5417.9 5418.2 5427.1 5270.4 5311.4 5312.5 5314.5 5327.9 5302.6 5303.7 5304.7 5322.1 5325.5 5138.3 5265.1 5266.6 5271.0 5312.7 5860.1 5863.0 5868.4 5870.5 5875.6 6213.8 6238.3 6243.7 6244.5 6256.7 6258.6 6290.3 6291.5 6310.2 6345.8 5142.1 6010.4 6025.3 6029.3 6068.7 6389.7 6418.2 6426.7 6438.5 6440.1 6118.8 6133.4 6177.5 6238.8 6271.4 6320.6 6325.5 6333.8 6349.2 6349.7 6330.6 6350.3 6355.6 6371.6 6389.3 6177.4 6207.8 6281.8 6304.1 6332.3 5042.9 5063.2 5114.7 5118.9 5120.5 4515.5 4535.9 4547.5 4559.3 4569.0 5829.9 5881.5 5913.1 5917.1 5929.0 6242.2 6314.5 6351.1 6356.0 6363.1 5879.6 5882.4 5910.4 5914.4 5915.4 5432.2 5529.9 5604.0 5605.7 5625.9 5892.3 6248.9 6447.4 6501.6 6520.3 6260.2 6301.0 6319.5 6324.3 6337.6 6516.3 6533.8 6546.3 6553.8 6562.6 6314.3 6363.5 6365.7 6372.7 6376.7 5301.7 6067.7 6291.4 6317.4 6324.1 6208.6 6246.5 6334.8 6343.7 6345.6 6264.4 6304.5 6327.6 6336.7 6337.1 6178.5 6179.9 6200.3 6205.5 6219.1 5506.3 6098.2 6137.7 6153.6 6233.1 5893.3 5893.8 6121.4 6139.5 6155.7 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:55744.33 Median:30867.69 stddev:112996.61 (366.1%) stddev-sans-first:113238.27 01:57:32 INFO - PROCESS | 1662 | Values: 792.3 812.9 866.7 879.6 966.7 28268.4 37520.9 37640.6 38811.2 38846.2 37263.3 37457.6 37465.8 37517.8 37612.7 7327.2 7495.2 7702.2 7846.2 11042.3 11029.6 11155.2 11169.4 11181.2 11198.6 11159.6 11162.4 11176.8 11186.5 11223.1 11115.3 11141.2 11144.5 11145.9 11161.3 10865.3 10913.8 10920.3 10929.3 10935.6 31915.2 38619.0 38791.4 38826.0 38869.0 3143.7 3803.6 3823.4 3836.2 3854.8 26230.7 26892.7 28147.2 28640.3 28807.7 3143.9 3173.3 3201.7 3275.4 3277.9 346342.0 348094.0 348175.3 348581.0 348623.0 41604.0 42473.6 44396.8 52223.5 52450.5 53893.7 54195.0 55937.0 56370.7 56474.0 14777.7 14836.3 14854.7 14858.7 14897.9 12785.8 12790.9 12809.0 12847.5 12890.6 13357.7 13369.2 13389.1 13401.5 13556.9 5885.9 5939.9 5955.3 5968.1 5969.5 1065.8 1090.2 1095.2 1119.6 1164.6 26040.5 26273.4 26654.7 26766.5 26893.5 1067.4 1154.6 1207.6 1253.0 1254.8 13698.7 13709.0 13859.5 13953.9 14019.9 486913.0 488846.0 489405.0 490352.0 490772.0 55036.7 55480.4 55569.6 55599.2 55624.6 1407.9 1442.8 1454.8 1459.6 1469.8 21667.7 25235.4 26179.6 26206.0 26272.5 46180.8 46591.5 46684.1 46720.0 46843.8 424873.0 428135.0 428148.0 428519.0 429496.0 28612.4 29126.6 29222.0 33859.7 35710.9 42654.6 43135.4 44151.0 44727.0 44759.0 29470.1 30303.7 30841.4 30993.7 31092.0 30071.7 30078.7 30468.7 30894.0 31148.2 32582.8 36207.3 36356.0 36361.0 36414.6 25436.0 26419.3 28242.0 28858.3 29122.3 21848.7 22443.9 22819.4 23109.9 23209.4 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:44527.41 Median:11535.32 stddev:133359.51 (1156.1%) stddev-sans-first:133692.42 01:57:32 INFO - PROCESS | 1662 | Values: 384.3 408.6 417.4 419.4 443.2 11185.9 14571.3 20470.8 20601.3 20973.5 18318.6 21814.1 22094.9 22685.7 22735.3 6433.5 6437.7 6558.9 6558.9 6640.2 4054.2 6472.3 6585.4 6605.6 6608.8 4298.8 4384.6 7357.7 7430.8 7457.8 7234.2 7255.4 7436.3 7437.4 7480.0 7181.4 7204.7 7209.9 7231.7 7240.1 24329.9 25531.1 25667.6 26005.6 26318.3 2794.5 3133.6 3154.4 3177.8 3205.2 14918.3 15851.2 16257.2 16952.5 17014.7 2038.3 2072.6 2078.9 2084.3 2097.2 150329.0 155474.0 156441.0 156457.0 157548.0 15842.7 15968.4 19884.2 20147.3 20177.7 30649.7 30751.2 30779.7 30781.1 30807.2 10011.1 10108.4 10148.1 10258.4 10380.3 7144.2 8551.3 8573.4 8577.4 8707.1 9032.3 9149.8 9203.5 9317.7 9338.9 3839.5 3902.2 3930.5 3939.0 3944.6 857.4 939.5 1006.8 1026.9 1083.9 18960.7 19773.4 21703.0 22071.6 22160.7 833.4 914.6 928.2 940.7 953.6 10817.2 11150.0 11292.9 11445.1 11644.7 788962.0 797856.0 800174.0 808702.0 812795.0 112357.0 147209.0 151188.0 164804.7 167638.0 1159.3 1187.1 1222.6 1226.9 1232.4 19684.0 19785.7 20218.4 20268.5 20282.7 65294.7 65353.7 65548.4 65835.0 66025.0 90085.0 92354.5 92892.5 97134.0 100309.0 11074.3 11532.9 11656.5 11744.1 11910.3 12603.7 12856.9 12884.2 13163.9 13203.8 11402.5 11428.8 11556.8 11618.7 11812.2 10480.7 10806.5 10839.0 11158.9 11175.8 11072.2 11299.7 11387.9 11430.7 11454.7 11462.6 11482.8 11570.0 11602.1 11811.6 10946.1 11342.1 11565.7 11570.3 11634.8 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:6429.92 Median:3497.38 stddev:4004.55 (114.5%) stddev-sans-first:3998.98 01:57:32 INFO - PROCESS | 1662 | Values: 1547.3 1555.6 1575.6 1578.5 1584.2 10335.1 10897.1 10907.8 10911.0 10916.2 10122.3 10433.4 10505.2 10545.2 10629.5 2317.8 2349.3 2350.8 2354.0 2356.1 2267.1 2338.9 2353.9 2360.4 2362.1 2284.3 2338.1 2347.7 2348.6 2352.5 2103.8 2306.8 2325.2 2330.8 2337.8 1839.9 2082.2 2231.2 2232.8 2239.8 10633.7 10731.1 10746.2 10764.9 10782.8 5371.9 5539.6 5540.4 5550.6 5556.9 3218.0 3316.2 3494.3 3496.5 3498.2 1116.7 1117.9 1122.5 1136.0 1169.2 11076.7 13563.3 13869.8 14894.0 14937.4 4342.4 4507.9 4535.3 4537.3 4576.8 11377.9 11401.1 11408.7 11421.0 11429.7 4722.8 4929.6 5437.8 5441.5 5444.9 4868.8 5084.7 5117.1 5127.7 5128.2 3590.3 3737.1 3760.2 3807.8 3821.3 964.8 965.0 972.7 978.2 981.9 789.9 799.2 812.3 819.9 821.9 6178.9 6226.0 6408.4 6408.5 6416.7 832.0 835.9 929.1 975.6 1010.5 3962.5 4090.9 4151.8 4170.7 4243.9 9225.4 13222.5 13233.1 13248.9 13262.3 13142.2 13160.2 13177.6 13197.9 13282.9 9581.2 9820.4 9865.7 10027.9 10063.5 5487.9 6279.1 6300.5 6387.5 6454.0 10853.9 11571.3 12080.2 12214.9 12228.8 11031.4 11386.9 11545.0 12222.5 12229.8 6880.8 7995.8 8232.3 8281.0 8452.6 10085.6 10096.8 10223.5 10224.1 10237.4 8539.4 8739.5 8790.0 8824.3 8831.7 8381.0 8399.3 8441.5 8497.5 8507.2 7239.2 7745.5 7771.5 7817.0 7822.8 5608.2 5694.5 5736.4 5823.3 5863.1 5752.1 5781.3 5785.4 5842.0 5949.9 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4386.27 Median:4747.98 stddev:1222.27 (25.7%) stddev-sans-first:1187.77 01:57:32 INFO - PROCESS | 1662 | Values: 360.9 373.6 374.6 378.1 383.0 4745.5 5043.6 5122.7 5160.6 5166.4 4476.6 4964.5 4975.2 4996.2 5056.9 3559.5 3645.0 3959.2 4162.8 4185.7 3715.8 4153.7 4177.5 4185.6 4285.7 3675.9 4087.8 4179.3 4202.6 4242.2 3610.3 3797.1 3999.5 4151.6 4162.6 3238.6 3761.5 3778.1 3795.8 3798.2 4397.8 4643.3 4696.4 4848.5 4916.9 3864.8 4050.2 4142.5 4348.8 4768.0 4742.1 5092.8 5187.8 5275.7 5292.8 4260.8 4285.5 4314.6 4317.3 4317.5 4580.8 4750.5 5247.3 5342.4 5605.7 3801.5 3942.7 4119.3 4323.6 4326.7 4250.3 4391.2 4400.4 4428.3 4444.6 4636.2 4724.4 4739.2 4805.0 4815.9 5015.8 5307.4 5336.6 5347.7 5356.7 1275.2 1311.3 1370.2 1506.0 1508.9 929.6 1077.2 1099.3 1129.5 1134.9 4060.9 4510.4 4552.5 4617.4 4995.0 4686.5 4874.7 4941.1 5534.0 5603.2 4367.9 4661.8 4919.8 4937.4 5041.8 2461.5 2464.0 2824.2 2865.3 2870.3 4933.5 4951.7 5100.1 5235.6 5656.2 4817.8 4889.8 5616.7 5618.8 5680.4 4816.8 4861.5 5185.5 5522.5 5586.7 4839.0 5170.3 5278.2 5438.8 5465.8 4655.3 5186.6 5342.5 5402.2 5427.1 5194.9 5607.5 5645.9 5663.3 5677.2 3885.7 4083.4 4231.7 4304.7 4320.7 4583.6 4651.3 5280.2 5390.9 5556.9 4834.9 5034.8 5132.6 5317.8 5364.7 4956.2 5278.0 5283.8 5295.5 5304.7 4443.8 4451.3 4655.8 4793.0 5239.6 5107.1 5299.4 5314.5 5328.3 5333.2 4798.4 4862.9 4916.0 5184.7 5215.2 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:19975.05 Median:3564.11 stddev:28509.42 (799.9%) stddev-sans-first:28552.87 01:57:32 INFO - PROCESS | 1662 | Values: 755.3 760.1 789.3 803.1 818.2 15007.3 15786.5 15813.9 15816.1 15846.8 14461.7 14550.0 14616.5 14616.7 15086.7 908.1 929.7 929.9 969.1 976.5 956.2 963.2 971.6 972.4 972.8 868.6 1014.1 1017.0 1017.1 1017.5 1002.0 1003.8 1003.9 1004.1 1006.0 747.8 747.8 748.1 749.3 750.3 15608.5 15636.7 15647.5 16508.3 16724.6 13889.9 13943.7 14139.5 14139.7 14149.7 11706.7 11735.8 11881.7 11903.6 11966.8 3572.4 3617.1 3669.7 3687.9 3815.1 107659.0 107726.0 109244.0 109696.0 109782.0 10434.7 10448.7 10609.5 11049.7 11105.2 21820.6 22256.6 22304.7 22327.6 22411.6 6424.8 7508.7 7584.7 7673.0 7812.8 4271.1 4283.3 4352.8 4379.9 4431.0 3300.3 3481.0 3547.8 3555.8 3581.3 619.8 631.6 632.1 632.9 643.5 670.9 673.0 685.9 695.9 734.0 9703.9 10158.4 10309.5 10329.5 10453.2 676.3 692.5 716.5 737.8 738.0 4846.4 4958.8 4972.9 4999.2 5003.7 87568.0 88856.0 103336.0 105383.0 109993.0 64104.0 81304.0 85224.0 85260.1 86363.0 74481.0 76931.1 77212.6 77752.6 79243.6 8782.0 9523.9 9710.1 9722.6 9743.5 44743.6 45094.3 47100.5 49712.0 50407.5 51886.7 61439.4 64347.7 64967.4 67078.7 12394.0 12614.1 12712.9 12790.7 13118.7 13908.6 14585.6 14659.9 14668.1 15058.5 13029.7 13572.0 13812.7 13882.0 14173.6 13230.5 13386.2 13588.2 13780.2 14033.3 12191.8 13255.0 13381.7 13418.1 13525.5 9163.1 9479.7 9945.4 10047.2 10260.6 8698.6 10677.6 10708.7 11309.9 11473.8 01:57:32 INFO - PROCESS | 1662 | -------- Summary: end -------- 01:57:32 INFO - PROCESS | 1662 | 01:57:32 INFO - TEST-INFO | 1662: exit 0 01:57:32 INFO - TEST-OK | dromaeo_css | took 1120731ms 01:57:32 INFO - TEST-START | kraken 01:57:32 INFO - operating with platform_type : mac_ 01:57:32 INFO - Initialising browser for kraken test... 01:57:32 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpCAPT7d/profile http://localhost:49245/getInfo.html) 01:57:35 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 01:57:35 INFO - PROCESS | 1667 | colorDepth:24 01:57:35 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 01:57:35 INFO - PROCESS | 1667 | __metrics 01:57:35 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 01:57:35 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 01:57:35 INFO - TEST-INFO | 1667: exit 0 01:57:35 INFO - Browser initialized. 01:57:35 INFO - Running cycle 1/1 for kraken test... 01:57:35 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/tmpCAPT7d/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 01:57:47 INFO - PROCESS | 1669 | RSS: Main: 210690048 01:57:47 INFO - PROCESS | 1669 | 01:57:50 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection) 01:57:51 INFO - PROCESS | 1669 | RSS: Main: 264433664 01:57:51 INFO - PROCESS | 1669 | 01:57:54 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-dft) 01:57:54 INFO - PROCESS | 1669 | RSS: Main: 277872640 01:57:54 INFO - PROCESS | 1669 | 01:57:58 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-fft) 01:57:58 INFO - PROCESS | 1669 | RSS: Main: 374145024 01:57:58 INFO - PROCESS | 1669 | 01:58:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator) 01:58:01 INFO - PROCESS | 1669 | RSS: Main: 278061056 01:58:01 INFO - PROCESS | 1669 | 01:58:03 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur) 01:58:04 INFO - PROCESS | 1669 | RSS: Main: 280244224 01:58:04 INFO - PROCESS | 1669 | 01:58:07 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom) 01:58:07 INFO - PROCESS | 1669 | RSS: Main: 320167936 01:58:07 INFO - PROCESS | 1669 | 01:58:10 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate) 01:58:11 INFO - PROCESS | 1669 | RSS: Main: 321847296 01:58:11 INFO - PROCESS | 1669 | 01:58:14 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial) 01:58:14 INFO - PROCESS | 1669 | RSS: Main: 316952576 01:58:14 INFO - PROCESS | 1669 | 01:58:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox) 01:58:17 INFO - PROCESS | 1669 | RSS: Main: 296079360 01:58:17 INFO - PROCESS | 1669 | 01:58:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes) 01:58:19 INFO - PROCESS | 1669 | RSS: Main: 299208704 01:58:19 INFO - PROCESS | 1669 | 01:58:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm) 01:58:22 INFO - PROCESS | 1669 | RSS: Main: 302002176 01:58:22 INFO - PROCESS | 1669 | 01:58:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 01:58:26 INFO - PROCESS | 1669 | RSS: Main: 320520192 01:58:26 INFO - PROCESS | 1669 | 01:58:29 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 01:58:29 INFO - PROCESS | 1669 | RSS: Main: 314257408 01:58:29 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49245/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49245/tests/kraken/driver.html?testName=ai-astar) 01:58:31 INFO - PROCESS | 1669 | RSS: Main: 358912000 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | __start_tp_report 01:58:31 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 01:58:31 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 01:58:31 INFO - PROCESS | 1669 | |i|pagename|runs| 01:58:31 INFO - PROCESS | 1669 | |0;ai-astar;105;115;114;115;122;168;120;114;115;115 01:58:31 INFO - PROCESS | 1669 | |1;audio-beat-detection;146;103;109;140;109;109;107;107;127;108 01:58:31 INFO - PROCESS | 1669 | |2;audio-dft;196;263;203;181;184;197;261;207;185;171 01:58:31 INFO - PROCESS | 1669 | |3;audio-fft;82;63;61;89;62;77;84;84;76;61 01:58:31 INFO - PROCESS | 1669 | |4;audio-oscillator;88;68;86;70;68;69;81;68;68;82 01:58:31 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;86;87;89;88;88;87;88;87;88;105 01:58:31 INFO - PROCESS | 1669 | |6;imaging-darkroom;95;95;95;95;95;96;96;108;151;95 01:58:31 INFO - PROCESS | 1669 | |7;imaging-desaturate;83;86;84;84;84;84;85;86;86;84 01:58:31 INFO - PROCESS | 1669 | |8;json-parse-financial;63;61;61;59;64;62;63;65;64;63 01:58:31 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;55;63;63;65;61;60;60;59;59;60 01:58:31 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;82;81;96;93;80;81;85;84;86;83 01:58:31 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;147;139;135;136;135;137;140;139;181;153 01:58:31 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;163;164;164;164;165;164;166;165;164;173 01:58:31 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;58;62;84;65;65;64;65;63;64;63 01:58:31 INFO - PROCESS | 1669 | __end_tp_report 01:58:31 INFO - PROCESS | 1669 | __start_cc_report 01:58:31 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2132 01:58:31 INFO - PROCESS | 1669 | __end_cc_report 01:58:31 INFO - PROCESS | 1669 | __startTimestamp1494061111620__endTimestamp 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | ------- Summary: start ------- 01:58:31 INFO - PROCESS | 1669 | Number of tests: 14 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:120.30 Median:115.00 stddev:17.33 (15.1%) stddev-sans-first:17.48 01:58:31 INFO - PROCESS | 1669 | Values: 105.0 115.0 114.0 115.0 122.0 168.0 120.0 114.0 115.0 115.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:116.50 Median:109.00 stddev:15.41 (14.1%) stddev-sans-first:12.09 01:58:31 INFO - PROCESS | 1669 | Values: 146.0 103.0 109.0 140.0 109.0 109.0 107.0 107.0 127.0 108.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:204.80 Median:200.00 stddev:32.02 (16.0%) stddev-sans-first:33.80 01:58:31 INFO - PROCESS | 1669 | Values: 196.0 263.0 203.0 181.0 184.0 197.0 261.0 207.0 185.0 171.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:73.90 Median:79.50 stddev:11.08 (13.9%) stddev-sans-first:11.36 01:58:31 INFO - PROCESS | 1669 | Values: 82.0 63.0 61.0 89.0 62.0 77.0 84.0 84.0 76.0 61.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:75.50 stddev:8.38 (11.1%) stddev-sans-first:7.40 01:58:31 INFO - PROCESS | 1669 | Values: 88.0 68.0 86.0 70.0 68.0 69.0 81.0 68.0 68.0 82.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:88.00 stddev:5.58 (6.3%) stddev-sans-first:5.79 01:58:31 INFO - PROCESS | 1669 | Values: 86.0 87.0 89.0 88.0 88.0 87.0 88.0 87.0 88.0 105.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:102.10 Median:95.00 stddev:17.65 (18.6%) stddev-sans-first:18.53 01:58:31 INFO - PROCESS | 1669 | Values: 95.0 95.0 95.0 95.0 95.0 96.0 96.0 108.0 151.0 95.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:84.60 Median:84.50 stddev:1.07 (1.3%) stddev-sans-first:0.97 01:58:31 INFO - PROCESS | 1669 | Values: 83.0 86.0 84.0 84.0 84.0 84.0 85.0 86.0 86.0 84.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:62.50 Median:63.00 stddev:1.78 (2.8%) stddev-sans-first:1.88 01:58:31 INFO - PROCESS | 1669 | Values: 63.0 61.0 61.0 59.0 64.0 62.0 63.0 65.0 64.0 63.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:60.50 Median:60.50 stddev:2.76 (4.6%) stddev-sans-first:2.09 01:58:31 INFO - PROCESS | 1669 | Values: 55.0 63.0 63.0 65.0 61.0 60.0 60.0 59.0 59.0 60.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:85.10 Median:84.50 stddev:5.34 (6.3%) stddev-sans-first:5.55 01:58:31 INFO - PROCESS | 1669 | Values: 82.0 81.0 96.0 93.0 80.0 81.0 85.0 84.0 86.0 83.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:144.20 Median:139.50 stddev:14.14 (10.1%) stddev-sans-first:14.96 01:58:31 INFO - PROCESS | 1669 | Values: 147.0 139.0 135.0 136.0 135.0 137.0 140.0 139.0 181.0 153.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:165.20 Median:164.50 stddev:2.86 (1.7%) stddev-sans-first:2.92 01:58:31 INFO - PROCESS | 1669 | Values: 163.0 164.0 164.0 164.0 165.0 164.0 166.0 165.0 164.0 173.0 01:58:31 INFO - PROCESS | 1669 | 01:58:31 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.30 Median:64.50 stddev:6.90 (10.7%) stddev-sans-first:6.79 01:58:31 INFO - PROCESS | 1669 | Values: 58.0 62.0 84.0 65.0 65.0 64.0 65.0 63.0 64.0 63.0 01:58:31 INFO - PROCESS | 1669 | -------- Summary: end -------- 01:58:31 INFO - PROCESS | 1669 | 01:58:32 INFO - TEST-INFO | 1669: exit 0 01:58:32 INFO - TEST-OK | kraken | took 59919ms 01:58:32 INFO - SUITE-END | took 1180s 01:58:32 INFO - Completed test suite (00:19:40) 01:58:32 INFO - javascript benchmark 01:58:32 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7565.06433751254, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3165.715085642292, 3371.3425881953, 3398.1844357354225, 3399.881004164855, 3447.4828775683645, 5831.970840145798, 5851.268591426072, 5863.501602363794, 5866.73599688014, 5925.903707814057, 5859.736311865966, 5917.467427931485, 5924.763009479627, 5934.703264836762, 5937.762489500415, 5292.761825717842, 5314.521693047625, 5366.597505187107, 5400.621956463049, 5434.619576629638, 5240.8689782755355, 5244.108501554745, 5417.945820541803, 5418.160185171305, 5427.0773968425265, 5270.367555893279, 5311.389190243113, 5312.4687531246955, 5314.468553144674, 5327.946720532803, 5302.575793936495, 5303.7348132593215, 5304.734763261821, 5322.148456246982, 5325.467453254663, 5138.332016837825, 5265.104932161504, 5266.631335806495, 5270.998510283034, 5312.734363281859, 5860.120981852713, 5863.0, 5868.354481007086, 5870.530357571427, 5875.559333049996, 6213.782517611862, 6238.251409830804, 6243.65659888706, 6244.5316601254635, 6256.6871656417, 6258.593191442542, 6290.276618188921, 6291.465225455841, 6310.179676642053, 6345.841353966188, 5142.125838607446, 6010.429009244115, 6025.3372129065765, 6029.276486821565, 6068.696565171768, 6389.6805159741825, 6418.19772528434, 6426.678666066678, 6438.484921206339, 6440.0661904023655, 6118.847028824315, 6133.4264757247465, 6177.4749146323065, 6238.75044998195, 6271.435570798559, 6320.62076275425, 6325.5255855811, 6333.778317758856, 6349.206349206349, 6349.714262858182, 6330.556861019684, 6350.269718982284, 6355.586886852339, 6371.585846919936, 6389.265234498, 6177.382261773787, 6207.751689932443, 6281.78013769525, 6304.148939893055, 6332.303446620868, 5042.873928151826, 5063.215201643751, 5114.744262786846, 5118.872028199325, 5120.513551212629, 4515.503294637587, 4535.931961020588, 4547.47704014036, 4559.293309537027, 4569.040501494657, 5829.941700583025, 5881.5000724938, 5913.113033045078, 5917.053271476543, 5928.970355148197, 6242.219722534684, 6314.463270621956, 6351.079093531412, 6356.0, 6363.140975968185, 5879.647221166743, 5882.441168089024, 5910.379410161957, 5914.4381283777975, 5915.37888521699, 5432.158015507603, 5529.917051244234, 5604.047311956977, 5605.7197140142825, 5625.915611265835, 5892.263467066617, 6248.9062664059165, 6447.4197322241225, 6501.6424096673245, 6520.347965203442, 6260.154879091265, 6301.0, 6319.494440444845, 6324.272708638566, 6337.6197428154455, 6516.348365163446, 6533.771318003847, 6546.279909210079, 6553.803385898431, 6562.573432726857, 6314.305426403183, 6363.490920726424, 6365.745370185141, 6372.681365931778, 6376.713047912948, 5301.708406037705, 6067.696615169136, 6291.433770960635, 6317.399847014526, 6324.146240257691, 6208.596441231305, 6246.531510136849, 6334.778282760081, 6343.651099189589, 6345.619262844059, 6264.404881536247, 6304.5271604628915, 6327.588706734047, 6336.7148478319505, 6337.081123237197, 6178.536609754196, 6179.907301390393, 6200.348963358782, 6205.5345849060595, 6219.067139929047, 5506.311711036121, 6098.237720284965, 6137.693115344304, 6153.569250152446, 6233.065040244, 5893.322267939242, 5893.793717219876, 6121.361519882331, 6139.508839292936, 6155.692215389302], "value": 5816.064990965222, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [792.3304807437715, 812.8821320908467, 866.7226487523991, 879.5558243087244, 966.6568368229283, 28268.434631307387, 37520.87437376883, 37640.62359376405, 38811.22377552447, 38846.22307553847, 37263.25473490536, 37457.625423745754, 37465.81267093668, 37517.812410937906, 37612.68355607554, 7327.157376901671, 7495.175530691647, 7702.152763196058, 7846.215378462165, 11042.3374597524, 11029.61396351128, 11155.163362747786, 11169.385683787388, 11181.161412894025, 11198.552057917674, 11159.609413670523, 11162.386068766215, 11176.776464470706, 11186.496607652674, 11223.102151827876, 11115.277506962062, 11141.164412669043, 11144.498497567629, 11145.888541114648, 11161.330320180734, 10865.29375590592, 10913.83629245562, 10920.344779313187, 10929.289596176222, 10935.617253396173, 31915.202119946956, 38619.0, 38791.41812872809, 38826.0, 38869.0, 3143.748500120008, 3803.581606023335, 3823.4073718573386, 3836.1943991761486, 3854.7687138771485, 26230.73769262302, 26892.731072689414, 28147.15558533247, 28640.283992900135, 28807.711922880713, 3143.858526366319, 3173.2701478660038, 3201.695838895301, 3275.394052100369, 3277.901662950115, 346342.0, 348094.0, 348175.25912370527, 348581.0, 348623.0, 41604.0, 42473.57526424758, 44396.77801611036, 52223.47776522262, 52450.47549524456, 53893.73053134788, 54195.0, 55937.0, 56370.718146409825, 56474.0, 14777.704445911237, 14836.3323650436, 14854.70290594182, 14858.702825943421, 14897.851021489863, 12785.80821287663, 12790.9360453199, 12809.0, 12847.486100555872, 12890.582035976035, 13357.732845343038, 13369.197848129143, 13389.129706569236, 13401.530946417024, 13556.915446764235, 5885.911711324334, 5939.851503712442, 5955.34491205967, 5968.134620480006, 5969.522438204933, 1065.8188108021654, 1090.2259395828892, 1095.1676725688376, 1119.6193294279983, 1164.551647615674, 26040.479190416085, 26273.39174739639, 26654.733452665612, 26766.46467070648, 26893.462130757667, 1067.3649178738638, 1154.5554961339944, 1207.5773479282357, 1252.956146534867, 1254.8368712067372, 13698.726025479635, 13708.971827112808, 13859.514916977858, 13953.930230348784, 14019.929900350433, 486913.0, 488846.0, 489405.0, 490352.0, 490772.0, 55036.72481637566, 55480.44519554834, 55569.61075973133, 55599.16601250984, 55624.609384764895, 1407.90848594841, 1442.816890150067, 1454.7599646058281, 1459.569427019032, 1469.808924839764, 21667.674984875553, 25235.369115772253, 26179.607305890808, 26206.0, 26272.474550509265, 46180.845478863295, 46591.53408465872, 46684.066318672754, 46720.0, 46843.76578117088, 424873.0, 428135.0, 428148.0, 428519.0, 429496.0, 28612.42775144527, 29126.56310155392, 29222.0, 33859.661403386635, 35710.928672139875, 42654.573454266305, 43135.35296970611, 44151.0, 44727.0, 44759.0, 29470.115896523137, 30303.69696303097, 30841.383172336875, 30993.69006309998, 31092.0, 30071.699283007765, 30078.699213008465, 30468.695313046584, 30894.0, 31148.22129446782, 32582.83708581442, 36207.275854482235, 36356.0, 36361.0, 36414.63585364218, 25435.982560698107, 26419.339516512242, 28242.011529596366, 28858.27854303659, 29122.27194320159, 21848.672269915645, 22443.88778056099, 22819.429514261614, 23109.88445057764, 23209.419764506023], "value": 18749.511286958423, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [384.2507111133291, 408.6097776623323, 417.42603919610536, 419.3939757051061, 443.1668463289016, 11185.944070279647, 14571.27143642819, 20470.795292047074, 20601.27895523657, 20973.47566310843, 18318.633627327446, 21814.127434902624, 22094.88952555235, 22685.659715104288, 22735.31794046177, 6433.485321174307, 6437.678116094176, 6558.8683677804665, 6558.8688226235445, 6640.236372817116, 4054.2296963576828, 6472.2880483147055, 6585.407313341799, 6605.57063790852, 6608.801735947929, 4298.849540266091, 4384.649228061763, 7357.668904899292, 7430.777076687681, 7457.81355466115, 7234.204237533867, 7255.419566434673, 7436.293552112541, 7437.367823734989, 7479.962600186991, 7181.425485961111, 7204.747833825843, 7209.855802883965, 7231.746888858917, 7240.058792357013, 24329.9051542681, 25531.106411275516, 25667.614985775228, 26005.609915851277, 26318.342041448926, 2794.483020641188, 3133.6082989626298, 3154.3691261747513, 3177.841107944617, 3205.182678416989, 14918.328675209641, 15851.207439627973, 16257.187140643038, 16952.491425257263, 17014.65970680592, 2038.2967876082876, 2072.5647614001077, 2078.906449209789, 2084.3017589107667, 2097.1716172112083, 150329.0, 155474.0, 156441.0, 156457.0, 157548.0, 15842.683146337009, 15968.36126554948, 19884.204631814853, 20147.29484468066, 20177.697334539993, 30649.693503064686, 30751.23121921925, 30779.692203077684, 30781.076567703003, 30807.22981925425, 10011.149052330633, 10108.39349639024, 10148.137408320228, 10258.384496930208, 10380.32527885685, 7144.214136444987, 8551.315894728406, 8573.356998225157, 8577.356698247657, 8707.12928707137, 9032.32257580671, 9149.771255718528, 9203.539823008823, 9317.72046838596, 9338.859917101248, 3839.462475253467, 3902.180542086137, 3930.4693866328253, 3938.9803050985133, 3944.599667118132, 857.352698712468, 939.5490164720835, 1006.8187726209206, 1026.9075783179549, 1083.8807731149568, 18960.715589266172, 19773.406797796088, 21703.02336394866, 22071.558568828532, 22160.667589986162, 833.4332653795356, 914.6478605736839, 928.2110206324705, 940.6801687426308, 953.594722243044, 10817.242793004414, 11149.99650031501, 11292.887071129348, 11445.141614378956, 11644.650660480029, 788962.0, 797856.0, 800174.0, 808702.0, 812795.0, 112357.0, 147209.0, 151188.0, 164804.7039059212, 167638.0, 1159.2701776750837, 1187.145255416099, 1222.6148763139583, 1226.8834460726216, 1232.3653318540958, 19684.01579921027, 19785.703214451507, 20218.393448196577, 20268.493287667927, 20282.695759563914, 65294.67352663206, 65353.67323163354, 65548.37806487878, 65835.0, 66025.0, 90085.0, 92354.53822731112, 92892.53553732188, 97134.0, 100308.99691002996, 11074.283486059674, 11532.94233528827, 11656.475458604213, 11744.119191060876, 11910.34493102853, 12603.68490787701, 12856.935715321737, 12884.162529435556, 13163.934180329037, 13203.801942971055, 11402.460667809739, 11428.771424571629, 11556.804207200605, 11618.70953226176, 11812.232204906653, 10480.68557943263, 10806.513706883052, 10839.0, 11158.944205278922, 11175.776484470103, 11072.224944253825, 11299.717507062389, 11387.943060284646, 11430.657080287605, 11454.713632159262, 11462.598809041643, 11482.82775758381, 11570.016548593463, 11602.129840262183, 11811.586594469152, 10946.124310055337, 11342.092632589538, 11565.653030409101, 11570.305781653127, 11634.76730465403], "value": 11506.655008464479, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1547.2650491016761, 1555.6266496040953, 1575.6218507558183, 1578.4633224703603, 1584.207896051974, 10335.069843714064, 10897.128229741622, 10907.781844363108, 10911.0, 10916.235863489559, 10122.34204776689, 10433.42616156113, 10505.21210909181, 10545.156387489002, 10629.52167152478, 2317.768223177681, 2349.3304408243653, 2350.764923507644, 2354.0113152476, 2356.1400088967575, 2267.1044937249776, 2338.9298321050396, 2353.9293821185393, 2360.4216966843146, 2362.126013375054, 2284.2804516577226, 2338.053088499152, 2347.7182738071456, 2348.612478940973, 2352.482453860148, 2103.7685854555984, 2306.8269879759087, 2325.232673217834, 2330.8368414211013, 2337.7662233776655, 1839.8804077734903, 2082.1671331467383, 2231.2079211879873, 2232.7767223277706, 2239.7648246934164, 10633.734156646145, 10731.141508679288, 10746.247762656618, 10764.946175269073, 10782.838257426145, 5371.865703357447, 5539.639923404966, 5540.418256083093, 5550.555955523549, 5556.91664625031, 3217.951730724041, 3316.1875340541724, 3494.3011397720556, 3496.5454490916272, 3498.2129020970333, 1116.7040734205443, 1117.8546788917388, 1122.4948773052158, 1135.9716007099805, 1169.228309315856, 11076.723081922935, 13563.321833908265, 13869.791953120812, 14893.957422980397, 14937.402503899832, 4342.392065110887, 4507.887302817456, 4535.342375355555, 4537.251353526697, 4576.794044268016, 11377.943110284396, 11401.087912966943, 11408.657740267805, 11421.029212517029, 11429.714257143638, 4722.811087556528, 4929.580985616191, 5437.8368648940595, 5441.537469315073, 5444.891102177934, 4868.756562171877, 5084.720340381315, 5117.053345131163, 5127.743612819344, 5128.1794912813775, 3590.335787879251, 3737.121776382548, 3760.2103558252525, 3807.8286477108595, 3821.3312670283144, 964.7636329099415, 964.9662261820943, 972.7081875437341, 978.2076518020284, 981.9459929703792, 789.8617741895146, 799.1688643810372, 812.29736278119, 819.8893149424871, 821.8685010398308, 6178.876422471525, 6225.968870155621, 6408.423241908251, 6408.487321014401, 6416.711247993851, 831.98336033279, 835.8871552340478, 929.0755698080453, 975.5707488704948, 1010.4998025977042, 3962.465067215889, 4090.877273681794, 4151.792410379529, 4170.70805043644, 4243.936340954888, 9225.40034897743, 13222.471101155847, 13233.139845909986, 13248.867511324956, 13262.336883155804, 13142.211467311992, 13160.210387376788, 13177.604671859859, 13197.868021319857, 13282.933585332205, 9581.233501319877, 9820.4107753535, 9865.654702085392, 10027.899721002843, 10063.49682515886, 5487.890242195213, 6279.089532017923, 6300.464460520907, 6387.457066149429, 6454.0, 10853.945730271298, 11571.305721656727, 12080.21478603923, 12214.938925305316, 12228.75542489163, 11031.39327336972, 11386.886131138583, 11544.960953513888, 12222.511099555919, 12229.75540489203, 6880.827979300558, 7995.800104997422, 8232.300254478198, 8281.0, 8452.577371131296, 10085.596576136872, 10096.848547271944, 10223.488825558841, 10224.079832815083, 10237.436940968319, 8539.359548033797, 8739.475631462132, 8790.033096359268, 8824.3381746368, 8831.69089081879, 8381.0, 8399.28606068491, 8441.451305665112, 8497.532635705096, 8507.191816777644, 7239.239879812544, 7745.496542724705, 7771.4559980800805, 7817.0, 7822.804429889071, 5608.158776183605, 5694.544436445157, 5736.397678243724, 5823.330317013598, 5863.120531920246, 5752.13717942312, 5781.277340332458, 5785.392533783892, 5842.0, 5949.851253718692], "value": 4897.82932609695, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [360.9133807886107, 373.5610657477465, 374.6272458903388, 378.11521040764615, 382.96361845624654, 4745.477997420286, 5043.621728370373, 5122.718250496221, 5160.612954028444, 5166.424240606618, 4476.6418686505085, 4964.453910069889, 4975.179095449246, 4996.1756310208775, 5056.938043010963, 3559.4660800879947, 3645.0, 3959.198564653076, 4162.793719328761, 4185.727927684691, 3715.8262416486386, 4153.68847336451, 4177.498700155986, 4185.623293903548, 4285.742855428669, 3675.9339791460443, 4087.7547347159116, 4179.331306990882, 4202.642775364083, 4242.172776308621, 3610.339243947787, 3797.1076796952693, 3999.500062492174, 4151.647109995654, 4162.646175075122, 3238.611366636019, 3761.5298087739034, 3778.1310298631192, 3795.7532760370764, 3798.2213646202463, 4397.789613582071, 4643.326717625925, 4696.365990591261, 4848.5151484851585, 4916.877078073041, 3864.835692012482, 4050.189962007581, 4142.523609784853, 4348.760818155, 4767.976160119212, 4742.099001189763, 5092.770825312869, 5187.8184263550975, 5275.733823882279, 5292.814751483718, 4260.7656578888145, 4285.507166675841, 4314.633256173229, 4317.309230523101, 4317.525072242051, 4580.839670611513, 4750.477447480775, 5247.2653828464045, 5342.358916929993, 5605.691686957257, 3801.467794508771, 3942.704297177723, 4119.340905455173, 4323.567643235714, 4326.697131200785, 4250.341197114452, 4391.155714599812, 4400.44994375703, 4428.31361139024, 4444.644428445717, 4636.188666983265, 4724.4094488188975, 4739.156506096384, 4804.95195048052, 4815.91641880574, 5015.824446144367, 5307.363116425976, 5336.626436149432, 5347.731709376655, 5356.6608347913025, 1275.1647670775658, 1311.2984553263957, 1370.1778932640536, 1506.006036016224, 1508.8868334874928, 929.5584597316356, 1077.186724023373, 1099.274478843959, 1129.5199540195383, 1134.880837512066, 4060.939085913714, 4510.368548403226, 4552.453705555355, 4617.376654151713, 4995.0, 4686.461056978423, 4874.658773885794, 4941.135301322254, 5533.972330138404, 5603.243562119062, 4367.912641747211, 4661.766911654404, 4919.77860996256, 4937.382827146606, 5041.773120209599, 2461.54461424637, 2463.95072098557, 2824.180987513639, 2865.298001989526, 2870.296777289577, 4933.506649335038, 4951.722207784387, 5100.132977393852, 5235.554977826851, 5656.236408084938, 4817.759112044384, 4889.841217515555, 5616.69108199053, 5618.831435056954, 5680.415410987991, 4816.807327706852, 4861.538153875376, 5185.510954741745, 5522.475364840334, 5586.748596313176, 4838.959623680926, 5170.327857378667, 5278.152646573669, 5438.809641662523, 5465.781368745205, 4655.3249778782565, 5186.559142472781, 5342.465753424626, 5402.162664786964, 5427.1045277528765, 5194.909069095381, 5607.467290607386, 5645.9153112702525, 5663.292088488939, 5677.24653543023, 3885.6502914737807, 4083.387491876242, 4231.703780735319, 4304.708587423823, 4320.697551171514, 4583.564561366665, 4651.255739130032, 5280.234366016983, 5390.892182156256, 5556.888862222814, 4834.927476087792, 5034.798608055637, 5132.640715149903, 5317.8404647860625, 5364.678119312854, 4956.182229932165, 5278.017919875411, 5283.762230699551, 5295.47850679294, 5304.681719096867, 4443.822247110079, 4451.265541185668, 4655.813767449264, 4792.976035119802, 5239.580833533384, 5107.0634385227895, 5299.417064122867, 5314.4951229633125, 5328.280682107866, 5333.240065753712, 4798.376211092535, 4862.90274194507, 4915.95084049155, 5184.733617820749, 5215.157739032161], "value": 4028.5260039825384, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [755.3051192902533, 760.0840986611134, 789.3487872505177, 803.1205829616571, 818.1940788323502, 15007.324670389831, 15786.526404207863, 15813.920930395361, 15816.051036937777, 15846.762298565516, 14461.710765784677, 14550.0, 14616.488422905173, 14616.707665846703, 15086.6982660347, 908.1372695938851, 929.6932012435914, 929.9070092990714, 969.0551712080735, 976.4775844922967, 956.2350119904083, 963.2390411574847, 971.5870754929163, 972.367960825462, 972.7713987212999, 868.6264906090379, 1014.06705830636, 1016.959321627134, 1017.0947856407803, 1017.5471914997829, 1002.0330381182122, 1003.8092762375198, 1003.9498025098752, 1004.1013293102706, 1005.9597616095348, 747.7794050755041, 747.7906186267853, 748.1209578745007, 749.3293502315452, 750.2909750285982, 15608.531744047697, 15636.687266254725, 15647.530574082683, 16508.25712842925, 16724.58188545284, 13889.861101388959, 13943.721125577431, 14139.505117320845, 14139.717205655828, 14149.71700565983, 11706.707332316675, 11735.823962640567, 11881.702957426047, 11903.642890713292, 11966.760664786743, 3572.4284114541547, 3617.113807117273, 3669.7247706422127, 3687.9262414751556, 3815.08437974884, 107659.0, 107726.0, 109244.0, 109696.0, 109782.0, 10434.739131521772, 10448.738781530523, 10609.522571484302, 11049.723756905982, 11105.167112466597, 21820.563588728455, 22256.55486890253, 22304.665430018562, 22327.553448930932, 22411.551768964855, 6424.775132870327, 7508.7371941982865, 7584.658690358947, 7673.0, 7812.765617031499, 4271.10306835568, 4283.250431174532, 4352.7823608819435, 4379.861236078643, 4431.0, 3300.339932013559, 3480.965190348115, 3547.840347184383, 3555.7866528008403, 3581.3195492856275, 619.845038740315, 631.6399652198295, 632.137132813706, 632.9430351268408, 643.5495153392644, 670.9127813384229, 672.9596224226563, 685.9314068593201, 695.9025736396909, 733.9889901651479, 9703.905449638996, 10158.441285729208, 10309.484525773682, 10329.535170917326, 10453.216008799218, 676.3270545806954, 692.5221597097986, 716.5378330976491, 737.8044818123094, 737.9520331178456, 4846.3699719037, 4958.776855041532, 4972.850814475499, 4999.175136102503, 5003.72479513623, 87568.0, 88856.0, 103335.96664033414, 105383.0, 109993.0, 64104.0, 81304.0, 85224.0, 85260.14739852646, 86363.0, 74481.0, 76931.07672308237, 77212.61393692995, 77752.61123694346, 79243.60378198187, 8781.956090219508, 9523.904760952579, 9710.077542633438, 9722.611095556098, 9743.464109473764, 44743.55256447524, 45094.32358514691, 47100.528994710985, 49712.0, 50407.4877753668, 51886.740566296925, 61439.38560614337, 64347.678261608395, 64967.35032649613, 67078.66460667666, 12394.0, 12614.058256297663, 12712.872871271169, 12790.74418511606, 13118.672033199246, 13908.582742517741, 14585.635359116108, 14659.853401465849, 14668.119912805267, 15058.54824355271, 13029.739405211652, 13572.04995650295, 13812.654683632989, 13881.958853085856, 14173.574792756233, 13230.47078116903, 13386.19682819034, 13588.1847089171, 13780.242086685328, 14033.29833508341, 12191.817122742988, 13255.0, 13381.66545836362, 13418.060735748404, 13525.458981640624, 9163.083691630784, 9479.715608531755, 9945.353566881224, 10047.246456515644, 10260.640877569249, 8698.565071746514, 10677.572897084341, 10708.678739637824, 11309.886901130883, 11473.827892581785], "value": 7570.292838973495, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1449.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 115.0, 114.0, 115.0, 122.0, 168.0, 120.0, 114.0, 115.0, 115.0], "value": 120.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 103.0, 109.0, 140.0, 109.0, 109.0, 107.0, 107.0, 127.0, 108.0], "value": 116.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 263.0, 203.0, 181.0, 184.0, 197.0, 261.0, 207.0, 185.0, 171.0], "value": 204.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 63.0, 61.0, 89.0, 62.0, 77.0, 84.0, 84.0, 76.0, 61.0], "value": 73.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 68.0, 86.0, 70.0, 68.0, 69.0, 81.0, 68.0, 68.0, 82.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 89.0, 88.0, 88.0, 87.0, 88.0, 87.0, 88.0, 105.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 96.0, 96.0, 108.0, 151.0, 95.0], "value": 102.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 84.0, 84.0, 84.0, 84.0, 85.0, 86.0, 86.0, 84.0], "value": 84.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 61.0, 61.0, 59.0, 64.0, 62.0, 63.0, 65.0, 64.0, 63.0], "value": 62.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 63.0, 63.0, 65.0, 61.0, 60.0, 60.0, 59.0, 59.0, 60.0], "value": 60.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 96.0, 93.0, 80.0, 81.0, 85.0, 84.0, 86.0, 83.0], "value": 85.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 139.0, 135.0, 136.0, 135.0, 137.0, 140.0, 139.0, 181.0, 153.0], "value": 144.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 164.0, 164.0, 164.0, 165.0, 164.0, 166.0, 165.0, 164.0, 173.0], "value": 165.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 62.0, 84.0, 65.0, 65.0, 64.0, 65.0, 63.0, 64.0, 63.0], "value": 65.3, "unit": "score"}]}]} 01:58:32 INFO - Return code: 0 01:58:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 01:58:32 ERROR - # TBPL SUCCESS # 01:58:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 01:58:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 01:58:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 01:58:32 INFO - 2017-05-06 01:58:32.954 screenresolution[1673:14123] starting screenresolution argv=screenresolution get 01:58:32 INFO - 2017-05-06 01:58:32.956 screenresolution[1673:14123] Display 0: 1600x1200x32@60 01:58:32 INFO - 2017-05-06 01:58:32.962 screenresolution[1674:14127] starting screenresolution argv=screenresolution list 01:58:32 INFO - Available Modes on Display 0 01:58:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 01:58:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 01:58:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 01:58:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 01:58:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 01:58:33 INFO - Intel Iris: 01:58:33 INFO - Chipset Model: Intel Iris 01:58:33 INFO - Type: GPU 01:58:33 INFO - Bus: Built-In 01:58:33 INFO - VRAM (Dynamic, Max): 1536 MB 01:58:33 INFO - Vendor: Intel (0x8086) 01:58:33 INFO - Device ID: 0x0a2e 01:58:33 INFO - Revision ID: 0x0009 01:58:33 INFO - Displays: 01:58:33 INFO - Display: 01:58:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 01:58:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 01:58:33 INFO - Display Serial Number: 1600x1200 60 01:58:33 INFO - Main Display: Yes 01:58:33 INFO - Mirror: Off 01:58:33 INFO - Online: Yes 01:58:33 INFO - Rotation: Supported 01:58:33 INFO - Return code: 0 01:58:33 INFO - Running post-action listener: _resource_record_post_action 01:58:33 INFO - [mozharness: 2017-05-06 08:58:33.200387Z] Finished run-tests step (success) 01:58:33 INFO - Running post-run listener: _resource_record_post_run 01:58:33 INFO - Total resource usage - Wall time: 1202s; CPU: 54.0%; Read bytes: 32967680; Write bytes: 514876416; Read time: 194; Write time: 1605 01:58:33 INFO - TinderboxPrint: CPU usage
53.8% 01:58:33 INFO - TinderboxPrint: I/O read bytes / time
32,967,680 / 194 01:58:33 INFO - TinderboxPrint: I/O write bytes / time
514,876,416 / 1,605 01:58:33 INFO - TinderboxPrint: CPU idle
2,222.9 (46.2%) 01:58:33 INFO - TinderboxPrint: CPU system
953.9 (19.8%) 01:58:33 INFO - TinderboxPrint: CPU user
1,632.8 (33.9%) 01:58:33 INFO - TinderboxPrint: Swap in / out
251,150,336 / 0 01:58:33 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 200770048; Write bytes: 204265472; Read time: 15783; Write time: 438 01:58:33 INFO - run-tests - Wall time: 1182s; CPU: 54.0%; Read bytes: 31972352; Write bytes: 300690432; Read time: 181; Write time: 1133 01:58:33 INFO - Running post-run listener: _upload_blobber_files 01:58:33 INFO - Blob upload gear active. 01:58:33 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 01:58:33 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 01:58:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 01:58:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 01:58:34 INFO - (blobuploader) - INFO - Open directory for files ... 01:58:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 01:58:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 01:58:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:35 INFO - (blobuploader) - INFO - Done attempting. 01:58:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 01:58:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:36 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 01:58:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:36 INFO - (blobuploader) - INFO - Done attempting. 01:58:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 01:58:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:38 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 01:58:38 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:38 INFO - (blobuploader) - INFO - Done attempting. 01:58:38 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 01:58:38 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 01:58:38 INFO - (blobuploader) - INFO - Uploading, attempt #1. 01:58:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 01:58:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 01:58:40 INFO - (blobuploader) - INFO - Done attempting. 01:58:40 INFO - (blobuploader) - INFO - Iteration through files over. 01:58:40 INFO - Return code: 0 01:58:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 01:58:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 01:58:40 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16e8582a541cfca480e12e837c27129cf2d962098ab94b855b3aaa3dfd41817a0c5639b1345f6257e38ed1bcd86b92eabb41972aa444441d9e5a85012a9703c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5471059078883e4addc777c9d88a8af838ef2d8b3a9317f9c213440c45033f87051fd57fac547fe31d2b8c2fb218cdc9e512f45101798ee777741742611c4e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1854b8af81e510a4808c1e2381cf72ec2ad958cde79a59be597d888342d2999eca2f1c579f376962e9e2d94457fd1439082d937d689f8df0f99e5edc064fec49", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f582225ea57bb7f22596a3f4919a56f7bf9869a6d4bb4bd025bd9dec83403d8eeac3dc0ba1e5b301bb66f18a91d66f981ab9d3b6bc2e0224b2e737098eafe2c"} 01:58:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 01:58:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 01:58:40 INFO - Contents: 01:58:40 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16e8582a541cfca480e12e837c27129cf2d962098ab94b855b3aaa3dfd41817a0c5639b1345f6257e38ed1bcd86b92eabb41972aa444441d9e5a85012a9703c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5471059078883e4addc777c9d88a8af838ef2d8b3a9317f9c213440c45033f87051fd57fac547fe31d2b8c2fb218cdc9e512f45101798ee777741742611c4e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1854b8af81e510a4808c1e2381cf72ec2ad958cde79a59be597d888342d2999eca2f1c579f376962e9e2d94457fd1439082d937d689f8df0f99e5edc064fec49", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f582225ea57bb7f22596a3f4919a56f7bf9869a6d4bb4bd025bd9dec83403d8eeac3dc0ba1e5b301bb66f18a91d66f981ab9d3b6bc2e0224b2e737098eafe2c"} 01:58:40 INFO - Running post-run listener: copy_logs_to_upload_dir 01:58:40 INFO - Copying logs to upload dir... 01:58:40 INFO - mkdir: /builds/slave/test/build/upload/logs 01:58:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1278.234683 ========= master_lag: 0.44 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2017-05-06 01:58:41.140987) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-06 01:58:41.144858) ========= 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.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16e8582a541cfca480e12e837c27129cf2d962098ab94b855b3aaa3dfd41817a0c5639b1345f6257e38ed1bcd86b92eabb41972aa444441d9e5a85012a9703c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5471059078883e4addc777c9d88a8af838ef2d8b3a9317f9c213440c45033f87051fd57fac547fe31d2b8c2fb218cdc9e512f45101798ee777741742611c4e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1854b8af81e510a4808c1e2381cf72ec2ad958cde79a59be597d888342d2999eca2f1c579f376962e9e2d94457fd1439082d937d689f8df0f99e5edc064fec49", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f582225ea57bb7f22596a3f4919a56f7bf9869a6d4bb4bd025bd9dec83403d8eeac3dc0ba1e5b301bb66f18a91d66f981ab9d3b6bc2e0224b2e737098eafe2c"} build_url:https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011274 build_url: 'https://queue.taskcluster.net/v1/task/Sqp7s4IVTuStADPRgcQX9Q/artifacts/public/build/firefox-54.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/16e8582a541cfca480e12e837c27129cf2d962098ab94b855b3aaa3dfd41817a0c5639b1345f6257e38ed1bcd86b92eabb41972aa444441d9e5a85012a9703c9", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/5471059078883e4addc777c9d88a8af838ef2d8b3a9317f9c213440c45033f87051fd57fac547fe31d2b8c2fb218cdc9e512f45101798ee777741742611c4e4b", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/1854b8af81e510a4808c1e2381cf72ec2ad958cde79a59be597d888342d2999eca2f1c579f376962e9e2d94457fd1439082d937d689f8df0f99e5edc064fec49", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/8f582225ea57bb7f22596a3f4919a56f7bf9869a6d4bb4bd025bd9dec83403d8eeac3dc0ba1e5b301bb66f18a91d66f981ab9d3b6bc2e0224b2e737098eafe2c"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-06 01:58:41.180389) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:58:41.180699) ========= 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.WiyF59NIUA/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.bJhPxZQVML/Listeners TMPDIR=/var/folders/qw/0zz61d_n6_s8yy9116jp1djc00000w/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.005195 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-06 01:58:41.229214) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-06 01:58:41.229518) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-06 01:58:41.229818) ========= ========= Total master_lag: 0.61 =========