builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0215 starttime: 1488041275.76 results: success (0) buildid: 20170225072608 builduid: 40f77ff56d8d4c50a6f6a902e2d58504 revision: 3629511a266bc964961afe690cc841ded9c3dc66 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.756730) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.757216) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.757523) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.786053) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.786528) ========= 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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-02-25 08:47:55-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.8M=0.001s 2017-02-25 08:47:55 (17.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.935017) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.935341) ========= 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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.026590 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:55.984838) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-25 08:47:55.985228) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 3629511a266bc964961afe690cc841ded9c3dc66 --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 3629511a266bc964961afe690cc841ded9c3dc66 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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-02-25 08:47:56,134 truncating revision to first 12 chars 2017-02-25 08:47:56,134 Setting DEBUG logging. 2017-02-25 08:47:56,134 attempt 1/10 2017-02-25 08:47:56,134 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/3629511a266b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-25 08:47:56,906 unpacking tar archive at: mozilla-aurora-3629511a266b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.050011 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-25 08:47:57.058771) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:57.059061) ========= script_repo_revision: 3629511a266bc964961afe690cc841ded9c3dc66 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:57.059471) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:57.059812) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-25 08:47:57.080061) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-25 08:47:57.080440) ========= /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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 08:47:57 INFO - MultiFileLogger online at 20170225 08:47:57 in /builds/slave/test 08:47:57 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 08:47:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:47:57 INFO - {'append_to_log': False, 08:47:57 INFO - 'base_work_dir': '/builds/slave/test', 08:47:57 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:47:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:47:57 INFO - 'branch': 'Mozilla-Aurora', 08:47:57 INFO - 'buildbot_json_path': 'buildprops.json', 08:47:57 INFO - 'config_files': ('talos/mac_config.py',), 08:47:57 INFO - 'default_actions': ('clobber', 08:47:57 INFO - 'read-buildbot-config', 08:47:57 INFO - 'download-and-extract', 08:47:57 INFO - 'populate-webroot', 08:47:57 INFO - 'create-virtualenv', 08:47:57 INFO - 'install', 08:47:57 INFO - 'run-tests'), 08:47:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:47:57 INFO - 'download_minidump_stackwalk': True, 08:47:57 INFO - 'download_symbols': 'ondemand', 08:47:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:47:57 INFO - 'tooltool.py': '/tools/tooltool.py', 08:47:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:47:57 INFO - '/tools/misc-python/virtualenv.py')}, 08:47:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:47:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:47:57 INFO - 'installer_path': 'installer.exe', 08:47:57 INFO - 'log_level': 'info', 08:47:57 INFO - 'log_name': 'talos', 08:47:57 INFO - 'log_to_console': True, 08:47:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:47:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:47:57 INFO - 'opt_config_files': (), 08:47:57 INFO - 'pip_index': False, 08:47:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:47:57 INFO - 'cmd': ('bash', 08:47:57 INFO - '-c', 08:47:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:47:57 INFO - 'enabled': True, 08:47:57 INFO - 'halt_on_failure': False, 08:47:57 INFO - 'name': 'check_screen_resolution'},), 08:47:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:47:57 INFO - 'cmd': ('bash', 08:47:57 INFO - '-c', 08:47:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:47:57 INFO - 'enabled': True, 08:47:57 INFO - 'halt_on_failure': False, 08:47:57 INFO - 'name': 'check_screen_resolution'},), 08:47:57 INFO - 'run_cmd_checks_enabled': True, 08:47:57 INFO - 'sps_profile': False, 08:47:57 INFO - 'sps_profile_interval': 0, 08:47:57 INFO - 'suite': 'dromaeojs', 08:47:57 INFO - 'system_bits': '32', 08:47:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:47:57 INFO - 'title': 't-yosemite-r7-0215', 08:47:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:47:57 INFO - 'use_talos_json': True, 08:47:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:47:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:47:57 INFO - 'work_dir': 'build'} 08:47:57 INFO - [mozharness: 2017-02-25 16:47:57.334106Z] Running clobber step. 08:47:57 INFO - Running pre-action listener: _resource_record_pre_action 08:47:57 INFO - Running main action method: clobber 08:47:57 INFO - rmtree: /builds/slave/test/build 08:47:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:47:59 INFO - Running post-action listener: _resource_record_post_action 08:47:59 INFO - [mozharness: 2017-02-25 16:47:59.818556Z] Finished clobber step (success) 08:47:59 INFO - [mozharness: 2017-02-25 16:47:59.818816Z] Running read-buildbot-config step. 08:47:59 INFO - Running pre-action listener: _resource_record_pre_action 08:47:59 INFO - Running main action method: read_buildbot_config 08:47:59 INFO - Using buildbot properties: 08:47:59 INFO - { 08:47:59 INFO - "project": "", 08:47:59 INFO - "product": "firefox", 08:47:59 INFO - "script_repo_revision": "production", 08:47:59 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:47:59 INFO - "repository": "", 08:47:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:47:59 INFO - "buildid": "20170225072608", 08:47:59 INFO - "pgo_build": "False", 08:47:59 INFO - "basedir": "/builds/slave/test", 08:47:59 INFO - "buildnumber": 4, 08:47:59 INFO - "slavename": "t-yosemite-r7-0215", 08:47:59 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 08:47:59 INFO - "platform": "yosemite_r7", 08:47:59 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:47:59 INFO - "branch": "mozilla-aurora", 08:47:59 INFO - "repo_path": "releases/mozilla-aurora", 08:47:59 INFO - "revision": "3629511a266bc964961afe690cc841ded9c3dc66", 08:47:59 INFO - "stage_platform": "macosx64", 08:47:59 INFO - "builduid": "40f77ff56d8d4c50a6f6a902e2d58504", 08:47:59 INFO - "slavebuilddir": "test" 08:47:59 INFO - } 08:47:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:47:59 INFO - Running post-action listener: _resource_record_post_action 08:47:59 INFO - [mozharness: 2017-02-25 16:47:59.824709Z] Finished read-buildbot-config step (success) 08:47:59 INFO - [mozharness: 2017-02-25 16:47:59.824948Z] Running download-and-extract step. 08:47:59 INFO - Running pre-action listener: _resource_record_pre_action 08:47:59 INFO - Running main action method: download_and_extract 08:47:59 INFO - mkdir: /builds/slave/test/build/tests 08:47:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:47:59 INFO - trying https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:47:59 INFO - Downloading https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:47:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json'}, attempt #1 08:48:00 INFO - Downloaded 1199 bytes. 08:48:00 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:48:00 INFO - Using the following test package requirements: 08:48:00 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:48:00 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:48:00 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:48:00 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'jsshell-mac.zip'], 08:48:00 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:48:00 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:48:00 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:48:00 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:48:00 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:48:00 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:48:00 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:48:00 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:48:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:48:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:00 INFO - Fetch https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:48:01 INFO - Expected file size: 16357810 08:48:01 INFO - Obtained file size: 16357810 08:48:08 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip', u'firefox-53.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 08:48:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:48:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:48:08 INFO - Fetch https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:48:09 INFO - Expected file size: 16357810 08:48:09 INFO - Obtained file size: 16357810 08:48:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:48:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:48:15 INFO - Fetch https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:48:16 INFO - Expected file size: 14403472 08:48:16 INFO - Obtained file size: 14403472 08:48:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:16 INFO - trying https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:48:16 INFO - Downloading https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:48:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg'}, attempt #1 08:48:19 INFO - Downloaded 73928995 bytes. 08:48:19 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:48:19 INFO - mkdir: /builds/slave/test/properties 08:48:19 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:48:19 INFO - Writing to file /builds/slave/test/properties/build_url 08:48:19 INFO - Contents: 08:48:19 INFO - build_url:https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:48:19 INFO - Running post-action listener: _resource_record_post_action 08:48:19 INFO - Running post-action listener: set_extra_try_arguments 08:48:19 INFO - [mozharness: 2017-02-25 16:48:19.664457Z] Finished download-and-extract step (success) 08:48:19 INFO - [mozharness: 2017-02-25 16:48:19.664597Z] Running populate-webroot step. 08:48:19 INFO - Running pre-action listener: _resource_record_pre_action 08:48:19 INFO - Running main action method: populate_webroot 08:48:19 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:48:19 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:48:19 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:48:19 INFO - '--tppagecycles', 08:48:19 INFO - '7'], 08:48:19 INFO - 'tests': ['tsvgm']}}, 08:48:19 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:48:19 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:48:19 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:48:19 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:48:19 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:19 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:48:19 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['damp', 'tps']}, 08:48:19 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'tests': ['damp', 'tps']}, 08:48:19 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['dromaeo_dom']}, 08:48:19 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:48:19 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['basic_compositor_video']}, 08:48:19 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:48:19 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['a11yr', 08:48:19 INFO - 'ts_paint', 08:48:19 INFO - 'tpaint', 08:48:19 INFO - 'sessionrestore', 08:48:19 INFO - 'sessionrestore_no_auto_restore', 08:48:19 INFO - 'tabpaint']}, 08:48:19 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tsvgx', 08:48:19 INFO - 'tsvgr_opacity', 08:48:19 INFO - 'tart', 08:48:19 INFO - 'tscrollx', 08:48:19 INFO - 'cart', 08:48:19 INFO - 'tsvg_static']}, 08:48:19 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:48:19 INFO - 'tsvgr_opacity', 08:48:19 INFO - 'tart', 08:48:19 INFO - 'tscrollx', 08:48:19 INFO - 'cart', 08:48:19 INFO - 'tsvg_static']}, 08:48:19 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s'], 08:48:19 INFO - 'tests': ['tp5o']}, 08:48:19 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:48:19 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'talos_options': ['--disable-e10s', 08:48:19 INFO - '--xperf_path', 08:48:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:19 INFO - 'tests': ['tp5n']}, 08:48:19 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:48:19 INFO - 'talos_options': ['--xperf_path', 08:48:19 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:48:19 INFO - 'tests': ['tp5n']}}, 08:48:19 INFO - 'talos.zip': {'path': '', 08:48:19 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:48:19 INFO - Running post-action listener: _resource_record_post_action 08:48:19 INFO - [mozharness: 2017-02-25 16:48:19.682629Z] Finished populate-webroot step (success) 08:48:19 INFO - [mozharness: 2017-02-25 16:48:19.682769Z] Running create-virtualenv step. 08:48:19 INFO - Running pre-action listener: _resource_record_pre_action 08:48:19 INFO - Running main action method: create_virtualenv 08:48:19 INFO - Creating virtualenv /builds/slave/test/build/venv 08:48:19 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 08:48:19 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:48:19 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:48:19 INFO - Using real prefix '/tools/python27' 08:48:19 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:48:20 INFO - Installing distribute.............................................................................................................................................................................................done. 08:48:23 INFO - Installing pip.................done. 08:48:23 INFO - Return code: 0 08:48:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:48:23 INFO - Reading from file tmpfile_stdout 08:48:23 INFO - Output received: 08:48:23 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:48:23 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 08:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:23 INFO - 'HOME': '/Users/cltbld', 08:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:23 INFO - 'LOGNAME': 'cltbld', 08:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:23 INFO - 'MOZ_NO_REMOTE': '1', 08:48:23 INFO - 'NO_EM_RESTART': '1', 08:48:23 INFO - 'PAGER': '/bin/cat', 08:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:23 INFO - 'PWD': '/builds/slave/test', 08:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:23 INFO - 'SHELL': '/bin/bash', 08:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:23 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:23 INFO - 'USER': 'cltbld', 08:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:23 INFO - 'XPC_FLAGS': '0x0', 08:48:23 INFO - 'XPC_SERVICE_NAME': '0', 08:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:23 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 08:48:23 INFO - Cleaning up... 08:48:23 INFO - Return code: 0 08:48:23 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:23 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 08:48:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 08:48:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:23 INFO - 'HOME': '/Users/cltbld', 08:48:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:23 INFO - 'LOGNAME': 'cltbld', 08:48:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:23 INFO - 'MOZ_NO_REMOTE': '1', 08:48:23 INFO - 'NO_EM_RESTART': '1', 08:48:23 INFO - 'PAGER': '/bin/cat', 08:48:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:23 INFO - 'PWD': '/builds/slave/test', 08:48:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:23 INFO - 'SHELL': '/bin/bash', 08:48:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:23 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:23 INFO - 'USER': 'cltbld', 08:48:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:23 INFO - 'XPC_FLAGS': '0x0', 08:48:23 INFO - 'XPC_SERVICE_NAME': '0', 08:48:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:24 INFO - Downloading/unpacking psutil>=3.1.1 08:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:48:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:28 INFO - Installing collected packages: psutil 08:48:28 INFO - Running setup.py install for psutil 08:48:28 INFO - building 'psutil._psutil_osx' extension 08:48:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 08:48:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:48:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:48:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 08:48:29 INFO - building 'psutil._psutil_posix' extension 08:48:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 08:48:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:48:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:48:29 INFO - ^ 08:48:29 INFO - 1 warning generated. 08:48:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 08:48:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:48:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:48:29 INFO - Successfully installed psutil 08:48:29 INFO - Cleaning up... 08:48:29 INFO - Return code: 0 08:48:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:29 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 08:48:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 08:48:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:29 INFO - 'HOME': '/Users/cltbld', 08:48:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:29 INFO - 'LOGNAME': 'cltbld', 08:48:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:29 INFO - 'MOZ_NO_REMOTE': '1', 08:48:29 INFO - 'NO_EM_RESTART': '1', 08:48:29 INFO - 'PAGER': '/bin/cat', 08:48:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:29 INFO - 'PWD': '/builds/slave/test', 08:48:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:29 INFO - 'SHELL': '/bin/bash', 08:48:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:29 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:29 INFO - 'USER': 'cltbld', 08:48:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:29 INFO - 'XPC_FLAGS': '0x0', 08:48:29 INFO - 'XPC_SERVICE_NAME': '0', 08:48:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:48:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:48:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:48:32 INFO - Installing collected packages: mozsystemmonitor 08:48:32 INFO - Running setup.py install for mozsystemmonitor 08:48:32 INFO - Successfully installed mozsystemmonitor 08:48:32 INFO - Cleaning up... 08:48:32 INFO - Return code: 0 08:48:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 08:48:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 08:48:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:32 INFO - 'HOME': '/Users/cltbld', 08:48:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:32 INFO - 'LOGNAME': 'cltbld', 08:48:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:32 INFO - 'MOZ_NO_REMOTE': '1', 08:48:32 INFO - 'NO_EM_RESTART': '1', 08:48:32 INFO - 'PAGER': '/bin/cat', 08:48:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:32 INFO - 'PWD': '/builds/slave/test', 08:48:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:32 INFO - 'SHELL': '/bin/bash', 08:48:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:32 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:32 INFO - 'USER': 'cltbld', 08:48:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:32 INFO - 'XPC_FLAGS': '0x0', 08:48:32 INFO - 'XPC_SERVICE_NAME': '0', 08:48:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:32 INFO - Downloading/unpacking jsonschema==2.5.1 08:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:48:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:36 INFO - Downloading functools32-3.2.3-2.tar.gz 08:48:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:48:36 INFO - warning: no files found matching '*.txt' 08:48:36 INFO - no previously-included directories found matching 'build' 08:48:36 INFO - no previously-included directories found matching 'dist' 08:48:36 INFO - no previously-included directories found matching '.git*' 08:48:36 INFO - Installing collected packages: jsonschema, functools32 08:48:36 INFO - Running setup.py install for functools32 08:48:36 INFO - warning: no files found matching '*.txt' 08:48:36 INFO - no previously-included directories found matching 'build' 08:48:36 INFO - no previously-included directories found matching 'dist' 08:48:36 INFO - no previously-included directories found matching '.git*' 08:48:36 INFO - Successfully installed jsonschema functools32 08:48:36 INFO - Cleaning up... 08:48:36 INFO - Return code: 0 08:48:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:36 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:36 INFO - 'HOME': '/Users/cltbld', 08:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:36 INFO - 'LOGNAME': 'cltbld', 08:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:36 INFO - 'MOZ_NO_REMOTE': '1', 08:48:36 INFO - 'NO_EM_RESTART': '1', 08:48:36 INFO - 'PAGER': '/bin/cat', 08:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:36 INFO - 'PWD': '/builds/slave/test', 08:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:36 INFO - 'SHELL': '/bin/bash', 08:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:36 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:36 INFO - 'USER': 'cltbld', 08:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:36 INFO - 'XPC_FLAGS': '0x0', 08:48:36 INFO - 'XPC_SERVICE_NAME': '0', 08:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:48:36 INFO - Cleaning up... 08:48:36 INFO - Return code: 0 08:48:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:36 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:48:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:48:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:36 INFO - 'HOME': '/Users/cltbld', 08:48:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:36 INFO - 'LOGNAME': 'cltbld', 08:48:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:36 INFO - 'MOZ_NO_REMOTE': '1', 08:48:36 INFO - 'NO_EM_RESTART': '1', 08:48:36 INFO - 'PAGER': '/bin/cat', 08:48:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:36 INFO - 'PWD': '/builds/slave/test', 08:48:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:36 INFO - 'SHELL': '/bin/bash', 08:48:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:36 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:36 INFO - 'USER': 'cltbld', 08:48:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:36 INFO - 'XPC_FLAGS': '0x0', 08:48:36 INFO - 'XPC_SERVICE_NAME': '0', 08:48:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:36 INFO - Downloading/unpacking blobuploader==1.2.4 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:39 INFO - Downloading blobuploader-1.2.4.tar.gz 08:48:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:48:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:48:40 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:40 INFO - Downloading docopt-0.6.1.tar.gz 08:48:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:48:40 INFO - Installing collected packages: blobuploader, requests, docopt 08:48:40 INFO - Running setup.py install for blobuploader 08:48:41 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:48:41 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:48:41 INFO - Running setup.py install for requests 08:48:41 INFO - Running setup.py install for docopt 08:48:41 INFO - Successfully installed blobuploader requests docopt 08:48:41 INFO - Cleaning up... 08:48:41 INFO - Return code: 0 08:48:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:41 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:41 INFO - 'HOME': '/Users/cltbld', 08:48:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:41 INFO - 'LOGNAME': 'cltbld', 08:48:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:41 INFO - 'MOZ_NO_REMOTE': '1', 08:48:41 INFO - 'NO_EM_RESTART': '1', 08:48:41 INFO - 'PAGER': '/bin/cat', 08:48:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:41 INFO - 'PWD': '/builds/slave/test', 08:48:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:41 INFO - 'SHELL': '/bin/bash', 08:48:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:41 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:41 INFO - 'USER': 'cltbld', 08:48:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:41 INFO - 'XPC_FLAGS': '0x0', 08:48:41 INFO - 'XPC_SERVICE_NAME': '0', 08:48:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-HZbsgr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-2BxBLH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-sN3Y6x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-YLeWjh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-en41WA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-pIVBgX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-BkGoIt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-3sL82Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:42 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-UDk6q3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-BgsofZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-glDVO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-SOIDrb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4XSNyY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-aSTICJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-8CtpOB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-ydg0aU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:43 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-nHG9xV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:48:43 INFO - Running setup.py install for manifestparser 08:48:44 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozcrash 08:48:44 INFO - Running setup.py install for mozdebug 08:48:44 INFO - Running setup.py install for mozdevice 08:48:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozfile 08:48:44 INFO - Running setup.py install for mozhttpd 08:48:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozinfo 08:48:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:48:44 INFO - Running setup.py install for mozInstall 08:48:45 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozleak 08:48:45 INFO - Running setup.py install for mozlog 08:48:45 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for moznetwork 08:48:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozprocess 08:48:45 INFO - Running setup.py install for mozprofile 08:48:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:48:45 INFO - Running setup.py install for mozrunner 08:48:46 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Running setup.py install for mozscreenshot 08:48:46 INFO - Running setup.py install for moztest 08:48:46 INFO - Running setup.py install for mozversion 08:48:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:48:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:48:46 INFO - Cleaning up... 08:48:46 INFO - Return code: 0 08:48:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:46 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:48:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:46 INFO - 'HOME': '/Users/cltbld', 08:48:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:46 INFO - 'LOGNAME': 'cltbld', 08:48:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:46 INFO - 'MOZ_NO_REMOTE': '1', 08:48:46 INFO - 'NO_EM_RESTART': '1', 08:48:46 INFO - 'PAGER': '/bin/cat', 08:48:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:46 INFO - 'PWD': '/builds/slave/test', 08:48:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:46 INFO - 'SHELL': '/bin/bash', 08:48:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:46 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:46 INFO - 'USER': 'cltbld', 08:48:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:46 INFO - 'XPC_FLAGS': '0x0', 08:48:46 INFO - 'XPC_SERVICE_NAME': '0', 08:48:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:48:46 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-zPkwW8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 08:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:48:46 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Dm9QMk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:48:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:48:46 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-QwhBIE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-QYMmef-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-SOvbUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-Nrskmz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-4PXOJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-U_RdCr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-bxDxFZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-uJw9tZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-iPHEre-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:48:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:48:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:48:47 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-VTGKUL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-LkG1nz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-CpJSu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-P_PeGB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-reDD67-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:48:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Running setup.py (path:/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/pip-35vd8d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:48:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:48:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:48:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:48:51 INFO - Downloading blessings-1.6.tar.gz 08:48:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:48:51 INFO - Installing collected packages: blessings 08:48:51 INFO - Running setup.py install for blessings 08:48:51 INFO - Successfully installed blessings 08:48:51 INFO - Cleaning up... 08:48:51 INFO - Return code: 0 08:48:51 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:48:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:51 INFO - Reading from file tmpfile_stdout 08:48:51 INFO - Current package versions: 08:48:51 INFO - blessings == 1.6 08:48:51 INFO - blobuploader == 1.2.4 08:48:51 INFO - docopt == 0.6.1 08:48:51 INFO - functools32 == 3.2.3-2 08:48:51 INFO - jsonschema == 2.5.1 08:48:51 INFO - manifestparser == 1.1 08:48:51 INFO - mozInstall == 1.12 08:48:51 INFO - mozcrash == 1.0 08:48:51 INFO - mozdebug == 0.1 08:48:51 INFO - mozdevice == 0.49 08:48:51 INFO - mozfile == 1.2 08:48:51 INFO - mozhttpd == 0.7 08:48:51 INFO - mozinfo == 0.9 08:48:51 INFO - mozleak == 0.1 08:48:51 INFO - mozlog == 3.4 08:48:51 INFO - moznetwork == 0.27 08:48:51 INFO - mozprocess == 0.24 08:48:51 INFO - mozprofile == 0.28 08:48:51 INFO - mozrunner == 6.13 08:48:51 INFO - mozscreenshot == 0.1 08:48:51 INFO - mozsystemmonitor == 0.3 08:48:51 INFO - moztest == 0.8 08:48:51 INFO - mozversion == 1.4 08:48:51 INFO - psutil == 3.1.1 08:48:51 INFO - requests == 1.2.3 08:48:51 INFO - wsgiref == 0.1.2 08:48:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:48: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')]} 08:48:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48: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')]} 08:48:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:51 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:48:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:48:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:51 INFO - 'HOME': '/Users/cltbld', 08:48:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:51 INFO - 'LOGNAME': 'cltbld', 08:48:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:51 INFO - 'MOZ_NO_REMOTE': '1', 08:48:51 INFO - 'NO_EM_RESTART': '1', 08:48:51 INFO - 'PAGER': '/bin/cat', 08:48:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:51 INFO - 'PWD': '/builds/slave/test', 08:48:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:51 INFO - 'SHELL': '/bin/bash', 08:48:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:51 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:51 INFO - 'USER': 'cltbld', 08:48:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:51 INFO - 'XPC_FLAGS': '0x0', 08:48:51 INFO - 'XPC_SERVICE_NAME': '0', 08:48:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:48:52 INFO - Cleaning up... 08:48:52 INFO - Return code: 0 08:48:52 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:48:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:48:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:48:52 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 0x101fa8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101fab768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8f504c1410>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, '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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 08:48:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:48:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:48:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:48:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:48:52 INFO - 'HOME': '/Users/cltbld', 08:48:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:48:52 INFO - 'LOGNAME': 'cltbld', 08:48:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:48:52 INFO - 'MOZ_NO_REMOTE': '1', 08:48:52 INFO - 'NO_EM_RESTART': '1', 08:48:52 INFO - 'PAGER': '/bin/cat', 08:48:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:48:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:48:52 INFO - 'PWD': '/builds/slave/test', 08:48:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:48:52 INFO - 'SHELL': '/bin/bash', 08:48:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:48:52 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:48:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:48:52 INFO - 'USER': 'cltbld', 08:48:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:48:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:48:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:48:52 INFO - 'XPC_FLAGS': '0x0', 08:48:52 INFO - 'XPC_SERVICE_NAME': '0', 08:48:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:48:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:48:52 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:48:52 INFO - Cleaning up... 08:48:52 INFO - Return code: 0 08:48:52 INFO - Running post-action listener: _resource_record_post_action 08:48:52 INFO - Running post-action listener: _start_resource_monitoring 08:48:52 INFO - Starting resource monitoring. 08:48:52 INFO - [mozharness: 2017-02-25 16:48:52.416731Z] Finished create-virtualenv step (success) 08:48:52 INFO - [mozharness: 2017-02-25 16:48:52.417659Z] Running install step. 08:48:52 INFO - Running pre-action listener: _resource_record_pre_action 08:48:52 INFO - Running main action method: install 08:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:48:52 INFO - Reading from file tmpfile_stdout 08:48:52 INFO - Detecting whether we're running mozinstall >=1.0... 08:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:48:52 INFO - Reading from file tmpfile_stdout 08:48:52 INFO - Output received: 08:48:52 INFO - Usage: mozinstall [options] installer 08:48:52 INFO - Options: 08:48:52 INFO - -h, --help show this help message and exit 08:48:52 INFO - -d DEST, --destination=DEST 08:48:52 INFO - Directory to install application into. [default: 08:48:52 INFO - "/builds/slave/test"] 08:48:52 INFO - --app=APP Application being installed. [default: firefox] 08:48:52 INFO - mkdir: /builds/slave/test/build/application 08:48:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:48:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 08:49:12 INFO - Reading from file tmpfile_stdout 08:49:12 INFO - Output received: 08:49:12 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:49:12 INFO - Running post-action listener: _resource_record_post_action 08:49:12 INFO - [mozharness: 2017-02-25 16:49:12.946435Z] Finished install step (success) 08:49:12 INFO - [mozharness: 2017-02-25 16:49:12.946599Z] Running run-tests step. 08:49:12 INFO - Running pre-action listener: _resource_record_pre_action 08:49:12 INFO - Running main action method: run_tests 08:49:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:49:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:49:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:49:12 INFO - 2017-02-25 08:49:12.960 screenresolution[1635:9226] starting screenresolution argv=screenresolution get 08:49:12 INFO - 2017-02-25 08:49:12.963 screenresolution[1635:9226] Display 0: 1600x1200x32@60 08:49:12 INFO - 2017-02-25 08:49:12.969 screenresolution[1636:9229] starting screenresolution argv=screenresolution list 08:49:12 INFO - Available Modes on Display 0 08:49:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:49:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:49:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:49:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:49:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:49:13 INFO - Intel Iris: 08:49:13 INFO - Chipset Model: Intel Iris 08:49:13 INFO - Type: GPU 08:49:13 INFO - Bus: Built-In 08:49:13 INFO - VRAM (Dynamic, Max): 1536 MB 08:49:13 INFO - Vendor: Intel (0x8086) 08:49:13 INFO - Device ID: 0x0a2e 08:49:13 INFO - Revision ID: 0x0009 08:49:13 INFO - Displays: 08:49:13 INFO - Display: 08:49:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:49:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:49:13 INFO - Display Serial Number: 1600x1200 60 08:49:13 INFO - Main Display: Yes 08:49:13 INFO - Mirror: Off 08:49:13 INFO - Online: Yes 08:49:13 INFO - Rotation: Supported 08:49:13 INFO - Return code: 0 08:49:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:49:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:49:13 INFO - Python 2.7.3 08:49:13 INFO - Return code: 0 08:49:13 INFO - grabbing minidump binary from tooltool 08:49: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')]} 08:49:13 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 0x101d86d30>, '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 0x101caddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101d7bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:49:13 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 08:49:13 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 08:49:13 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 08:49:13 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:49:13 INFO - Return code: 0 08:49:13 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:49:13 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:49:13 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:49:13 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:49:13 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:49:13 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/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 08:49:13 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/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0215 --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 08:49:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.SrPwDWHw0u/Render', 08:49:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:49:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:49:13 INFO - 'HOME': '/Users/cltbld', 08:49:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:49:13 INFO - 'LOGNAME': 'cltbld', 08:49:13 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:49:13 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:49:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:49:13 INFO - 'MOZ_NO_REMOTE': '1', 08:49:13 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:49:13 INFO - 'NO_EM_RESTART': '1', 08:49:13 INFO - 'PAGER': '/bin/cat', 08:49:13 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:49:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:49:13 INFO - 'PWD': '/builds/slave/test', 08:49:13 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:49:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:49:13 INFO - 'SHELL': '/bin/bash', 08:49:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Jp0uklKc4t/Listeners', 08:49:13 INFO - 'TMPDIR': '/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/', 08:49:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:49:13 INFO - 'USER': 'cltbld', 08:49:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:49:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:49:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:49:13 INFO - 'XPC_FLAGS': '0x0', 08:49:13 INFO - 'XPC_SERVICE_NAME': '0', 08:49:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:49:13 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/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0215', '--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 08:49:13 INFO - mozversion application_buildid: 20170225072608 08:49:13 INFO - mozversion application_changeset: 3629511a266bc964961afe690cc841ded9c3dc66 08:49:13 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:49:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:49:13 INFO - mozversion application_name: Firefox 08:49:13 INFO - mozversion application_remotingname: firefox-dev 08:49:13 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:49:13 INFO - mozversion application_vendor: Mozilla 08:49:13 INFO - mozversion application_version: 53.0a2 08:49:13 INFO - mozversion platform_buildid: 20170225072608 08:49:13 INFO - mozversion platform_changeset: 3629511a266bc964961afe690cc841ded9c3dc66 08:49:13 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:49:13 INFO - mozversion platform_version: 53.0a2 08:49:13 INFO - using testdate: 1488041353 08:49:13 INFO - actual date: 1488041353 08:49:13 INFO - starting webserver on 'localhost:49245' 08:49:13 INFO - SUITE-START | Running 2 tests 08:49:13 INFO - TEST-START | dromaeo_css 08:49:13 INFO - operating with platform_type : mac_ 08:49:13 INFO - Initialising browser for dromaeo_css test... 08:49:13 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpqh9Q9i/profile http://localhost:49245/getInfo.html) 08:49:14 INFO - PROCESS | 1646 | 1488041354107 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:49:14 INFO - PROCESS | 1646 | 1488041354108 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 08:49:14 INFO - PROCESS | 1646 | 1488041354143 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:49:15 INFO - PROCESS | 1646 | __metrics Screen width/height:1600/1200 08:49:15 INFO - PROCESS | 1646 | colorDepth:24 08:49:15 INFO - PROCESS | 1646 | Browser inner width/height: 1024/689 08:49:15 INFO - PROCESS | 1646 | __metrics 08:49:15 INFO - PROCESS | 1646 | 1488041355965 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 08:49:16 INFO - PROCESS | 1646 | 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" 08:49:16 INFO - PROCESS | 1646 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:49:16 INFO - TEST-INFO | 1646: exit 0 08:49:16 INFO - Browser initialized. 08:49:16 INFO - Running cycle 1/1 for dromaeo_css test... 08:49:16 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpqh9Q9i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:49:17 INFO - PROCESS | 1649 | 1488041357186 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 08:49:17 INFO - PROCESS | 1649 | 1488041357187 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 08:49:28 INFO - PROCESS | 1649 | RSS: Main: 212725760 08:49:28 INFO - PROCESS | 1649 | 08:52:32 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49245/tests/dromaeo/cssquery-ext.html) 08:52:34 INFO - PROCESS | 1649 | RSS: Main: 348409856 08:52:34 INFO - PROCESS | 1649 | 08:54:18 INFO - PROCESS | 1649 | ************************* 08:54:18 INFO - PROCESS | 1649 | A coding exception was thrown and uncaught in a Task. 08:54:18 INFO - PROCESS | 1649 | 08:54:18 ERROR - PROCESS | 1649 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:54:18 INFO - PROCESS | 1649 | Full stack: 08:54:18 INFO - PROCESS | 1649 | ************************* 08:55:37 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-ext.html (next: http://localhost:49245/tests/dromaeo/cssquery-jquery.html) 08:55:37 INFO - PROCESS | 1649 | RSS: Main: 351752192 08:55:37 INFO - PROCESS | 1649 | 08:58:40 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49245/tests/dromaeo/cssquery-mootools.html) 08:58:40 INFO - PROCESS | 1649 | RSS: Main: 321134592 08:58:40 INFO - PROCESS | 1649 | 09:01:43 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49245/tests/dromaeo/cssquery-prototype.html) 09:01:43 INFO - PROCESS | 1649 | RSS: Main: 337334272 09:01:43 INFO - PROCESS | 1649 | 09:04:47 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49245/tests/dromaeo/cssquery-yui.html) 09:04:47 INFO - PROCESS | 1649 | RSS: Main: 313729024 09:04:47 INFO - PROCESS | 1649 | 09:07:50 INFO - PROCESS | 1649 | Cycle 1(1): loaded http://localhost:49245/tests/dromaeo/cssquery-yui.html (next: http://localhost:49245/tests/dromaeo/cssquery-dojo.html) 09:07:51 INFO - PROCESS | 1649 | RSS: Main: 460832768 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | __start_tp_report 09:07:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load 09:07:51 INFO - PROCESS | 1649 | _x_x_mozilla_page_load_details 09:07:51 INFO - PROCESS | 1649 | |i|pagename|runs| 09:07:51 INFO - PROCESS | 1649 | |0;dojo.html;3376.9493457598137;3399.114055391493;3401.183715908183;3401.659834016597;3458.273762509873;5784;5807.883842323162;5816.214811000514;5838.678872662002;5861.824145275638;5772.509336706385;5838.357780644125;5860.706964651772;5871.677057761822;5908.88182236355;5245.318108645871;5246.213068039787;5296.470352964692;5301.0723123453245;5387.703676297802;5198.298229738974;5233.816816411427;5353.491418315254;5355.946440535584;5359.9732001339935;4365.388845561624;5270.947290527103;5352;5353.5984801139875;5364.168553874136;5311.2298716686055;5321.574274058066;5352.090144675414;5356.4107948125675;5359.9732001340135;5219.010259805563;5274.498922602346;5276.12943864262;5282.31329927107;5300.284461597674;5647.237622920894;5700.144978253295;5700.230468886689;5764.394738552433;5808.82573522795;6239.563230573862;6339.11252424661;6348.079528468346;6351.237851457809;6351.872962540723;6258.906116408258;6286.119943207955;6299.968500157515;6301.40136687014;6301.936980630181;5758.337791154071;5880.235569375954;5899.174115623816;5930.9406905931255;5943.791967281124;6225.439710426083;6253.6873156342;6256.124142620037;6273.435390814849;6285.2457705075685;6227.782027629011;6234.345393733593;6243.687815609202;6246.156768836149;6249.9062514062325;5876.648370874637;6164.506839452833;6184.783532576338;6318.147050148263;6325.240971083499;6104.3590423005835;6152.138700581922;6155.415235552616;6210.658413787285;6312.842178945563;6270.592411493239;6335.11308416822;6337.556371053982;6354.142190804182;6359.109724638462;5018.197088465828;5042.596592272609;5062.0382127396415;5067.366579177603;5080.314157588752;4429.092036132585;4458.041521072985;4485.192665320208;4515.81936722534;4528.682992190581;5713.285839270091;5721.771129154788;5750.02249617566;5756.107803290497;5834.299884013861;6202.34875338092;6245.24445372761;6253.343398943137;6270.655113968684;6276.843078923063;5830.416958304136;5833.679147646835;5873.442023007808;5892.882142357129;5957.49361304294;5432;5496.367917689437;5533.944660553423;5536.307961504812;5558.77764889408;6205.689715514206;6247.812565623039;6249.187605611241;6263.404976527223;6292.811215663537;6301.8109456716375;6325.272593651789;6334.398232167937;6343.0485427186295;6347.650879201688;6344.651044192614;6351.936480635134;6356.523260755369;6360.650164240826;6366.522510811615;6127.816165515042;6147.016477363601;6159.6612186330185;6161.84595385119;6257.874842503215;5593.932612209374;6060.969695151496;6297.275813281348;6326.936730632819;6334.8733025338315;6047.66737829406;6144.38556144382;6206.286277078194;6231.4391704746795;6236.719347629277;6014.428629280212;6077.361877003028;6133.785317513865;6223.751049957951;6226.937730622636;5892.646441213541;5960.046392577166;6043.184170137151;6073.635581865102;6077.817665470043;6206.291090116453;6224.408681175462;6225.439710426083;6233.844153896189;6240.126382306572;5857.26784151981;5974.701264936823;6094.969525152524;6096.177016102769;6139.140520327069 09:07:51 INFO - PROCESS | 1649 | |1;ext.html;455.88374964384076;478.26586190198054;493.4005183701802;515.6081378152603;522.0863488894435;31729.524057139133;35714.8214258929;41511.79244103779;43576.78211608937;43639.563604363946;45782.542174578244;46024.769876150574;46134.30798538025;46157.53842461574;46239.76880115603;8320.70877519287;8340.416170868042;8570.057293697688;9340.57967391469;9568.330216884786;9808.215342772597;11273;11536.653900383002;11665;11685.824712629317;11294.774104517905;11375.544978200862;11380.430978451044;11396.97427231549;11409.828852567218;10943.726406839893;11491;11544.711382215428;11554.422278886064;11562;10480.266381353309;11502.367369794658;11516.827247591293;11525.827112593317;11536.192466527347;42385.57614423878;42705.57294427078;42783;42822;42855.785721071195;3947.1142176361855;3977.54258260298;4029.2747305485;4037.9192416151513;4062.1672557125717;29840.8507957461;30444.8477757612;31030.68969310323;32041.359172816414;32119.19702007445;3128.655847856734;3140.277736120682;3254.2027203335333;3285.4578994465883;3388.9830550847337;367775;368818;369219;369509;371934;67401.32598674048;67908.98136527956;67965.66017169882;68124.65937670378;68170.3182968174;70794.64602677057;72794;72867;72908;73019.63490182621;17012.74480882763;17297.221625026905;17306.048167350917;17308.221130049176;17345;11767.470463829148;12023.27860328383;12064.939675301568;13102.606921792361;13192.670183245496;19081.63364744636;22419.663705044437;22432.88783556072;22942.770572294063;23041.193558225383;8095.433319667684;8147.103818579952;8621.698240561676;8855.468671879707;8953.731388058368;861.8405594964826;876.6186708781573;891.2112780189578;908.4322298563397;914.6295750221171;27367.45265094687;30634.387312254075;30780.38439231203;31073.5814277883;31293.68706312953;1064.893510648929;1067.2475904487474;1107.8338249262674;1113.716002419394;1192.3025030357164;15377.692446151013;15800.683986320439;16192.838071619133;16436.917815411085;16495.175241237892;663644;672635;673680.3159842086;678919;679362;48967.510324897004;49165;49218.26172607414;49330.766730832016;49458.25812612814;1409.2249262905361;1449.7245523350741;1471.9190444525655;1474.4176050460223;1480.222882986419;28806.711932880404;30177.698223017487;30625.02811951648;30709.385812284076;30873.4041283856;56097.43902561085;56635;56658.716706416206;56742;56752.432475674716;580138;581894;583917;583942;584005;36182.11541441872;36580.81709591435;44829.77585112053;44966.1006779856;45625.31562026506;47840.521594783604;49418.258726118416;49525;50160.74919625378;50411.49588504068;37538.06154846041;37688.43467348047;40162.1967560653;40703.38944915888;41733.74798756042;40266.20803110722;40387.596124038384;40666.79666601648;41087.589124108374;41732.79133604313;38514.0371490715;38854;39292.60707393004;39498.2100357997;39748.20503589854;31115.533267001465;32475;34565.48151777675;34652;35255.64744352524;24307.756922430548;25152.13386781971;25292.873535632203;25812.483750325264;25949 09:07:51 INFO - PROCESS | 1649 | |2;jquery.html;385.15074261253955;386.947762052123;393.3745344901606;394.7789238026704;420.43451557654964;13754.58736237912;20142.295019674315;21044.263450779188;21429.464263393424;21476.463088422777;21677.783222167775;21895.562088758215;22104.447388815246;22268.99789509472;22501.54996900061;4940.505949405057;6024.849378765544;6026.9095963560585;6028.065649824284;6029.155918171459;5705.971470142644;5870.823875283749;5912.379200183983;5929.970350148244;5961.612495187821;5793.188953546507;5810.796622118227;5889.499392551638;5898.705064746767;5911.6748578828365;5623.26897503324;5734.856628584298;5804.013317735995;5816.5346772258545;5871.236739223895;5134.332536770196;5140.1004824155625;5145.022445735298;5145.3568303962;5146.9485305146845;24143.63784543233;24408.779561021878;27352.58971115435;28415.289617759514;29445.55831662527;2969.094426200008;2979.8212107273635;2994.9850250748823;3025.8940937067096;3031.681673424303;16907.91546042274;17184.656306873792;17439.21523531444;17668.558286042822;18293;1668.2659629762866;1998.680211166221;2008.2167954497756;2022.9898850505654;2029.8376129909573;168819.46770798447;171984;174830;178830;181831;24125.91433385502;24227.75772242255;24299.757002430102;24303.14938977127;24546.895389707504;34537.827310863286;35704.82147589245;44003.89990250269;44080.559194408284;44214.33678494825;9995.900040999642;11086.22396432242;11132.165087618461;11625.941870290595;11637.650870473899;9240.815183696288;9257.444553326823;9281.72154835356;9393.436393816393;9414.16423797353;9199.666048422941;10890.649949730981;11226.438678066064;11233.943830280796;11361.772764544663;3611.7178401667556;4100.282450571138;4240.321548552217;4244.175004747988;4266.765327906933;946.2288235088381;955.9139677429065;989.2135752077116;989.6091044037565;1046.2414749306693;18622.720659190123;20669.689954650694;20772.58454830895;20774.961251937646;21254.46863828417;983.9262055345878;990.4153354632674;1007.6926537406015;1023.9846402303972;1024.288119756762;10606.363618182933;11223.494942727597;11227.55089796399;11238.213325067167;11250.987411132874;768546;775466;782725;785260;799532;115258;120784;123001;133972.33013834868;144258.5221753422;979.583676937313;989.4014121456559;998.9250806189565;1003.1322905686675;1005.331454582709;17641;18566;18761.812381876007;18773.718394223826;18816.341428049953;37737.43393849149;54609;58671.70664146823;58911.82176356535;59889;85574.14425855661;87424;88768.5561572188;88970;89675;8205.630746616298;8371.790705232173;8427.41008129425;8554.059053503985;8680.522571258389;12192.841680040381;12660.240385576895;13228;13358.265295408457;13524.59426217215;11572.30566165999;11577.479013444246;11658;11670.70823229426;11829.585964491202;11091.445427728742;11339.603113891304;11647.068234541048;11678.532858685898;11822.995045420892;11554.653360399201;11696.415179240834;11837.289762614271;11947.22343047734;11957.521699131938;7229.819254518679;7410.814729631802;7552.962235188789;7822.022247219098;8587.742367728979;9664;11231.326120432801;11444.141689373164;11533.365664888512;11565.363904985306 09:07:51 INFO - PROCESS | 1649 | |3;mootools.html;1304.2565737529605;1422.9217393043384;1448.4351103069798;1449.0725935401342;1452.0779305141232;8042.879356809645;8449.408541402088;8459.069502354736;8460.57697115144;8463.28062114721;6702.832429189272;7469.962650186755;7918.247766462191;8191.467554608947;8262.586870656458;2310.9653355199684;2350.3536527454917;2356.2813341930705;2356.6347216181434;2356.9882150589224;2304;2344.0272287000876;2352.564775516535;2355.599548076831;2362.0197617988515;2045.4988527810708;2126.1601667341392;2248.2243625949104;2281.441046943501;2309.7574754650686;2322.036354912709;2337.5792357375667;2339.0760649543336;2339.4736184358385;2342.1217043608563;2031.8882461464614;2265.8980345884474;2318.9304320870233;2320.2923108451914;2349.835511514195;8252.669893204265;8321.500709957421;8334.1249168837;8338.374621903382;8340;4335.479742430928;5040.521150490697;5194.92207616886;5195.714235717034;5217.34783152106;3274.607047154333;3427.7600567960253;3437.432823584106;3496.4230901901155;3498.387782138141;1145.2956431794469;1199.2684462477882;1209.5666635037421;1230.39095647655;1236.394166858242;10812.837807432894;10826.566937322497;10828.72928176794;10853.945730271298;10865.782684346348;4535;4702.788374523155;4718.905621887543;4725.5983241424865;4758.595519380822;8662.436941598777;8723.651053957898;8724.563771811385;8734.257588105082;8767.386282960131;3885.4948856648457;3959.5842436544326;5076.796928122833;5095.261187127846;5096.847094587094;4534.161180181677;4654.674172807939;4675.0883577702025;4755.096531658974;4771.33201351811;3695.519582454264;3752.3996160614715;3802.3269761304937;3812.9809350953624;3857.283508838596;1161.5586077290745;1168.9070718877877;1174.7356844710043;1186.163754553038;1186.839776630144;765.5406755946387;769.2461387839836;796.1680044353665;797.960101994898;812.6627449608465;5330.307060082242;5377.193420986883;5419.729013549307;5538.280023596906;5713.628614140068;534.1400345443832;540.4811381074135;561.5872333834628;575.5539456920919;579.4234736437272;3436.0722604896537;3452.706519945833;3476.1309672581856;3561.074120728629;3767.9811600942367;7986.800329991796;9958.402495850272;10038.397696138109;10064.69805905824;10071;9999.450030248407;10000.000000000087;10001.24990625706;10023.949880250699;10103.444310562989;6683.699233534406;7740.26467485588;7858.803529911798;7862.685492580233;7878.684852605831;5398.892022159614;5453.291072160594;5621.269234999424;5648.830535083954;5722.542196624343;8828.690995815115;8959.820803584022;9251.629934802533;9339.346245762732;9362.812743745224;8541.273991710774;8830.602622882114;8953.955230223808;9249.953750231205;9431.62273509052;6602.801915942529;7143.964280178566;7172.748953786592;7261.273872612696;7276.745313913988;6784.185897692309;7614.2766437188375;7827.25641064098;8024.317932975762;8026.277635012877;6981;7035.929640703527;7095.254998225112;7097.183823860322;7127.358537731629;6934.687939042654;6967.338102880219;6980.6509674515055;6998.475114366341;7021.719131234693;6322.925102732454;6330.01884707871;6365.777197798055;6386.904196436964;6389.424951754364;4195.804195804181;4565.657575681904;4877.6829506081995;4913.803447862046;5016.448190699093;4888.706677599355;4942.555170034714;4964.478729733326;5027.824026159067;5037.647364684583 09:07:51 INFO - PROCESS | 1649 | |4;prototype.html;345.6681585677749;349.5647918341665;354.09352058729655;365.0272025053233;368.94650275710006;4891.192953162724;5261.736913154336;5271.841844744659;5318.840434786953;5360.855657736903;4261.382099595557;5010.795250089967;5030.5921134780165;5185.455527169649;5266.947330526703;3566.3402270580027;3831.9042023949487;4143.21278956998;4146.3780432935155;4161.916761664765;3682.668559829615;3778.5843557208686;3969.900752481197;4139.820151256892;4261.318189089746;3653.4885116083638;3785.791781452019;3990.7406018608845;4091.897702557445;4111.342185250361;3956.5647778744315;3964.226975739732;3965.6232657897453;3965.9206815863668;4003.4395184674167;3434.8969530914114;3443.1908501502126;3458;3462.3207744244023;3474.669906358917;4275.722078064916;5084.542391184774;5116.641835071547;5159.71621560814;5161.896762064775;3675.096673627831;3895.590962948906;4355.236129367611;4513.27787553993;4538.52345503724;4589.449266088058;5495.395506494282;5509.47659972302;5510.9448905510835;5522.3649280332875;3286.075392900321;3528.923678278124;3562.340966921128;3691.464737613031;3866.5553461352024;4714.316424118518;5309.734513274321;5504.752286147133;5732.054211055212;5803.172000819771;3272.8199949002556;3383.796972181677;3756.7978246961256;3818.236352729465;3824.388097904322;3066.5706801047872;3671.724620653462;3892.9416058759143;3900.5904380040256;3901.5903330150495;4036.6972477064337;4263.765492897858;4511.571400716926;4516.706414083074;4575.22221222393;5287.4976877196605;5540.252065971043;5607.130894711327;5614.905093506809;5638.577106717013;1067.1356201476829;1205.37320593291;1219.688979310288;1239.293602646483;1241.4599649152647;805.2470939671426;818.5293456262673;826.5288785392269;827.1315119124895;847.0216899481109;4408.889777755582;4543.318502224693;4850.1269771441475;4887.9490909454635;4900.754962251873;4433.248866697636;4617.836781804474;4773.706142761553;5081.415637201695;5386;3901.005243662848;4318.287482565342;4733.928991065137;4845.418549773979;4960.280759289883;2497.963345211717;2744.929477503775;2812.54999200127;2812.662480502353;2853.5576985567272;5560.026995275892;5562.3047119110115;5609.943900561024;5623.718814059281;5681.630694004876;4941.357623508921;4971.6271279654175;5012.398512178489;5233;5291.8567979968175;4894.094592500399;5027.547520723152;5184.300119483822;5335.319374397131;5557.44425557441;4675.78958946855;5055.191169413023;5170.069387510134;5342.813001545082;5370.702265000661;4575;4780.90039290972;4955.017992802907;5275.829686906776;5325.946740532545;4729.929051064306;5321.175217841241;5504.477194524501;5550.250716153268;5602.2717046783655;3089.3626378019685;3108.1918701137415;3147.464930961696;3606.0804494853646;3615.3492371373404;4587.793549290223;4714.646401519831;4920.311156438173;5150.969806038733;5369.4093649697725;4689.671722979495;5185.107435785986;5193.298904647975;5195.896082078413;5246.685198887926;4875.708896494923;5120.539151476385;5183.604738896029;5194.117000109914;5197.7141257231215;4360.878969373911;4822.819005244039;5020.899582008413;5068.873278168076;5084.754235212396;4940.802367905244;5093.949060509496;5327.467253274642;5340.305760251298;5341.946580534145;4571.794269257958;4585.495595484427;5367.865803354947;5404.162354835007;5424.779424629427 09:07:51 INFO - PROCESS | 1649 | |5;yui.html;686.4714170089028;723.7394537966331;746.2201998911137;809.5952023988006;810.5825499867568;14895.77656335156;15818.525444236677;15848.920755396206;15857.60355991098;15913.44302949397;13216.93391533041;13584.796228056586;14087.57737267881;14448.133112013287;14575.489857854976;847.0428415890036;868.3834477520968;870.2733217763175;870.3124531620012;871.9667194374687;676.9492288078413;694.1427337238506;711.4095300900241;848.4145939301893;848.4697064334791;741.9406447484188;890.6348397157135;905.3798148268457;916.0564618443015;928.6888892221114;875.3172525430175;905.5608030105394;915.871777951084;918.9908100919006;920.1304766995192;532.2149829002229;683.3405763438257;684.090160087088;684.3737979748524;685.3318014935448;12280.263184208887;14523.20122393268;14999.850001499954;15085;15389.538313850602;13614.591562253148;14285.57143285703;14477.34851931655;14511.346989385504;14523.564293071226;10174;10421.801492828346;10510.896355882674;10695.425587777814;11147.944260278648;3561.928761424757;3698.500702405168;3715.1640880801874;3761.360568703327;3829.2341531693774;99411.50882736765;108478;109027.90972090336;109814.45092774565;109906.90093099046;10643.41461219625;10660.733481663021;10731.785364292671;10736.921955942282;11328.773424531464;22095.44761380946;22373.552528949655;22839.771602283763;22895.198668046538;22938.885305573367;7870.29167374939;7985.560794156378;8010.239027292398;8018.71934482302;8059.153788852203;4209.877708816784;4231.111466591989;4304.677149213821;4357.041450880801;4359.215341238608;3494.353544594258;3592.964070359263;3595.9640403596154;3596.8021758803516;3599.6220396857952;637.1589501857503;650.0249625561657;656.6060363781788;656.7143292667705;677.3937326093119;553.261396036288;556.4963707844407;561.8342588936274;588.4145275450953;602.9788957386559;9877.308588398742;10076.143527800072;10212.489375531193;10282.434466104436;10468.214883883738;685.6126288646998;697.6375455842747;698.4168219536743;699.8740226759232;705.6511641755482;4411.580899814512;4953.331300274417;4954.133026720309;4961.871983503614;4968.056069346885;95788.08423831624;104969.47515262375;105745;108361;111460.442697786;88035;92879.60680589797;93300.53349733344;95191;95991.04008959961;70635.29364706493;85533;85989;86386.56806715926;86424.56787716148;9366.438013719198;9732.221422286342;9747.658831940847;9751.927287998173;9767.706968790948;52693.47306527039;53225.66935826481;53377.46622533725;54617.08840190574;55310.170347444015;69813;70133;70560.64719676369;71211;73367.63316183384;11027.558897644005;11268.774624507629;11455.713607159554;11816.645500634651;11978.700532486757;12573;12713.87286127127;12924.483020679067;13330.86669133335;13344;11577.536898524302;11702.590409335633;11898.22661526998;11910.166288360078;12166.087543434101;11611.187216894736;11961.461734221806;11965.76068478643;12167.39163041862;12538.749225015632;11432.439696966574;11683.123765717435;11787.351695656822;11954.462049207632;12046.277223366626;9357.438553686801;9816.411015339103;10043.672446746561;10286.588536458457;10459.267851250339;9683.96694544582;9769.511524423608;9803.264755143535;9908.87304505544;9915.900840991786 09:07:51 INFO - PROCESS | 1649 | __end_tp_report 09:07:51 INFO - PROCESS | 1649 | __start_cc_report 09:07:51 INFO - PROCESS | 1649 | _x_x_mozilla_cycle_collect,1486 09:07:51 INFO - PROCESS | 1649 | __end_cc_report 09:07:51 INFO - PROCESS | 1649 | __startTimestamp1488042471247__endTimestamp 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | ------- Summary: start ------- 09:07:51 INFO - PROCESS | 1649 | Number of tests: 6 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#0] dojo.html Cycles:165 Average:5805.47 Median:6043.18 stddev:632.91 (10.5%) stddev-sans-first:605.50 09:07:51 INFO - PROCESS | 1649 | Values: 3376.9 3399.1 3401.2 3401.7 3458.3 5784.0 5807.9 5816.2 5838.7 5861.8 5772.5 5838.4 5860.7 5871.7 5908.9 5245.3 5246.2 5296.5 5301.1 5387.7 5198.3 5233.8 5353.5 5355.9 5360.0 4365.4 5270.9 5352.0 5353.6 5364.2 5311.2 5321.6 5352.1 5356.4 5360.0 5219.0 5274.5 5276.1 5282.3 5300.3 5647.2 5700.1 5700.2 5764.4 5808.8 6239.6 6339.1 6348.1 6351.2 6351.9 6258.9 6286.1 6300.0 6301.4 6301.9 5758.3 5880.2 5899.2 5930.9 5943.8 6225.4 6253.7 6256.1 6273.4 6285.2 6227.8 6234.3 6243.7 6246.2 6249.9 5876.6 6164.5 6184.8 6318.1 6325.2 6104.4 6152.1 6155.4 6210.7 6312.8 6270.6 6335.1 6337.6 6354.1 6359.1 5018.2 5042.6 5062.0 5067.4 5080.3 4429.1 4458.0 4485.2 4515.8 4528.7 5713.3 5721.8 5750.0 5756.1 5834.3 6202.3 6245.2 6253.3 6270.7 6276.8 5830.4 5833.7 5873.4 5892.9 5957.5 5432.0 5496.4 5533.9 5536.3 5558.8 6205.7 6247.8 6249.2 6263.4 6292.8 6301.8 6325.3 6334.4 6343.0 6347.7 6344.7 6351.9 6356.5 6360.7 6366.5 6127.8 6147.0 6159.7 6161.8 6257.9 5593.9 6061.0 6297.3 6326.9 6334.9 6047.7 6144.4 6206.3 6231.4 6236.7 6014.4 6077.4 6133.8 6223.8 6226.9 5892.6 5960.0 6043.2 6073.6 6077.8 6206.3 6224.4 6225.4 6233.8 6240.1 5857.3 5974.7 6095.0 6096.2 6139.1 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#1] ext.html Cycles:180 Average:69523.25 Median:38684.02 stddev:148774.49 (384.6%) stddev-sans-first:149101.47 09:07:51 INFO - PROCESS | 1649 | Values: 455.9 478.3 493.4 515.6 522.1 31729.5 35714.8 41511.8 43576.8 43639.6 45782.5 46024.8 46134.3 46157.5 46239.8 8320.7 8340.4 8570.1 9340.6 9568.3 9808.2 11273.0 11536.7 11665.0 11685.8 11294.8 11375.5 11380.4 11397.0 11409.8 10943.7 11491.0 11544.7 11554.4 11562.0 10480.3 11502.4 11516.8 11525.8 11536.2 42385.6 42705.6 42783.0 42822.0 42855.8 3947.1 3977.5 4029.3 4037.9 4062.2 29840.9 30444.8 31030.7 32041.4 32119.2 3128.7 3140.3 3254.2 3285.5 3389.0 367775.0 368818.0 369219.0 369509.0 371934.0 67401.3 67909.0 67965.7 68124.7 68170.3 70794.6 72794.0 72867.0 72908.0 73019.6 17012.7 17297.2 17306.0 17308.2 17345.0 11767.5 12023.3 12064.9 13102.6 13192.7 19081.6 22419.7 22432.9 22942.8 23041.2 8095.4 8147.1 8621.7 8855.5 8953.7 861.8 876.6 891.2 908.4 914.6 27367.5 30634.4 30780.4 31073.6 31293.7 1064.9 1067.2 1107.8 1113.7 1192.3 15377.7 15800.7 16192.8 16436.9 16495.2 663644.0 672635.0 673680.3 678919.0 679362.0 48967.5 49165.0 49218.3 49330.8 49458.3 1409.2 1449.7 1471.9 1474.4 1480.2 28806.7 30177.7 30625.0 30709.4 30873.4 56097.4 56635.0 56658.7 56742.0 56752.4 580138.0 581894.0 583917.0 583942.0 584005.0 36182.1 36580.8 44829.8 44966.1 45625.3 47840.5 49418.3 49525.0 50160.7 50411.5 37538.1 37688.4 40162.2 40703.4 41733.7 40266.2 40387.6 40666.8 41087.6 41732.8 38514.0 38854.0 39292.6 39498.2 39748.2 31115.5 32475.0 34565.5 34652.0 35255.6 24307.8 25152.1 25292.9 25812.5 25949.0 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#2] jquery.html Cycles:180 Average:43753.52 Median:27883.94 stddev:130257.64 (467.1%) stddev-sans-first:130582.34 09:07:51 INFO - PROCESS | 1649 | Values: 385.2 386.9 393.4 394.8 420.4 13754.6 20142.3 21044.3 21429.5 21476.5 21677.8 21895.6 22104.4 22269.0 22501.5 4940.5 6024.8 6026.9 6028.1 6029.2 5706.0 5870.8 5912.4 5930.0 5961.6 5793.2 5810.8 5889.5 5898.7 5911.7 5623.3 5734.9 5804.0 5816.5 5871.2 5134.3 5140.1 5145.0 5145.4 5146.9 24143.6 24408.8 27352.6 28415.3 29445.6 2969.1 2979.8 2995.0 3025.9 3031.7 16907.9 17184.7 17439.2 17668.6 18293.0 1668.3 1998.7 2008.2 2023.0 2029.8 168819.5 171984.0 174830.0 178830.0 181831.0 24125.9 24227.8 24299.8 24303.1 24546.9 34537.8 35704.8 44003.9 44080.6 44214.3 9995.9 11086.2 11132.2 11625.9 11637.7 9240.8 9257.4 9281.7 9393.4 9414.2 9199.7 10890.6 11226.4 11233.9 11361.8 3611.7 4100.3 4240.3 4244.2 4266.8 946.2 955.9 989.2 989.6 1046.2 18622.7 20669.7 20772.6 20775.0 21254.5 983.9 990.4 1007.7 1024.0 1024.3 10606.4 11223.5 11227.6 11238.2 11251.0 768546.0 775466.0 782725.0 785260.0 799532.0 115258.0 120784.0 123001.0 133972.3 144258.5 979.6 989.4 998.9 1003.1 1005.3 17641.0 18566.0 18761.8 18773.7 18816.3 37737.4 54609.0 58671.7 58911.8 59889.0 85574.1 87424.0 88768.6 88970.0 89675.0 8205.6 8371.8 8427.4 8554.1 8680.5 12192.8 12660.2 13228.0 13358.3 13524.6 11572.3 11577.5 11658.0 11670.7 11829.6 11091.4 11339.6 11647.1 11678.5 11823.0 11554.7 11696.4 11837.3 11947.2 11957.5 7229.8 7410.8 7553.0 7822.0 8587.7 9664.0 11231.3 11444.1 11533.4 11565.4 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#3] mootools.html Cycles:180 Average:5322.59 Median:5058.66 stddev:2929.82 (57.9%) stddev-sans-first:2922.47 09:07:51 INFO - PROCESS | 1649 | Values: 1304.3 1422.9 1448.4 1449.1 1452.1 8042.9 8449.4 8459.1 8460.6 8463.3 6702.8 7470.0 7918.2 8191.5 8262.6 2311.0 2350.4 2356.3 2356.6 2357.0 2304.0 2344.0 2352.6 2355.6 2362.0 2045.5 2126.2 2248.2 2281.4 2309.8 2322.0 2337.6 2339.1 2339.5 2342.1 2031.9 2265.9 2318.9 2320.3 2349.8 8252.7 8321.5 8334.1 8338.4 8340.0 4335.5 5040.5 5194.9 5195.7 5217.3 3274.6 3427.8 3437.4 3496.4 3498.4 1145.3 1199.3 1209.6 1230.4 1236.4 10812.8 10826.6 10828.7 10853.9 10865.8 4535.0 4702.8 4718.9 4725.6 4758.6 8662.4 8723.7 8724.6 8734.3 8767.4 3885.5 3959.6 5076.8 5095.3 5096.8 4534.2 4654.7 4675.1 4755.1 4771.3 3695.5 3752.4 3802.3 3813.0 3857.3 1161.6 1168.9 1174.7 1186.2 1186.8 765.5 769.2 796.2 798.0 812.7 5330.3 5377.2 5419.7 5538.3 5713.6 534.1 540.5 561.6 575.6 579.4 3436.1 3452.7 3476.1 3561.1 3768.0 7986.8 9958.4 10038.4 10064.7 10071.0 9999.5 10000.0 10001.2 10023.9 10103.4 6683.7 7740.3 7858.8 7862.7 7878.7 5398.9 5453.3 5621.3 5648.8 5722.5 8828.7 8959.8 9251.6 9339.3 9362.8 8541.3 8830.6 8954.0 9250.0 9431.6 6602.8 7144.0 7172.7 7261.3 7276.7 6784.2 7614.3 7827.3 8024.3 8026.3 6981.0 7035.9 7095.3 7097.2 7127.4 6934.7 6967.3 6980.7 6998.5 7021.7 6322.9 6330.0 6365.8 6386.9 6389.4 4195.8 4565.7 4877.7 4913.8 5016.4 4888.7 4942.6 4964.5 5027.8 5037.6 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#4] prototype.html Cycles:180 Average:4288.36 Median:4777.30 stddev:1298.14 (27.2%) stddev-sans-first:1267.60 09:07:51 INFO - PROCESS | 1649 | Values: 345.7 349.6 354.1 365.0 368.9 4891.2 5261.7 5271.8 5318.8 5360.9 4261.4 5010.8 5030.6 5185.5 5266.9 3566.3 3831.9 4143.2 4146.4 4161.9 3682.7 3778.6 3969.9 4139.8 4261.3 3653.5 3785.8 3990.7 4091.9 4111.3 3956.6 3964.2 3965.6 3965.9 4003.4 3434.9 3443.2 3458.0 3462.3 3474.7 4275.7 5084.5 5116.6 5159.7 5161.9 3675.1 3895.6 4355.2 4513.3 4538.5 4589.4 5495.4 5509.5 5510.9 5522.4 3286.1 3528.9 3562.3 3691.5 3866.6 4714.3 5309.7 5504.8 5732.1 5803.2 3272.8 3383.8 3756.8 3818.2 3824.4 3066.6 3671.7 3892.9 3900.6 3901.6 4036.7 4263.8 4511.6 4516.7 4575.2 5287.5 5540.3 5607.1 5614.9 5638.6 1067.1 1205.4 1219.7 1239.3 1241.5 805.2 818.5 826.5 827.1 847.0 4408.9 4543.3 4850.1 4887.9 4900.8 4433.2 4617.8 4773.7 5081.4 5386.0 3901.0 4318.3 4733.9 4845.4 4960.3 2498.0 2744.9 2812.5 2812.7 2853.6 5560.0 5562.3 5609.9 5623.7 5681.6 4941.4 4971.6 5012.4 5233.0 5291.9 4894.1 5027.5 5184.3 5335.3 5557.4 4675.8 5055.2 5170.1 5342.8 5370.7 4575.0 4780.9 4955.0 5275.8 5325.9 4729.9 5321.2 5504.5 5550.3 5602.3 3089.4 3108.2 3147.5 3606.1 3615.3 4587.8 4714.6 4920.3 5151.0 5369.4 4689.7 5185.1 5193.3 5195.9 5246.7 4875.7 5120.5 5183.6 5194.1 5197.7 4360.9 4822.8 5020.9 5068.9 5084.8 4940.8 5093.9 5327.5 5340.3 5341.9 4571.8 4585.5 5367.9 5404.2 5424.8 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | [#5] yui.html Cycles:180 Average:20748.52 Median:4682.46 stddev:30609.55 (653.7%) stddev-sans-first:30658.35 09:07:51 INFO - PROCESS | 1649 | Values: 686.5 723.7 746.2 809.6 810.6 14895.8 15818.5 15848.9 15857.6 15913.4 13216.9 13584.8 14087.6 14448.1 14575.5 847.0 868.4 870.3 870.3 872.0 676.9 694.1 711.4 848.4 848.5 741.9 890.6 905.4 916.1 928.7 875.3 905.6 915.9 919.0 920.1 532.2 683.3 684.1 684.4 685.3 12280.3 14523.2 14999.9 15085.0 15389.5 13614.6 14285.6 14477.3 14511.3 14523.6 10174.0 10421.8 10510.9 10695.4 11147.9 3561.9 3698.5 3715.2 3761.4 3829.2 99411.5 108478.0 109027.9 109814.5 109906.9 10643.4 10660.7 10731.8 10736.9 11328.8 22095.4 22373.6 22839.8 22895.2 22938.9 7870.3 7985.6 8010.2 8018.7 8059.2 4209.9 4231.1 4304.7 4357.0 4359.2 3494.4 3593.0 3596.0 3596.8 3599.6 637.2 650.0 656.6 656.7 677.4 553.3 556.5 561.8 588.4 603.0 9877.3 10076.1 10212.5 10282.4 10468.2 685.6 697.6 698.4 699.9 705.7 4411.6 4953.3 4954.1 4961.9 4968.1 95788.1 104969.5 105745.0 108361.0 111460.4 88035.0 92879.6 93300.5 95191.0 95991.0 70635.3 85533.0 85989.0 86386.6 86424.6 9366.4 9732.2 9747.7 9751.9 9767.7 52693.5 53225.7 53377.5 54617.1 55310.2 69813.0 70133.0 70560.6 71211.0 73367.6 11027.6 11268.8 11455.7 11816.6 11978.7 12573.0 12713.9 12924.5 13330.9 13344.0 11577.5 11702.6 11898.2 11910.2 12166.1 11611.2 11961.5 11965.8 12167.4 12538.7 11432.4 11683.1 11787.4 11954.5 12046.3 9357.4 9816.4 10043.7 10286.6 10459.3 9684.0 9769.5 9803.3 9908.9 9915.9 09:07:51 INFO - PROCESS | 1649 | -------- Summary: end -------- 09:07:51 INFO - PROCESS | 1649 | 09:07:51 INFO - PROCESS | 1649 | 1488042471270 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:07:51 INFO - TEST-INFO | 1649: exit 0 09:07:51 INFO - TEST-OK | dromaeo_css | took 1118310ms 09:07:51 INFO - TEST-START | kraken 09:07:51 INFO - operating with platform_type : mac_ 09:07:51 INFO - Initialising browser for kraken test... 09:07:51 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpG9ZlRC/profile http://localhost:49245/getInfo.html) 09:07:52 INFO - PROCESS | 1654 | 1488042472360 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:07:52 INFO - PROCESS | 1654 | 1488042472361 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method install 09:07:52 INFO - PROCESS | 1654 | 1488042472396 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:07:54 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 09:07:54 INFO - PROCESS | 1654 | colorDepth:24 09:07:54 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 09:07:54 INFO - PROCESS | 1654 | __metrics 09:07:54 INFO - PROCESS | 1654 | 1488042474098 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:07:54 INFO - PROCESS | 1654 | 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" 09:07:54 INFO - PROCESS | 1654 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:07:54 INFO - TEST-INFO | 1654: exit 0 09:07:54 INFO - Browser initialized. 09:07:54 INFO - Running cycle 1/1 for kraken test... 09:07:54 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/T/tmpG9ZlRC/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:55 INFO - PROCESS | 1655 | 1488042475217 addons.xpi WARN Error loading bootstrap.js for disableSHA1rollout@mozilla.org: Error opening input stream (invalid filename?): jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/disableSHA1rollout@mozilla.org.xpi!/bootstrap.js 09:07:55 INFO - PROCESS | 1655 | 1488042475218 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method startup 09:08:07 INFO - PROCESS | 1655 | RSS: Main: 213106688 09:08:07 INFO - PROCESS | 1655 | 09:08:09 INFO - PROCESS | 1655 | 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) 09:08:10 INFO - PROCESS | 1655 | RSS: Main: 265875456 09:08:10 INFO - PROCESS | 1655 | 09:08:13 INFO - PROCESS | 1655 | 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) 09:08:13 INFO - PROCESS | 1655 | RSS: Main: 275005440 09:08:13 INFO - PROCESS | 1655 | 09:08:16 INFO - PROCESS | 1655 | 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) 09:08:17 INFO - PROCESS | 1655 | RSS: Main: 371232768 09:08:17 INFO - PROCESS | 1655 | 09:08:19 INFO - PROCESS | 1655 | 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) 09:08:20 INFO - PROCESS | 1655 | RSS: Main: 275902464 09:08:20 INFO - PROCESS | 1655 | 09:08:22 INFO - PROCESS | 1655 | 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) 09:08:22 INFO - PROCESS | 1655 | RSS: Main: 277168128 09:08:22 INFO - PROCESS | 1655 | 09:08:25 INFO - PROCESS | 1655 | 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) 09:08:26 INFO - PROCESS | 1655 | RSS: Main: 318996480 09:08:26 INFO - PROCESS | 1655 | 09:08:29 INFO - PROCESS | 1655 | 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) 09:08:29 INFO - PROCESS | 1655 | RSS: Main: 319705088 09:08:29 INFO - PROCESS | 1655 | 09:08:32 INFO - PROCESS | 1655 | 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) 09:08:33 INFO - PROCESS | 1655 | RSS: Main: 315170816 09:08:33 INFO - PROCESS | 1655 | 09:08:34 INFO - PROCESS | 1655 | 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) 09:08:35 INFO - PROCESS | 1655 | RSS: Main: 294940672 09:08:35 INFO - PROCESS | 1655 | 09:08:37 INFO - PROCESS | 1655 | 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) 09:08:38 INFO - PROCESS | 1655 | RSS: Main: 300576768 09:08:38 INFO - PROCESS | 1655 | 09:08:40 INFO - PROCESS | 1655 | 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) 09:08:40 INFO - PROCESS | 1655 | RSS: Main: 302579712 09:08:40 INFO - PROCESS | 1655 | 09:08:43 INFO - PROCESS | 1655 | 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) 09:08:44 INFO - PROCESS | 1655 | RSS: Main: 317468672 09:08:44 INFO - PROCESS | 1655 | 09:08:47 INFO - PROCESS | 1655 | 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) 09:08:48 INFO - PROCESS | 1655 | RSS: Main: 311799808 09:08:48 INFO - PROCESS | 1655 | 09:08:49 INFO - PROCESS | 1655 | 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) 09:08:50 INFO - PROCESS | 1655 | RSS: Main: 356642816 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | __start_tp_report 09:08:50 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 09:08:50 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 09:08:50 INFO - PROCESS | 1655 | |i|pagename|runs| 09:08:50 INFO - PROCESS | 1655 | |0;ai-astar;114;117;117;115;114;116;115;114;110;111 09:08:50 INFO - PROCESS | 1655 | |1;audio-beat-detection;142;111;107;148;111;92;129;139;138;110 09:08:50 INFO - PROCESS | 1655 | |2;audio-dft;184;183;183;184;183;184;183;186;185;184 09:08:50 INFO - PROCESS | 1655 | |3;audio-fft;81;58;59;90;62;58;58;59;74;58 09:08:50 INFO - PROCESS | 1655 | |4;audio-oscillator;84;70;86;69;67;70;83;68;70;81 09:08:50 INFO - PROCESS | 1655 | |5;imaging-gaussian-blur;90;89;91;89;90;88;91;88;91;88 09:08:50 INFO - PROCESS | 1655 | |6;imaging-darkroom;100;100;100;114;167;100;102;101;100;99 09:08:50 INFO - PROCESS | 1655 | |7;imaging-desaturate;86;95;137;88;86;87;86;89;85;88 09:08:50 INFO - PROCESS | 1655 | |8;json-parse-financial;56;54;54;52;54;57;56;55;56;55 09:08:50 INFO - PROCESS | 1655 | |9;json-stringify-tinderbox;66;78;79;78;75;70;69;71;70;72 09:08:50 INFO - PROCESS | 1655 | |10;stanford-crypto-aes;83;82;78;81;83;82;83;83;83;75 09:08:50 INFO - PROCESS | 1655 | |11;stanford-crypto-ccm;142;140;142;149;145;151;140;141;199;170 09:08:50 INFO - PROCESS | 1655 | |12;stanford-crypto-pbkdf2;167;169;230;165;166;170;168;169;166;165 09:08:50 INFO - PROCESS | 1655 | |13;stanford-crypto-sha256-iterative;68;66;67;65;66;67;62;57;66;79 09:08:50 INFO - PROCESS | 1655 | __end_tp_report 09:08:50 INFO - PROCESS | 1655 | __start_cc_report 09:08:50 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,2047 09:08:50 INFO - PROCESS | 1655 | __end_cc_report 09:08:50 INFO - PROCESS | 1655 | __startTimestamp1488042530130__endTimestamp 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | ------- Summary: start ------- 09:08:50 INFO - PROCESS | 1655 | Number of tests: 14 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:2.31 (2.0%) stddev-sans-first:2.45 09:08:50 INFO - PROCESS | 1655 | Values: 114.0 117.0 117.0 115.0 114.0 116.0 115.0 114.0 110.0 111.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:138.50 stddev:18.79 (13.6%) stddev-sans-first:18.58 09:08:50 INFO - PROCESS | 1655 | Values: 142.0 111.0 107.0 148.0 111.0 92.0 129.0 139.0 138.0 110.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.00 stddev:0.99 (0.5%) stddev-sans-first:1.05 09:08:50 INFO - PROCESS | 1655 | Values: 184.0 183.0 183.0 184.0 183.0 184.0 183.0 186.0 185.0 184.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#3] audio-fft Cycles:10 Average:65.70 Median:60.50 stddev:11.71 (19.4%) stddev-sans-first:11.03 09:08:50 INFO - PROCESS | 1655 | Values: 81.0 58.0 59.0 90.0 62.0 58.0 58.0 59.0 74.0 58.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#4] audio-oscillator Cycles:10 Average:74.80 Median:75.50 stddev:7.64 (10.1%) stddev-sans-first:7.34 09:08:50 INFO - PROCESS | 1655 | Values: 84.0 70.0 86.0 69.0 67.0 70.0 83.0 68.0 70.0 81.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:1.27 (1.4%) stddev-sans-first:1.33 09:08:50 INFO - PROCESS | 1655 | Values: 90.0 89.0 91.0 89.0 90.0 88.0 91.0 88.0 91.0 88.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#6] imaging-darkroom Cycles:10 Average:108.30 Median:101.50 stddev:21.09 (20.8%) stddev-sans-first:22.15 09:08:50 INFO - PROCESS | 1655 | Values: 100.0 100.0 100.0 114.0 167.0 100.0 102.0 101.0 100.0 99.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#7] imaging-desaturate Cycles:10 Average:92.70 Median:87.50 stddev:15.82 (18.1%) stddev-sans-first:16.59 09:08:50 INFO - PROCESS | 1655 | Values: 86.0 95.0 137.0 88.0 86.0 87.0 86.0 89.0 85.0 88.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#8] json-parse-financial Cycles:10 Average:54.90 Median:55.50 stddev:1.45 (2.6%) stddev-sans-first:1.48 09:08:50 INFO - PROCESS | 1655 | Values: 56.0 54.0 54.0 52.0 54.0 57.0 56.0 55.0 56.0 55.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:72.80 Median:73.50 stddev:4.44 (6.0%) stddev-sans-first:3.97 09:08:50 INFO - PROCESS | 1655 | Values: 66.0 78.0 79.0 78.0 75.0 70.0 69.0 71.0 70.0 72.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#10] stanford-crypto-aes Cycles:10 Average:81.30 Median:83.00 stddev:2.71 (3.3%) stddev-sans-first:2.80 09:08:50 INFO - PROCESS | 1655 | Values: 83.0 82.0 78.0 81.0 83.0 82.0 83.0 83.0 83.0 75.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:151.90 Median:147.00 stddev:18.86 (12.8%) stddev-sans-first:19.66 09:08:50 INFO - PROCESS | 1655 | Values: 142.0 140.0 142.0 149.0 145.0 151.0 140.0 141.0 199.0 170.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:173.50 Median:168.50 stddev:19.93 (11.8%) stddev-sans-first:21.00 09:08:50 INFO - PROCESS | 1655 | Values: 167.0 169.0 230.0 165.0 166.0 170.0 168.0 169.0 166.0 165.0 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:5.50 (8.3%) stddev-sans-first:5.80 09:08:50 INFO - PROCESS | 1655 | Values: 68.0 66.0 67.0 65.0 66.0 67.0 62.0 57.0 66.0 79.0 09:08:50 INFO - PROCESS | 1655 | -------- Summary: end -------- 09:08:50 INFO - PROCESS | 1655 | 09:08:50 INFO - PROCESS | 1655 | 1488042530165 addons.xpi WARN Add-on disableSHA1rollout@mozilla.org is missing bootstrap method shutdown 09:08:51 INFO - TEST-INFO | 1655: exit 0 09:08:51 INFO - TEST-OK | kraken | took 59237ms 09:08:51 INFO - SUITE-END | took 1177s 09:08:51 INFO - Completed test suite (00:19:37) 09:08:51 INFO - javascript benchmark 09:08:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3376.9493457598137, 3399.114055391493, 3401.183715908183, 3401.659834016597, 3458.273762509873, 5784.0, 5807.883842323162, 5816.214811000514, 5838.678872662002, 5861.824145275638, 5772.509336706385, 5838.357780644125, 5860.706964651772, 5871.677057761822, 5908.88182236355, 5245.318108645871, 5246.213068039787, 5296.470352964692, 5301.0723123453245, 5387.703676297802, 5198.298229738974, 5233.816816411427, 5353.491418315254, 5355.946440535584, 5359.9732001339935, 4365.388845561624, 5270.947290527103, 5352.0, 5353.5984801139875, 5364.168553874136, 5311.2298716686055, 5321.574274058066, 5352.090144675414, 5356.4107948125675, 5359.9732001340135, 5219.010259805563, 5274.498922602346, 5276.12943864262, 5282.31329927107, 5300.284461597674, 5647.237622920894, 5700.144978253295, 5700.230468886689, 5764.394738552433, 5808.82573522795, 6239.563230573862, 6339.11252424661, 6348.079528468346, 6351.237851457809, 6351.872962540723, 6258.906116408258, 6286.119943207955, 6299.968500157515, 6301.40136687014, 6301.936980630181, 5758.337791154071, 5880.235569375954, 5899.174115623816, 5930.9406905931255, 5943.791967281124, 6225.439710426083, 6253.6873156342, 6256.124142620037, 6273.435390814849, 6285.2457705075685, 6227.782027629011, 6234.345393733593, 6243.687815609202, 6246.156768836149, 6249.9062514062325, 5876.648370874637, 6164.506839452833, 6184.783532576338, 6318.147050148263, 6325.240971083499, 6104.3590423005835, 6152.138700581922, 6155.415235552616, 6210.658413787285, 6312.842178945563, 6270.592411493239, 6335.11308416822, 6337.556371053982, 6354.142190804182, 6359.109724638462, 5018.197088465828, 5042.596592272609, 5062.0382127396415, 5067.366579177603, 5080.314157588752, 4429.092036132585, 4458.041521072985, 4485.192665320208, 4515.81936722534, 4528.682992190581, 5713.285839270091, 5721.771129154788, 5750.02249617566, 5756.107803290497, 5834.299884013861, 6202.34875338092, 6245.24445372761, 6253.343398943137, 6270.655113968684, 6276.843078923063, 5830.416958304136, 5833.679147646835, 5873.442023007808, 5892.882142357129, 5957.49361304294, 5432.0, 5496.367917689437, 5533.944660553423, 5536.307961504812, 5558.77764889408, 6205.689715514206, 6247.812565623039, 6249.187605611241, 6263.404976527223, 6292.811215663537, 6301.8109456716375, 6325.272593651789, 6334.398232167937, 6343.0485427186295, 6347.650879201688, 6344.651044192614, 6351.936480635134, 6356.523260755369, 6360.650164240826, 6366.522510811615, 6127.816165515042, 6147.016477363601, 6159.6612186330185, 6161.84595385119, 6257.874842503215, 5593.932612209374, 6060.969695151496, 6297.275813281348, 6326.936730632819, 6334.8733025338315, 6047.66737829406, 6144.38556144382, 6206.286277078194, 6231.4391704746795, 6236.719347629277, 6014.428629280212, 6077.361877003028, 6133.785317513865, 6223.751049957951, 6226.937730622636, 5892.646441213541, 5960.046392577166, 6043.184170137151, 6073.635581865102, 6077.817665470043, 6206.291090116453, 6224.408681175462, 6225.439710426083, 6233.844153896189, 6240.126382306572, 5857.26784151981, 5974.701264936823, 6094.969525152524, 6096.177016102769, 6139.140520327069], "value": 5765.27524591806, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [455.88374964384076, 478.26586190198054, 493.4005183701802, 515.6081378152603, 522.0863488894435, 31729.524057139133, 35714.8214258929, 41511.79244103779, 43576.78211608937, 43639.563604363946, 45782.542174578244, 46024.769876150574, 46134.30798538025, 46157.53842461574, 46239.76880115603, 8320.70877519287, 8340.416170868042, 8570.057293697688, 9340.57967391469, 9568.330216884786, 9808.215342772597, 11273.0, 11536.653900383002, 11665.0, 11685.824712629317, 11294.774104517905, 11375.544978200862, 11380.430978451044, 11396.97427231549, 11409.828852567218, 10943.726406839893, 11491.0, 11544.711382215428, 11554.422278886064, 11562.0, 10480.266381353309, 11502.367369794658, 11516.827247591293, 11525.827112593317, 11536.192466527347, 42385.57614423878, 42705.57294427078, 42783.0, 42822.0, 42855.785721071195, 3947.1142176361855, 3977.54258260298, 4029.2747305485, 4037.9192416151513, 4062.1672557125717, 29840.8507957461, 30444.8477757612, 31030.68969310323, 32041.359172816414, 32119.19702007445, 3128.655847856734, 3140.277736120682, 3254.2027203335333, 3285.4578994465883, 3388.9830550847337, 367775.0, 368818.0, 369219.0, 369509.0, 371934.0, 67401.32598674048, 67908.98136527956, 67965.66017169882, 68124.65937670378, 68170.3182968174, 70794.64602677057, 72794.0, 72867.0, 72908.0, 73019.63490182621, 17012.74480882763, 17297.221625026905, 17306.048167350917, 17308.221130049176, 17345.0, 11767.470463829148, 12023.27860328383, 12064.939675301568, 13102.606921792361, 13192.670183245496, 19081.63364744636, 22419.663705044437, 22432.88783556072, 22942.770572294063, 23041.193558225383, 8095.433319667684, 8147.103818579952, 8621.698240561676, 8855.468671879707, 8953.731388058368, 861.8405594964826, 876.6186708781573, 891.2112780189578, 908.4322298563397, 914.6295750221171, 27367.45265094687, 30634.387312254075, 30780.38439231203, 31073.5814277883, 31293.68706312953, 1064.893510648929, 1067.2475904487474, 1107.8338249262674, 1113.716002419394, 1192.3025030357164, 15377.692446151013, 15800.683986320439, 16192.838071619133, 16436.917815411085, 16495.175241237892, 663644.0, 672635.0, 673680.3159842086, 678919.0, 679362.0, 48967.510324897004, 49165.0, 49218.26172607414, 49330.766730832016, 49458.25812612814, 1409.2249262905361, 1449.7245523350741, 1471.9190444525655, 1474.4176050460223, 1480.222882986419, 28806.711932880404, 30177.698223017487, 30625.02811951648, 30709.385812284076, 30873.4041283856, 56097.43902561085, 56635.0, 56658.716706416206, 56742.0, 56752.432475674716, 580138.0, 581894.0, 583917.0, 583942.0, 584005.0, 36182.11541441872, 36580.81709591435, 44829.77585112053, 44966.1006779856, 45625.31562026506, 47840.521594783604, 49418.258726118416, 49525.0, 50160.74919625378, 50411.49588504068, 37538.06154846041, 37688.43467348047, 40162.1967560653, 40703.38944915888, 41733.74798756042, 40266.20803110722, 40387.596124038384, 40666.79666601648, 41087.589124108374, 41732.79133604313, 38514.0371490715, 38854.0, 39292.60707393004, 39498.2100357997, 39748.20503589854, 31115.533267001465, 32475.0, 34565.48151777675, 34652.0, 35255.64744352524, 24307.756922430548, 25152.13386781971, 25292.873535632203, 25812.483750325264, 25949.0], "value": 21032.092308648353, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [385.15074261253955, 386.947762052123, 393.3745344901606, 394.7789238026704, 420.43451557654964, 13754.58736237912, 20142.295019674315, 21044.263450779188, 21429.464263393424, 21476.463088422777, 21677.783222167775, 21895.562088758215, 22104.447388815246, 22268.99789509472, 22501.54996900061, 4940.505949405057, 6024.849378765544, 6026.9095963560585, 6028.065649824284, 6029.155918171459, 5705.971470142644, 5870.823875283749, 5912.379200183983, 5929.970350148244, 5961.612495187821, 5793.188953546507, 5810.796622118227, 5889.499392551638, 5898.705064746767, 5911.6748578828365, 5623.26897503324, 5734.856628584298, 5804.013317735995, 5816.5346772258545, 5871.236739223895, 5134.332536770196, 5140.1004824155625, 5145.022445735298, 5145.3568303962, 5146.9485305146845, 24143.63784543233, 24408.779561021878, 27352.58971115435, 28415.289617759514, 29445.55831662527, 2969.094426200008, 2979.8212107273635, 2994.9850250748823, 3025.8940937067096, 3031.681673424303, 16907.91546042274, 17184.656306873792, 17439.21523531444, 17668.558286042822, 18293.0, 1668.2659629762866, 1998.680211166221, 2008.2167954497756, 2022.9898850505654, 2029.8376129909573, 168819.46770798447, 171984.0, 174830.0, 178830.0, 181831.0, 24125.91433385502, 24227.75772242255, 24299.757002430102, 24303.14938977127, 24546.895389707504, 34537.827310863286, 35704.82147589245, 44003.89990250269, 44080.559194408284, 44214.33678494825, 9995.900040999642, 11086.22396432242, 11132.165087618461, 11625.941870290595, 11637.650870473899, 9240.815183696288, 9257.444553326823, 9281.72154835356, 9393.436393816393, 9414.16423797353, 9199.666048422941, 10890.649949730981, 11226.438678066064, 11233.943830280796, 11361.772764544663, 3611.7178401667556, 4100.282450571138, 4240.321548552217, 4244.175004747988, 4266.765327906933, 946.2288235088381, 955.9139677429065, 989.2135752077116, 989.6091044037565, 1046.2414749306693, 18622.720659190123, 20669.689954650694, 20772.58454830895, 20774.961251937646, 21254.46863828417, 983.9262055345878, 990.4153354632674, 1007.6926537406015, 1023.9846402303972, 1024.288119756762, 10606.363618182933, 11223.494942727597, 11227.55089796399, 11238.213325067167, 11250.987411132874, 768546.0, 775466.0, 782725.0, 785260.0, 799532.0, 115258.0, 120784.0, 123001.0, 133972.33013834868, 144258.5221753422, 979.583676937313, 989.4014121456559, 998.9250806189565, 1003.1322905686675, 1005.331454582709, 17641.0, 18566.0, 18761.812381876007, 18773.718394223826, 18816.341428049953, 37737.43393849149, 54609.0, 58671.70664146823, 58911.82176356535, 59889.0, 85574.14425855661, 87424.0, 88768.5561572188, 88970.0, 89675.0, 8205.630746616298, 8371.790705232173, 8427.41008129425, 8554.059053503985, 8680.522571258389, 12192.841680040381, 12660.240385576895, 13228.0, 13358.265295408457, 13524.59426217215, 11572.30566165999, 11577.479013444246, 11658.0, 11670.70823229426, 11829.585964491202, 11091.445427728742, 11339.603113891304, 11647.068234541048, 11678.532858685898, 11822.995045420892, 11554.653360399201, 11696.415179240834, 11837.289762614271, 11947.22343047734, 11957.521699131938, 7229.819254518679, 7410.814729631802, 7552.962235188789, 7822.022247219098, 8587.742367728979, 9664.0, 11231.326120432801, 11444.141689373164, 11533.365664888512, 11565.363904985306], "value": 11226.07255650178, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1304.2565737529605, 1422.9217393043384, 1448.4351103069798, 1449.0725935401342, 1452.0779305141232, 8042.879356809645, 8449.408541402088, 8459.069502354736, 8460.57697115144, 8463.28062114721, 6702.832429189272, 7469.962650186755, 7918.247766462191, 8191.467554608947, 8262.586870656458, 2310.9653355199684, 2350.3536527454917, 2356.2813341930705, 2356.6347216181434, 2356.9882150589224, 2304.0, 2344.0272287000876, 2352.564775516535, 2355.599548076831, 2362.0197617988515, 2045.4988527810708, 2126.1601667341392, 2248.2243625949104, 2281.441046943501, 2309.7574754650686, 2322.036354912709, 2337.5792357375667, 2339.0760649543336, 2339.4736184358385, 2342.1217043608563, 2031.8882461464614, 2265.8980345884474, 2318.9304320870233, 2320.2923108451914, 2349.835511514195, 8252.669893204265, 8321.500709957421, 8334.1249168837, 8338.374621903382, 8340.0, 4335.479742430928, 5040.521150490697, 5194.92207616886, 5195.714235717034, 5217.34783152106, 3274.607047154333, 3427.7600567960253, 3437.432823584106, 3496.4230901901155, 3498.387782138141, 1145.2956431794469, 1199.2684462477882, 1209.5666635037421, 1230.39095647655, 1236.394166858242, 10812.837807432894, 10826.566937322497, 10828.72928176794, 10853.945730271298, 10865.782684346348, 4535.0, 4702.788374523155, 4718.905621887543, 4725.5983241424865, 4758.595519380822, 8662.436941598777, 8723.651053957898, 8724.563771811385, 8734.257588105082, 8767.386282960131, 3885.4948856648457, 3959.5842436544326, 5076.796928122833, 5095.261187127846, 5096.847094587094, 4534.161180181677, 4654.674172807939, 4675.0883577702025, 4755.096531658974, 4771.33201351811, 3695.519582454264, 3752.3996160614715, 3802.3269761304937, 3812.9809350953624, 3857.283508838596, 1161.5586077290745, 1168.9070718877877, 1174.7356844710043, 1186.163754553038, 1186.839776630144, 765.5406755946387, 769.2461387839836, 796.1680044353665, 797.960101994898, 812.6627449608465, 5330.307060082242, 5377.193420986883, 5419.729013549307, 5538.280023596906, 5713.628614140068, 534.1400345443832, 540.4811381074135, 561.5872333834628, 575.5539456920919, 579.4234736437272, 3436.0722604896537, 3452.706519945833, 3476.1309672581856, 3561.074120728629, 3767.9811600942367, 7986.800329991796, 9958.402495850272, 10038.397696138109, 10064.69805905824, 10071.0, 9999.450030248407, 10000.000000000087, 10001.24990625706, 10023.949880250699, 10103.444310562989, 6683.699233534406, 7740.26467485588, 7858.803529911798, 7862.685492580233, 7878.684852605831, 5398.892022159614, 5453.291072160594, 5621.269234999424, 5648.830535083954, 5722.542196624343, 8828.690995815115, 8959.820803584022, 9251.629934802533, 9339.346245762732, 9362.812743745224, 8541.273991710774, 8830.602622882114, 8953.955230223808, 9249.953750231205, 9431.62273509052, 6602.801915942529, 7143.964280178566, 7172.748953786592, 7261.273872612696, 7276.745313913988, 6784.185897692309, 7614.2766437188375, 7827.25641064098, 8024.317932975762, 8026.277635012877, 6981.0, 7035.929640703527, 7095.254998225112, 7097.183823860322, 7127.358537731629, 6934.687939042654, 6967.338102880219, 6980.6509674515055, 6998.475114366341, 7021.719131234693, 6322.925102732454, 6330.01884707871, 6365.777197798055, 6386.904196436964, 6389.424951754364, 4195.804195804181, 4565.657575681904, 4877.6829506081995, 4913.803447862046, 5016.448190699093, 4888.706677599355, 4942.555170034714, 4964.478729733326, 5027.824026159067, 5037.647364684583], "value": 4268.691387046202, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.6681585677749, 349.5647918341665, 354.09352058729655, 365.0272025053233, 368.94650275710006, 4891.192953162724, 5261.736913154336, 5271.841844744659, 5318.840434786953, 5360.855657736903, 4261.382099595557, 5010.795250089967, 5030.5921134780165, 5185.455527169649, 5266.947330526703, 3566.3402270580027, 3831.9042023949487, 4143.21278956998, 4146.3780432935155, 4161.916761664765, 3682.668559829615, 3778.5843557208686, 3969.900752481197, 4139.820151256892, 4261.318189089746, 3653.4885116083638, 3785.791781452019, 3990.7406018608845, 4091.897702557445, 4111.342185250361, 3956.5647778744315, 3964.226975739732, 3965.6232657897453, 3965.9206815863668, 4003.4395184674167, 3434.8969530914114, 3443.1908501502126, 3458.0, 3462.3207744244023, 3474.669906358917, 4275.722078064916, 5084.542391184774, 5116.641835071547, 5159.71621560814, 5161.896762064775, 3675.096673627831, 3895.590962948906, 4355.236129367611, 4513.27787553993, 4538.52345503724, 4589.449266088058, 5495.395506494282, 5509.47659972302, 5510.9448905510835, 5522.3649280332875, 3286.075392900321, 3528.923678278124, 3562.340966921128, 3691.464737613031, 3866.5553461352024, 4714.316424118518, 5309.734513274321, 5504.752286147133, 5732.054211055212, 5803.172000819771, 3272.8199949002556, 3383.796972181677, 3756.7978246961256, 3818.236352729465, 3824.388097904322, 3066.5706801047872, 3671.724620653462, 3892.9416058759143, 3900.5904380040256, 3901.5903330150495, 4036.6972477064337, 4263.765492897858, 4511.571400716926, 4516.706414083074, 4575.22221222393, 5287.4976877196605, 5540.252065971043, 5607.130894711327, 5614.905093506809, 5638.577106717013, 1067.1356201476829, 1205.37320593291, 1219.688979310288, 1239.293602646483, 1241.4599649152647, 805.2470939671426, 818.5293456262673, 826.5288785392269, 827.1315119124895, 847.0216899481109, 4408.889777755582, 4543.318502224693, 4850.1269771441475, 4887.9490909454635, 4900.754962251873, 4433.248866697636, 4617.836781804474, 4773.706142761553, 5081.415637201695, 5386.0, 3901.005243662848, 4318.287482565342, 4733.928991065137, 4845.418549773979, 4960.280759289883, 2497.963345211717, 2744.929477503775, 2812.54999200127, 2812.662480502353, 2853.5576985567272, 5560.026995275892, 5562.3047119110115, 5609.943900561024, 5623.718814059281, 5681.630694004876, 4941.357623508921, 4971.6271279654175, 5012.398512178489, 5233.0, 5291.8567979968175, 4894.094592500399, 5027.547520723152, 5184.300119483822, 5335.319374397131, 5557.44425557441, 4675.78958946855, 5055.191169413023, 5170.069387510134, 5342.813001545082, 5370.702265000661, 4575.0, 4780.90039290972, 4955.017992802907, 5275.829686906776, 5325.946740532545, 4729.929051064306, 5321.175217841241, 5504.477194524501, 5550.250716153268, 5602.2717046783655, 3089.3626378019685, 3108.1918701137415, 3147.464930961696, 3606.0804494853646, 3615.3492371373404, 4587.793549290223, 4714.646401519831, 4920.311156438173, 5150.969806038733, 5369.4093649697725, 4689.671722979495, 5185.107435785986, 5193.298904647975, 5195.896082078413, 5246.685198887926, 4875.708896494923, 5120.539151476385, 5183.604738896029, 5194.117000109914, 5197.7141257231215, 4360.878969373911, 4822.819005244039, 5020.899582008413, 5068.873278168076, 5084.754235212396, 4940.802367905244, 5093.949060509496, 5327.467253274642, 5340.305760251298, 5341.946580534145, 4571.794269257958, 4585.495595484427, 5367.865803354947, 5404.162354835007, 5424.779424629427], "value": 3888.9239397418155, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [686.4714170089028, 723.7394537966331, 746.2201998911137, 809.5952023988006, 810.5825499867568, 14895.77656335156, 15818.525444236677, 15848.920755396206, 15857.60355991098, 15913.44302949397, 13216.93391533041, 13584.796228056586, 14087.57737267881, 14448.133112013287, 14575.489857854976, 847.0428415890036, 868.3834477520968, 870.2733217763175, 870.3124531620012, 871.9667194374687, 676.9492288078413, 694.1427337238506, 711.4095300900241, 848.4145939301893, 848.4697064334791, 741.9406447484188, 890.6348397157135, 905.3798148268457, 916.0564618443015, 928.6888892221114, 875.3172525430175, 905.5608030105394, 915.871777951084, 918.9908100919006, 920.1304766995192, 532.2149829002229, 683.3405763438257, 684.090160087088, 684.3737979748524, 685.3318014935448, 12280.263184208887, 14523.20122393268, 14999.850001499954, 15085.0, 15389.538313850602, 13614.591562253148, 14285.57143285703, 14477.34851931655, 14511.346989385504, 14523.564293071226, 10174.0, 10421.801492828346, 10510.896355882674, 10695.425587777814, 11147.944260278648, 3561.928761424757, 3698.500702405168, 3715.1640880801874, 3761.360568703327, 3829.2341531693774, 99411.50882736765, 108478.0, 109027.90972090336, 109814.45092774565, 109906.90093099046, 10643.41461219625, 10660.733481663021, 10731.785364292671, 10736.921955942282, 11328.773424531464, 22095.44761380946, 22373.552528949655, 22839.771602283763, 22895.198668046538, 22938.885305573367, 7870.29167374939, 7985.560794156378, 8010.239027292398, 8018.71934482302, 8059.153788852203, 4209.877708816784, 4231.111466591989, 4304.677149213821, 4357.041450880801, 4359.215341238608, 3494.353544594258, 3592.964070359263, 3595.9640403596154, 3596.8021758803516, 3599.6220396857952, 637.1589501857503, 650.0249625561657, 656.6060363781788, 656.7143292667705, 677.3937326093119, 553.261396036288, 556.4963707844407, 561.8342588936274, 588.4145275450953, 602.9788957386559, 9877.308588398742, 10076.143527800072, 10212.489375531193, 10282.434466104436, 10468.214883883738, 685.6126288646998, 697.6375455842747, 698.4168219536743, 699.8740226759232, 705.6511641755482, 4411.580899814512, 4953.331300274417, 4954.133026720309, 4961.871983503614, 4968.056069346885, 95788.08423831624, 104969.47515262375, 105745.0, 108361.0, 111460.442697786, 88035.0, 92879.60680589797, 93300.53349733344, 95191.0, 95991.04008959961, 70635.29364706493, 85533.0, 85989.0, 86386.56806715926, 86424.56787716148, 9366.438013719198, 9732.221422286342, 9747.658831940847, 9751.927287998173, 9767.706968790948, 52693.47306527039, 53225.66935826481, 53377.46622533725, 54617.08840190574, 55310.170347444015, 69813.0, 70133.0, 70560.64719676369, 71211.0, 73367.63316183384, 11027.558897644005, 11268.774624507629, 11455.713607159554, 11816.645500634651, 11978.700532486757, 12573.0, 12713.87286127127, 12924.483020679067, 13330.86669133335, 13344.0, 11577.536898524302, 11702.590409335633, 11898.22661526998, 11910.166288360078, 12166.087543434101, 11611.187216894736, 11961.461734221806, 11965.76068478643, 12167.39163041862, 12538.749225015632, 11432.439696966574, 11683.123765717435, 11787.351695656822, 11954.462049207632, 12046.277223366626, 9357.438553686801, 9816.411015339103, 10043.672446746561, 10286.588536458457, 10459.267851250339, 9683.96694544582, 9769.511524423608, 9803.264755143535, 9908.87304505544, 9915.900840991786], "value": 7334.796321469823, "unit": "score"}], "name": "dromaeo_css", "value": 7411.6753680567, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 117.0, 117.0, 115.0, 114.0, 116.0, 115.0, 114.0, 110.0, 111.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 111.0, 107.0, 148.0, 111.0, 92.0, 129.0, 139.0, 138.0, 110.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 183.0, 184.0, 183.0, 184.0, 183.0, 186.0, 185.0, 184.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 58.0, 59.0, 90.0, 62.0, 58.0, 58.0, 59.0, 74.0, 58.0], "value": 65.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 86.0, 69.0, 67.0, 70.0, 83.0, 68.0, 70.0, 81.0], "value": 74.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 89.0, 91.0, 89.0, 90.0, 88.0, 91.0, 88.0, 91.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 100.0, 100.0, 114.0, 167.0, 100.0, 102.0, 101.0, 100.0, 99.0], "value": 108.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 95.0, 137.0, 88.0, 86.0, 87.0, 86.0, 89.0, 85.0, 88.0], "value": 92.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 54.0, 54.0, 52.0, 54.0, 57.0, 56.0, 55.0, 56.0, 55.0], "value": 54.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 78.0, 79.0, 78.0, 75.0, 70.0, 69.0, 71.0, 70.0, 72.0], "value": 72.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 78.0, 81.0, 83.0, 82.0, 83.0, 83.0, 83.0, 75.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 140.0, 142.0, 149.0, 145.0, 151.0, 140.0, 141.0, 199.0, 170.0], "value": 151.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 169.0, 230.0, 165.0, 166.0, 170.0, 168.0, 169.0, 166.0, 165.0], "value": 173.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 67.0, 65.0, 66.0, 67.0, 62.0, 57.0, 66.0, 79.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1452.6, "alertThreshold": 2.0}]} 09:08:51 INFO - Return code: 0 09:08:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:08:51 ERROR - # TBPL SUCCESS # 09:08:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:08:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:08:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:08:51 INFO - 2017-02-25 09:08:51.412 screenresolution[1659:11391] starting screenresolution argv=screenresolution get 09:08:51 INFO - 2017-02-25 09:08:51.415 screenresolution[1659:11391] Display 0: 1600x1200x32@60 09:08:51 INFO - 2017-02-25 09:08:51.421 screenresolution[1660:11394] starting screenresolution argv=screenresolution list 09:08:51 INFO - Available Modes on Display 0 09:08:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:08:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:08:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:08:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:08:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:08:51 INFO - Intel Iris: 09:08:51 INFO - Chipset Model: Intel Iris 09:08:51 INFO - Type: GPU 09:08:51 INFO - Bus: Built-In 09:08:51 INFO - VRAM (Dynamic, Max): 1536 MB 09:08:51 INFO - Vendor: Intel (0x8086) 09:08:51 INFO - Device ID: 0x0a2e 09:08:51 INFO - Revision ID: 0x0009 09:08:51 INFO - Displays: 09:08:51 INFO - Display: 09:08:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:08:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:08:51 INFO - Display Serial Number: 1600x1200 60 09:08:51 INFO - Main Display: Yes 09:08:51 INFO - Mirror: Off 09:08:51 INFO - Online: Yes 09:08:51 INFO - Rotation: Supported 09:08:51 INFO - Return code: 0 09:08:51 INFO - Running post-action listener: _resource_record_post_action 09:08:51 INFO - [mozharness: 2017-02-25 17:08:51.666465Z] Finished run-tests step (success) 09:08:51 INFO - Running post-run listener: _resource_record_post_run 09:08:51 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 32349184; Write bytes: 509597696; Read time: 192; Write time: 1582 09:08:51 INFO - TinderboxPrint: CPU usage
53.8% 09:08:51 INFO - TinderboxPrint: I/O read bytes / time
32,349,184 / 192 09:08:51 INFO - TinderboxPrint: I/O write bytes / time
509,597,696 / 1,582 09:08:51 INFO - TinderboxPrint: CPU idle
2,212.6 (46.2%) 09:08:51 INFO - TinderboxPrint: CPU system
959.6 (20.0%) 09:08:51 INFO - TinderboxPrint: CPU user
1,621.5 (33.8%) 09:08:51 INFO - TinderboxPrint: Swap in / out
235,884,544 / 0 09:08:51 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 203534848; Write bytes: 201127424; Read time: 15710; Write time: 448 09:08:51 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 30383104; Write bytes: 297771520; Read time: 175; Write time: 1103 09:08:52 INFO - Running post-run listener: _upload_blobber_files 09:08:52 INFO - Blob upload gear active. 09:08:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:08:52 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:08:52 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'] 09:08:52 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 09:08:52 INFO - (blobuploader) - INFO - Open directory for files ... 09:08:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:08:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:53 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:08:53 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:53 INFO - (blobuploader) - INFO - Done attempting. 09:08:53 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:08:53 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:53 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:08:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:54 INFO - (blobuploader) - INFO - Done attempting. 09:08:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:08:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:55 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:08:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:55 INFO - (blobuploader) - INFO - Done attempting. 09:08:55 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:08:55 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:08:55 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:08:58 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:08:58 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:08:58 INFO - (blobuploader) - INFO - Done attempting. 09:08:58 INFO - (blobuploader) - INFO - Iteration through files over. 09:08:58 INFO - Return code: 0 09:08:58 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:08:58 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:08:58 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/462ea12b3f6ab0e9c1db9e6e5b1202e1f23f5ac4283cf11a264ff225ec9c32b819d3c7bb9504c5c69c012f1c6887ad781f37c0cc9a028403827a3ffde7b546b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2013c9e446050b0b24efaba073bfbb440f69a00efe648dd97434d95644424a4e69545028b47d993b26f8d50a3b41e766615e8282e8ecf2885e1904bd16e3ce88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc8bda156ff1d19944cbada3a299a802e2f3036d0d6b61e06f9b1dbd70dbc77ba336d09fccebffaa591775cf3cb0f03e048b043cfc1ffa0fe5915410990a129", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ac7a384e8a5354fabbe5d854b493d30878a067c6709f2b7cfff7767c4df1ba20c6149264d234a5e35fac08b90d06270e168834b7021bb697a5ab52e7b35b8e9"} 09:08:58 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:08:58 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:08:58 INFO - Contents: 09:08:58 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/462ea12b3f6ab0e9c1db9e6e5b1202e1f23f5ac4283cf11a264ff225ec9c32b819d3c7bb9504c5c69c012f1c6887ad781f37c0cc9a028403827a3ffde7b546b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2013c9e446050b0b24efaba073bfbb440f69a00efe648dd97434d95644424a4e69545028b47d993b26f8d50a3b41e766615e8282e8ecf2885e1904bd16e3ce88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc8bda156ff1d19944cbada3a299a802e2f3036d0d6b61e06f9b1dbd70dbc77ba336d09fccebffaa591775cf3cb0f03e048b043cfc1ffa0fe5915410990a129", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ac7a384e8a5354fabbe5d854b493d30878a067c6709f2b7cfff7767c4df1ba20c6149264d234a5e35fac08b90d06270e168834b7021bb697a5ab52e7b35b8e9"} 09:08:58 INFO - Running post-run listener: copy_logs_to_upload_dir 09:08:58 INFO - Copying logs to upload dir... 09:08:58 INFO - mkdir: /builds/slave/test/build/upload/logs 09:08:58 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.952884 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-02-25 09:08:58.079648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 09:08:58.083007) ========= 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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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/462ea12b3f6ab0e9c1db9e6e5b1202e1f23f5ac4283cf11a264ff225ec9c32b819d3c7bb9504c5c69c012f1c6887ad781f37c0cc9a028403827a3ffde7b546b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2013c9e446050b0b24efaba073bfbb440f69a00efe648dd97434d95644424a4e69545028b47d993b26f8d50a3b41e766615e8282e8ecf2885e1904bd16e3ce88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc8bda156ff1d19944cbada3a299a802e2f3036d0d6b61e06f9b1dbd70dbc77ba336d09fccebffaa591775cf3cb0f03e048b043cfc1ffa0fe5915410990a129", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ac7a384e8a5354fabbe5d854b493d30878a067c6709f2b7cfff7767c4df1ba20c6149264d234a5e35fac08b90d06270e168834b7021bb697a5ab52e7b35b8e9"} build_url:https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011256 build_url: 'https://queue.taskcluster.net/v1/task/U6_X7ydNT7WUnJQB-G7lPg/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/462ea12b3f6ab0e9c1db9e6e5b1202e1f23f5ac4283cf11a264ff225ec9c32b819d3c7bb9504c5c69c012f1c6887ad781f37c0cc9a028403827a3ffde7b546b4", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2013c9e446050b0b24efaba073bfbb440f69a00efe648dd97434d95644424a4e69545028b47d993b26f8d50a3b41e766615e8282e8ecf2885e1904bd16e3ce88", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/bbc8bda156ff1d19944cbada3a299a802e2f3036d0d6b61e06f9b1dbd70dbc77ba336d09fccebffaa591775cf3cb0f03e048b043cfc1ffa0fe5915410990a129", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2ac7a384e8a5354fabbe5d854b493d30878a067c6709f2b7cfff7767c4df1ba20c6149264d234a5e35fac08b90d06270e168834b7021bb697a5ab52e7b35b8e9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-25 09:08:58.121084) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 09:08:58.121400) ========= 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.SrPwDWHw0u/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.Jp0uklKc4t/Listeners TMPDIR=/var/folders/k_/0p5m47c95ynggg32ny3_mlwh00000w/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.005084 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-25 09:08:58.169339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 09:08:58.169650) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-25 09:08:58.169959) ========= ========= Total master_lag: 0.19 =========