builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0017 starttime: 1486399149.51 results: success (0) buildid: 20170206072755 builduid: 5782f978260940a087b740ebfcd0907e revision: 65e5f8c2d8160bb7a078726ccad358f647418959 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.505901) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.506350) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.506655) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.534649) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.535388) ========= 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.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-06 08:39:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.3M=0.001s 2017-02-06 08:39:09 (14.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.143911 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.703959) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.704293) ========= 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.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.026486 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:09.752734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-06 08:39:09.753102) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 65e5f8c2d8160bb7a078726ccad358f647418959 --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 65e5f8c2d8160bb7a078726ccad358f647418959 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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-06 08:39:09,856 truncating revision to first 12 chars 2017-02-06 08:39:09,856 Setting DEBUG logging. 2017-02-06 08:39:09,856 attempt 1/10 2017-02-06 08:39:09,856 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/65e5f8c2d816?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-06 08:39:10,881 unpacking tar archive at: mozilla-aurora-65e5f8c2d816/testing/mozharness/ program finished with exit code 0 elapsedTime=1.281537 ========= master_lag: 1.84 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2017-02-06 08:39:12.874782) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:12.875137) ========= script_repo_revision: 65e5f8c2d8160bb7a078726ccad358f647418959 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-06 08:39:12.875553) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-02-06 08:39:12.875825) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-02-06 08:39:14.321419) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-06 08:39:14.321800) ========= /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.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:14 INFO - MultiFileLogger online at 20170206 08:39:14 in /builds/slave/test 08:39:14 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:39:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:39:14 INFO - {'append_to_log': False, 08:39:14 INFO - 'base_work_dir': '/builds/slave/test', 08:39:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:39:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:39:14 INFO - 'branch': 'Mozilla-Aurora', 08:39:14 INFO - 'buildbot_json_path': 'buildprops.json', 08:39:14 INFO - 'config_files': ('talos/mac_config.py',), 08:39:14 INFO - 'default_actions': ('clobber', 08:39:14 INFO - 'read-buildbot-config', 08:39:14 INFO - 'download-and-extract', 08:39:14 INFO - 'populate-webroot', 08:39:14 INFO - 'create-virtualenv', 08:39:14 INFO - 'install', 08:39:14 INFO - 'run-tests'), 08:39:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:39:14 INFO - 'download_minidump_stackwalk': True, 08:39:14 INFO - 'download_symbols': 'ondemand', 08:39:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:39:14 INFO - 'tooltool.py': '/tools/tooltool.py', 08:39:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:39:14 INFO - '/tools/misc-python/virtualenv.py')}, 08:39:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:39:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:39:14 INFO - 'installer_path': 'installer.exe', 08:39:14 INFO - 'log_level': 'info', 08:39:14 INFO - 'log_name': 'talos', 08:39:14 INFO - 'log_to_console': True, 08:39:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:39:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:39:14 INFO - 'opt_config_files': (), 08:39:14 INFO - 'pip_index': False, 08:39:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:14 INFO - 'cmd': ('bash', 08:39:14 INFO - '-c', 08:39:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:14 INFO - 'enabled': True, 08:39:14 INFO - 'halt_on_failure': False, 08:39:14 INFO - 'name': 'check_screen_resolution'},), 08:39:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:14 INFO - 'cmd': ('bash', 08:39:14 INFO - '-c', 08:39:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:14 INFO - 'enabled': True, 08:39:14 INFO - 'halt_on_failure': False, 08:39:14 INFO - 'name': 'check_screen_resolution'},), 08:39:14 INFO - 'run_cmd_checks_enabled': True, 08:39:14 INFO - 'sps_profile': False, 08:39:14 INFO - 'sps_profile_interval': 0, 08:39:14 INFO - 'suite': 'dromaeojs', 08:39:14 INFO - 'system_bits': '32', 08:39:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:39:14 INFO - 'title': 't-yosemite-r7-0017', 08:39:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:39:14 INFO - 'use_talos_json': True, 08:39:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:39:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:39:14 INFO - 'work_dir': 'build'} 08:39:14 INFO - [mozharness: 2017-02-06 16:39:14.538070Z] Running clobber step. 08:39:14 INFO - Running pre-action listener: _resource_record_pre_action 08:39:14 INFO - Running main action method: clobber 08:39:14 INFO - rmtree: /builds/slave/test/build 08:39:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:39:17 INFO - Running post-action listener: _resource_record_post_action 08:39:17 INFO - [mozharness: 2017-02-06 16:39:17.401872Z] Finished clobber step (success) 08:39:17 INFO - [mozharness: 2017-02-06 16:39:17.402067Z] Running read-buildbot-config step. 08:39:17 INFO - Running pre-action listener: _resource_record_pre_action 08:39:17 INFO - Running main action method: read_buildbot_config 08:39:17 INFO - Using buildbot properties: 08:39:17 INFO - { 08:39:17 INFO - "project": "", 08:39:17 INFO - "product": "firefox", 08:39:17 INFO - "script_repo_revision": "production", 08:39:17 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:39:17 INFO - "repository": "", 08:39:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:39:17 INFO - "buildid": "20170206072755", 08:39:17 INFO - "pgo_build": "False", 08:39:17 INFO - "basedir": "/builds/slave/test", 08:39:17 INFO - "buildnumber": 6, 08:39:17 INFO - "slavename": "t-yosemite-r7-0017", 08:39:17 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 08:39:17 INFO - "platform": "yosemite_r7", 08:39:17 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:39:17 INFO - "branch": "mozilla-aurora", 08:39:17 INFO - "repo_path": "releases/mozilla-aurora", 08:39:17 INFO - "revision": "65e5f8c2d8160bb7a078726ccad358f647418959", 08:39:17 INFO - "stage_platform": "macosx64", 08:39:17 INFO - "builduid": "5782f978260940a087b740ebfcd0907e", 08:39:17 INFO - "slavebuilddir": "test" 08:39:17 INFO - } 08:39:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:39:17 INFO - Running post-action listener: _resource_record_post_action 08:39:17 INFO - [mozharness: 2017-02-06 16:39:17.406230Z] Finished read-buildbot-config step (success) 08:39:17 INFO - [mozharness: 2017-02-06 16:39:17.406345Z] Running download-and-extract step. 08:39:17 INFO - Running pre-action listener: _resource_record_pre_action 08:39:17 INFO - Running main action method: download_and_extract 08:39:17 INFO - mkdir: /builds/slave/test/build/tests 08:39:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:17 INFO - trying https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:39:17 INFO - Downloading https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/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:39:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/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:39:17 INFO - Downloaded 1199 bytes. 08:39:17 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:39:17 INFO - Using the following test package requirements: 08:39:17 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:39:17 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:39:17 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:39:17 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'jsshell-mac.zip'], 08:39:17 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:39:17 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:39:17 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:39:17 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:39:17 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:39:17 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:39:17 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:39:17 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:39:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:39:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:39:17 INFO - Fetch https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:39:18 INFO - Expected file size: 16356137 08:39:18 INFO - Obtained file size: 16356137 08:39:25 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:39:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:39:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:39:25 INFO - Fetch https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:39:26 INFO - Expected file size: 16356137 08:39:26 INFO - Obtained file size: 16356137 08:39:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:39:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:39:33 INFO - Fetch https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:39:34 INFO - Expected file size: 14403485 08:39:34 INFO - Obtained file size: 14403485 08:39:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:34 INFO - trying https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:39:34 INFO - Downloading https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:39:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/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:39:36 INFO - Downloaded 73832158 bytes. 08:39:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:39:36 INFO - mkdir: /builds/slave/test/properties 08:39:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:39:36 INFO - Writing to file /builds/slave/test/properties/build_url 08:39:36 INFO - Contents: 08:39:36 INFO - build_url:https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:39:37 INFO - Running post-action listener: _resource_record_post_action 08:39:37 INFO - Running post-action listener: set_extra_try_arguments 08:39:37 INFO - [mozharness: 2017-02-06 16:39:37.306328Z] Finished download-and-extract step (success) 08:39:37 INFO - [mozharness: 2017-02-06 16:39:37.306477Z] Running populate-webroot step. 08:39:37 INFO - Running pre-action listener: _resource_record_pre_action 08:39:37 INFO - Running main action method: populate_webroot 08:39:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:39:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:39:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:39:37 INFO - '--tppagecycles', 08:39:37 INFO - '7'], 08:39:37 INFO - 'tests': ['tsvgm']}}, 08:39:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:39:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:39:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:39:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:39:37 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:37 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:39:37 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['damp', 'tps']}, 08:39:37 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'tests': ['damp', 'tps']}, 08:39:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['dromaeo_dom']}, 08:39:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:39:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['basic_compositor_video']}, 08:39:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:39:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'other-e10s': {'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['a11yr', 08:39:37 INFO - 'ts_paint', 08:39:37 INFO - 'tpaint', 08:39:37 INFO - 'sessionrestore', 08:39:37 INFO - 'sessionrestore_no_auto_restore', 08:39:37 INFO - 'tabpaint']}, 08:39:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['tsvgx', 08:39:37 INFO - 'tsvgr_opacity', 08:39:37 INFO - 'tart', 08:39:37 INFO - 'tscrollx', 08:39:37 INFO - 'cart', 08:39:37 INFO - 'tsvg_static']}, 08:39:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:39:37 INFO - 'tsvgr_opacity', 08:39:37 INFO - 'tart', 08:39:37 INFO - 'tscrollx', 08:39:37 INFO - 'cart', 08:39:37 INFO - 'tsvg_static']}, 08:39:37 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'talos_options': ['--disable-e10s'], 08:39:37 INFO - 'tests': ['tp5o']}, 08:39:37 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:39:37 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'talos_options': ['--disable-e10s', 08:39:37 INFO - '--xperf_path', 08:39:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:37 INFO - 'tests': ['tp5n']}, 08:39:37 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:39:37 INFO - 'talos_options': ['--xperf_path', 08:39:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:39:37 INFO - 'tests': ['tp5n']}}, 08:39:37 INFO - 'talos.zip': {'path': '', 08:39:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:39:37 INFO - Running post-action listener: _resource_record_post_action 08:39:37 INFO - [mozharness: 2017-02-06 16:39:37.324716Z] Finished populate-webroot step (success) 08:39:37 INFO - [mozharness: 2017-02-06 16:39:37.324850Z] Running create-virtualenv step. 08:39:37 INFO - Running pre-action listener: _resource_record_pre_action 08:39:37 INFO - Running main action method: create_virtualenv 08:39:37 INFO - Creating virtualenv /builds/slave/test/build/venv 08:39:37 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:39:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:39:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:39:37 INFO - Using real prefix '/tools/python27' 08:39:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:39:38 INFO - Installing distribute.............................................................................................................................................................................................done. 08:39:41 INFO - Installing pip.................done. 08:39:41 INFO - Return code: 0 08:39:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:39:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:39:41 INFO - Reading from file tmpfile_stdout 08:39:41 INFO - Output received: 08:39:41 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:39:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:39: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:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39: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:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:41 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:41 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:39:41 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:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:41 INFO - 'HOME': '/Users/cltbld', 08:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:41 INFO - 'LOGNAME': 'cltbld', 08:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:41 INFO - 'MOZ_NO_REMOTE': '1', 08:39:41 INFO - 'NO_EM_RESTART': '1', 08:39:41 INFO - 'PAGER': '/bin/cat', 08:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:41 INFO - 'PWD': '/builds/slave/test', 08:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:41 INFO - 'SHELL': '/bin/bash', 08:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:41 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:41 INFO - 'USER': 'cltbld', 08:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:41 INFO - 'XPC_FLAGS': '0x0', 08:39:41 INFO - 'XPC_SERVICE_NAME': '0', 08:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:41 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:39:41 INFO - Cleaning up... 08:39:41 INFO - Return code: 0 08:39:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:39: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:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39: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:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:41 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:41 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:39:41 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:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:41 INFO - 'HOME': '/Users/cltbld', 08:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:41 INFO - 'LOGNAME': 'cltbld', 08:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:41 INFO - 'MOZ_NO_REMOTE': '1', 08:39:41 INFO - 'NO_EM_RESTART': '1', 08:39:41 INFO - 'PAGER': '/bin/cat', 08:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:41 INFO - 'PWD': '/builds/slave/test', 08:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:41 INFO - 'SHELL': '/bin/bash', 08:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:41 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:41 INFO - 'USER': 'cltbld', 08:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:41 INFO - 'XPC_FLAGS': '0x0', 08:39:41 INFO - 'XPC_SERVICE_NAME': '0', 08:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:41 INFO - Downloading/unpacking psutil>=3.1.1 08:39:41 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:39:41 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:39:41 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:39:41 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:39:41 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:39:41 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:39:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:39:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:45 INFO - Installing collected packages: psutil 08:39:45 INFO - Running setup.py install for psutil 08:39:45 INFO - building 'psutil._psutil_osx' extension 08:39:45 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:39:46 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:39:46 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:39:46 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:39:46 INFO - building 'psutil._psutil_posix' extension 08:39:46 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:39:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:39:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:39:46 INFO - ^ 08:39:46 INFO - 1 warning generated. 08:39:46 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:39:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:39:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:39:46 INFO - Successfully installed psutil 08:39:46 INFO - Cleaning up... 08:39:46 INFO - Return code: 0 08:39:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:39: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:39:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39: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:39:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:46 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:46 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:39:46 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:39:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:46 INFO - 'HOME': '/Users/cltbld', 08:39:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:46 INFO - 'LOGNAME': 'cltbld', 08:39:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:46 INFO - 'MOZ_NO_REMOTE': '1', 08:39:46 INFO - 'NO_EM_RESTART': '1', 08:39:46 INFO - 'PAGER': '/bin/cat', 08:39:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:46 INFO - 'PWD': '/builds/slave/test', 08:39:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:46 INFO - 'SHELL': '/bin/bash', 08:39:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:46 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:46 INFO - 'USER': 'cltbld', 08:39:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:46 INFO - 'XPC_FLAGS': '0x0', 08:39:46 INFO - 'XPC_SERVICE_NAME': '0', 08:39:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:46 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:39:46 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:39:46 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:39:46 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:39:46 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:39:46 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:39:46 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:39:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:39:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:39:49 INFO - Installing collected packages: mozsystemmonitor 08:39:49 INFO - Running setup.py install for mozsystemmonitor 08:39:49 INFO - Successfully installed mozsystemmonitor 08:39:49 INFO - Cleaning up... 08:39:49 INFO - Return code: 0 08:39:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:49 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:49 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:39:49 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:39:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:49 INFO - 'HOME': '/Users/cltbld', 08:39:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:49 INFO - 'LOGNAME': 'cltbld', 08:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:49 INFO - 'MOZ_NO_REMOTE': '1', 08:39:49 INFO - 'NO_EM_RESTART': '1', 08:39:49 INFO - 'PAGER': '/bin/cat', 08:39:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:49 INFO - 'PWD': '/builds/slave/test', 08:39:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:49 INFO - 'SHELL': '/bin/bash', 08:39:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:49 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:49 INFO - 'USER': 'cltbld', 08:39:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:49 INFO - 'XPC_FLAGS': '0x0', 08:39:49 INFO - 'XPC_SERVICE_NAME': '0', 08:39:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:50 INFO - Downloading/unpacking jsonschema==2.5.1 08:39:50 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:39:50 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:39:50 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:39:50 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:39:50 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:39:50 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:39:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:39:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:39:52 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:39:52 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:39:52 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:39:52 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:39:52 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:39:52 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:39:53 INFO - Downloading functools32-3.2.3-2.tar.gz 08:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:39:53 INFO - warning: no files found matching '*.txt' 08:39:53 INFO - no previously-included directories found matching 'build' 08:39:53 INFO - no previously-included directories found matching 'dist' 08:39:53 INFO - no previously-included directories found matching '.git*' 08:39:53 INFO - Installing collected packages: jsonschema, functools32 08:39:53 INFO - Running setup.py install for functools32 08:39:53 INFO - warning: no files found matching '*.txt' 08:39:53 INFO - no previously-included directories found matching 'build' 08:39:53 INFO - no previously-included directories found matching 'dist' 08:39:53 INFO - no previously-included directories found matching '.git*' 08:39:53 INFO - Successfully installed jsonschema functools32 08:39:53 INFO - Cleaning up... 08:39:53 INFO - Return code: 0 08:39:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:39:53 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:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:53 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:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:53 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:53 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:39:53 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:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:53 INFO - 'HOME': '/Users/cltbld', 08:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:53 INFO - 'LOGNAME': 'cltbld', 08:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:53 INFO - 'MOZ_NO_REMOTE': '1', 08:39:53 INFO - 'NO_EM_RESTART': '1', 08:39:53 INFO - 'PAGER': '/bin/cat', 08:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:53 INFO - 'PWD': '/builds/slave/test', 08:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:53 INFO - 'SHELL': '/bin/bash', 08:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:53 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:53 INFO - 'USER': 'cltbld', 08:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:53 INFO - 'XPC_FLAGS': '0x0', 08:39:53 INFO - 'XPC_SERVICE_NAME': '0', 08:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:39:53 INFO - Cleaning up... 08:39:53 INFO - Return code: 0 08:39:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:39:53 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:39:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:53 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:39:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:53 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:53 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:39:53 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:39:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:53 INFO - 'HOME': '/Users/cltbld', 08:39:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:53 INFO - 'LOGNAME': 'cltbld', 08:39:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:53 INFO - 'MOZ_NO_REMOTE': '1', 08:39:53 INFO - 'NO_EM_RESTART': '1', 08:39:53 INFO - 'PAGER': '/bin/cat', 08:39:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:53 INFO - 'PWD': '/builds/slave/test', 08:39:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:53 INFO - 'SHELL': '/bin/bash', 08:39:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:53 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:53 INFO - 'USER': 'cltbld', 08:39:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:53 INFO - 'XPC_FLAGS': '0x0', 08:39:53 INFO - 'XPC_SERVICE_NAME': '0', 08:39:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:54 INFO - Downloading/unpacking blobuploader==1.2.4 08:39:54 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:39:54 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:39:54 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:39:54 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:39:54 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:39:54 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:39:56 INFO - Downloading blobuploader-1.2.4.tar.gz 08:39:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:39:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:39:56 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:39:56 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:39:56 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:39:56 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:39:56 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:39:56 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:39:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:39:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:39:57 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:39:57 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:39:57 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:39:57 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:39:57 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:39:57 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:39:57 INFO - Downloading docopt-0.6.1.tar.gz 08:39:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:39:58 INFO - Installing collected packages: blobuploader, requests, docopt 08:39:58 INFO - Running setup.py install for blobuploader 08:39:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:39:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:39:58 INFO - Running setup.py install for requests 08:39:58 INFO - Running setup.py install for docopt 08:39:58 INFO - Successfully installed blobuploader requests docopt 08:39:58 INFO - Cleaning up... 08:39:58 INFO - Return code: 0 08:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:39:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:39:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39: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:39:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:39:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:39:59 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:39:59 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:39:59 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:39:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:39:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:39:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:39:59 INFO - 'HOME': '/Users/cltbld', 08:39:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:39:59 INFO - 'LOGNAME': 'cltbld', 08:39:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:39:59 INFO - 'MOZ_NO_REMOTE': '1', 08:39:59 INFO - 'NO_EM_RESTART': '1', 08:39:59 INFO - 'PAGER': '/bin/cat', 08:39:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:39:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:39:59 INFO - 'PWD': '/builds/slave/test', 08:39:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:39:59 INFO - 'SHELL': '/bin/bash', 08:39:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:39:59 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:39:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:39:59 INFO - 'USER': 'cltbld', 08:39:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:39:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:39:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:39:59 INFO - 'XPC_FLAGS': '0x0', 08:39:59 INFO - 'XPC_SERVICE_NAME': '0', 08:39:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-oOlbJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-T4HdKK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-lg1jxS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-PspB64-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-aTXSrW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-RNyZF1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-Ml2cxi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:39:59 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-SJkvNA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-s24cc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-oEdSuW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-vdfDYx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-n5l2N6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-tsn_Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-9Xt9VV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-juUROd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-dXLdzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:00 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-J_muL8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:40:01 INFO - Running setup.py install for manifestparser 08:40:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:40:01 INFO - Running setup.py install for mozcrash 08:40:01 INFO - Running setup.py install for mozdebug 08:40:01 INFO - Running setup.py install for mozdevice 08:40:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:40:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:40:01 INFO - Running setup.py install for mozfile 08:40:01 INFO - Running setup.py install for mozhttpd 08:40:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:40:01 INFO - Running setup.py install for mozinfo 08:40:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Running setup.py install for mozInstall 08:40:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Running setup.py install for mozleak 08:40:02 INFO - Running setup.py install for mozlog 08:40:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Running setup.py install for moznetwork 08:40:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:40:02 INFO - Running setup.py install for mozprocess 08:40:02 INFO - Running setup.py install for mozprofile 08:40:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:40:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:40:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:40:03 INFO - Running setup.py install for mozrunner 08:40:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:40:03 INFO - Running setup.py install for mozscreenshot 08:40:03 INFO - Running setup.py install for moztest 08:40:03 INFO - Running setup.py install for mozversion 08:40:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:40:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:40:03 INFO - Cleaning up... 08:40:03 INFO - Return code: 0 08:40:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:03 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:40:03 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:40:03 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:40:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:40:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:03 INFO - 'HOME': '/Users/cltbld', 08:40:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:03 INFO - 'LOGNAME': 'cltbld', 08:40:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:03 INFO - 'MOZ_NO_REMOTE': '1', 08:40:03 INFO - 'NO_EM_RESTART': '1', 08:40:03 INFO - 'PAGER': '/bin/cat', 08:40:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:03 INFO - 'PWD': '/builds/slave/test', 08:40:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:03 INFO - 'SHELL': '/bin/bash', 08:40:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:40:03 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:40:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:03 INFO - 'USER': 'cltbld', 08:40:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:03 INFO - 'XPC_FLAGS': '0x0', 08:40:03 INFO - 'XPC_SERVICE_NAME': '0', 08:40:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:03 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-HhJ3BI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-s89Bzd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-qx6KQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-rlK8Iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-eyyu95-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-rwHx1s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-JysY6V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-oHEfn3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-fep9dZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:04 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:40:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:04 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-fh2F0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-p6M6fV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-vxRqEi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-bb0cUU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-MacGuv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-YOwjZl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-3bNsDg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:05 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:40:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:05 INFO - Running setup.py (path:/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/pip-D00Ygr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:05 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:40:05 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:40:05 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:40:05 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:40:05 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:40:05 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:40:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:40:05 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:40:05 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:40:05 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:40:05 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:40:05 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:40:05 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:40:08 INFO - Downloading blessings-1.6.tar.gz 08:40:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:40:08 INFO - Installing collected packages: blessings 08:40:08 INFO - Running setup.py install for blessings 08:40:09 INFO - Successfully installed blessings 08:40:09 INFO - Cleaning up... 08:40:09 INFO - Return code: 0 08:40:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:40:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:09 INFO - Reading from file tmpfile_stdout 08:40:09 INFO - Current package versions: 08:40:09 INFO - blessings == 1.6 08:40:09 INFO - blobuploader == 1.2.4 08:40:09 INFO - docopt == 0.6.1 08:40:09 INFO - functools32 == 3.2.3-2 08:40:09 INFO - jsonschema == 2.5.1 08:40:09 INFO - manifestparser == 1.1 08:40:09 INFO - mozInstall == 1.12 08:40:09 INFO - mozcrash == 1.0 08:40:09 INFO - mozdebug == 0.1 08:40:09 INFO - mozdevice == 0.49 08:40:09 INFO - mozfile == 1.2 08:40:09 INFO - mozhttpd == 0.7 08:40:09 INFO - mozinfo == 0.9 08:40:09 INFO - mozleak == 0.1 08:40:09 INFO - mozlog == 3.4 08:40:09 INFO - moznetwork == 0.27 08:40:09 INFO - mozprocess == 0.24 08:40:09 INFO - mozprofile == 0.28 08:40:09 INFO - mozrunner == 6.13 08:40:09 INFO - mozscreenshot == 0.1 08:40:09 INFO - mozsystemmonitor == 0.3 08:40:09 INFO - moztest == 0.8 08:40:09 INFO - mozversion == 1.4 08:40:09 INFO - psutil == 3.1.1 08:40:09 INFO - requests == 1.2.3 08:40:09 INFO - wsgiref == 0.1.2 08:40:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:09 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 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:40:09 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:40:09 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:40:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:40:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:09 INFO - 'HOME': '/Users/cltbld', 08:40:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:09 INFO - 'LOGNAME': 'cltbld', 08:40:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:09 INFO - 'MOZ_NO_REMOTE': '1', 08:40:09 INFO - 'NO_EM_RESTART': '1', 08:40:09 INFO - 'PAGER': '/bin/cat', 08:40:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:09 INFO - 'PWD': '/builds/slave/test', 08:40:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:09 INFO - 'SHELL': '/bin/bash', 08:40:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:40:09 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:40:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:09 INFO - 'USER': 'cltbld', 08:40:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:09 INFO - 'XPC_FLAGS': '0x0', 08:40:09 INFO - 'XPC_SERVICE_NAME': '0', 08:40:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 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:40:10 INFO - Cleaning up... 08:40:10 INFO - Return code: 0 08:40:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:10 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10119ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10119d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f89684acb70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, '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.DlLRXdrcRR/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.vwCVfzsChN/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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:40:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:40:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:40:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:10 INFO - 'HOME': '/Users/cltbld', 08:40:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:10 INFO - 'LOGNAME': 'cltbld', 08:40:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:10 INFO - 'MOZ_NO_REMOTE': '1', 08:40:10 INFO - 'NO_EM_RESTART': '1', 08:40:10 INFO - 'PAGER': '/bin/cat', 08:40:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:10 INFO - 'PWD': '/builds/slave/test', 08:40:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:10 INFO - 'SHELL': '/bin/bash', 08:40:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:40:10 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:40:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:10 INFO - 'USER': 'cltbld', 08:40:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:10 INFO - 'XPC_FLAGS': '0x0', 08:40:10 INFO - 'XPC_SERVICE_NAME': '0', 08:40:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:40:10 INFO - Cleaning up... 08:40:10 INFO - Return code: 0 08:40:10 INFO - Running post-action listener: _resource_record_post_action 08:40:10 INFO - Running post-action listener: _start_resource_monitoring 08:40:10 INFO - Starting resource monitoring. 08:40:10 INFO - [mozharness: 2017-02-06 16:40:10.333024Z] Finished create-virtualenv step (success) 08:40:10 INFO - [mozharness: 2017-02-06 16:40:10.334034Z] Running install step. 08:40:10 INFO - Running pre-action listener: _resource_record_pre_action 08:40:10 INFO - Running main action method: install 08:40:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:10 INFO - Reading from file tmpfile_stdout 08:40:10 INFO - Detecting whether we're running mozinstall >=1.0... 08:40:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:40:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:40:10 INFO - Reading from file tmpfile_stdout 08:40:10 INFO - Output received: 08:40:10 INFO - Usage: mozinstall [options] installer 08:40:10 INFO - Options: 08:40:10 INFO - -h, --help show this help message and exit 08:40:10 INFO - -d DEST, --destination=DEST 08:40:10 INFO - Directory to install application into. [default: 08:40:10 INFO - "/builds/slave/test"] 08:40:10 INFO - --app=APP Application being installed. [default: firefox] 08:40:10 INFO - mkdir: /builds/slave/test/build/application 08:40:10 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:40:10 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:40:30 INFO - Reading from file tmpfile_stdout 08:40:30 INFO - Output received: 08:40:30 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:40:30 INFO - Running post-action listener: _resource_record_post_action 08:40:30 INFO - [mozharness: 2017-02-06 16:40:30.554436Z] Finished install step (success) 08:40:30 INFO - [mozharness: 2017-02-06 16:40:30.554583Z] Running run-tests step. 08:40:30 INFO - Running pre-action listener: _resource_record_pre_action 08:40:30 INFO - Running main action method: run_tests 08:40:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:40:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:40:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:40:30 INFO - 2017-02-06 08:40:30.569 screenresolution[1628:7547] starting screenresolution argv=screenresolution get 08:40:30 INFO - 2017-02-06 08:40:30.571 screenresolution[1628:7547] Display 0: 1600x1200x32@50 08:40:30 INFO - 2017-02-06 08:40:30.577 screenresolution[1629:7549] starting screenresolution argv=screenresolution list 08:40:30 INFO - Available Modes on Display 0 08:40:30 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 08:40:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:40:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:40:30 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 08:40:30 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 08:40:30 INFO - Graphics/Displays: 08:40:30 INFO - Intel Iris: 08:40:30 INFO - Chipset Model: Intel Iris 08:40:30 INFO - Type: GPU 08:40:30 INFO - Bus: Built-In 08:40:30 INFO - VRAM (Dynamic, Max): 1536 MB 08:40:30 INFO - Vendor: Intel (0x8086) 08:40:30 INFO - Device ID: 0x0a2e 08:40:30 INFO - Revision ID: 0x0009 08:40:30 INFO - Displays: 08:40:30 INFO - Display: 08:40:30 INFO - Resolution: 1600 x 1200 @ 50 Hz 08:40:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:40:30 INFO - Display Serial Number: 1600x1200 50 08:40:30 INFO - Main Display: Yes 08:40:30 INFO - Mirror: Off 08:40:30 INFO - Online: Yes 08:40:30 INFO - Rotation: Supported 08:40:30 INFO - Return code: 0 08:40:30 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:40:30 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:40:30 INFO - Python 2.7.3 08:40:30 INFO - Return code: 0 08:40:30 INFO - grabbing minidump binary from tooltool 08:40:30 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:30 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 0x100f78d30>, '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 0x100e9fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x100f6dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:40:30 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:40:30 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:40:30 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:40:30 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:40:30 INFO - Return code: 0 08:40:30 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:40:30 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:40:30 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:40:30 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:40:30 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:40:30 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/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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:40:30 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/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0017 --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:40:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.DlLRXdrcRR/Render', 08:40:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:30 INFO - 'HOME': '/Users/cltbld', 08:40:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:30 INFO - 'LOGNAME': 'cltbld', 08:40:30 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:40:30 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:40:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:30 INFO - 'MOZ_NO_REMOTE': '1', 08:40:30 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:40:30 INFO - 'NO_EM_RESTART': '1', 08:40:30 INFO - 'PAGER': '/bin/cat', 08:40:30 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:30 INFO - 'PWD': '/builds/slave/test', 08:40:30 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:40:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:30 INFO - 'SHELL': '/bin/bash', 08:40:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.vwCVfzsChN/Listeners', 08:40:30 INFO - 'TMPDIR': '/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/', 08:40:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:30 INFO - 'USER': 'cltbld', 08:40:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:30 INFO - 'XPC_FLAGS': '0x0', 08:40:30 INFO - 'XPC_SERVICE_NAME': '0', 08:40:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:30 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/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0017', '--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:40:31 INFO - mozversion application_buildid: 20170206072755 08:40:31 INFO - mozversion application_changeset: 65e5f8c2d8160bb7a078726ccad358f647418959 08:40:31 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:40:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:40:31 INFO - mozversion application_name: Firefox 08:40:31 INFO - mozversion application_remotingname: firefox-dev 08:40:31 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:40:31 INFO - mozversion application_vendor: Mozilla 08:40:31 INFO - mozversion application_version: 53.0a2 08:40:31 INFO - mozversion platform_buildid: 20170206072755 08:40:31 INFO - mozversion platform_changeset: 65e5f8c2d8160bb7a078726ccad358f647418959 08:40:31 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:40:31 INFO - mozversion platform_version: 53.0a2 08:40:31 INFO - using testdate: 1486399231 08:40:31 INFO - actual date: 1486399231 08:40:31 INFO - starting webserver on 'localhost:49241' 08:40:31 INFO - SUITE-START | Running 2 tests 08:40:31 INFO - TEST-START | dromaeo_css 08:40:31 INFO - operating with platform_type : mac_ 08:40:31 INFO - Initialising browser for dromaeo_css test... 08:40:31 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpkFkwBD/profile http://localhost:49241/getInfo.html) 08:40:33 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 08:40:33 INFO - PROCESS | 1639 | colorDepth:24 08:40:33 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 08:40:33 INFO - PROCESS | 1639 | __metrics 08:40:33 INFO - PROCESS | 1639 | 1486399233663 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:40:33 INFO - PROCESS | 1639 | 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:40:33 INFO - PROCESS | 1639 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:40:34 INFO - TEST-INFO | 1639: exit 0 08:40:34 INFO - Browser initialized. 08:40:34 INFO - Running cycle 1/1 for dromaeo_css test... 08:40:34 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmpkFkwBD/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:40:46 INFO - PROCESS | 1641 | RSS: Main: 212676608 08:40:46 INFO - PROCESS | 1641 | 08:43:50 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49241/tests/dromaeo/cssquery-ext.html) 08:43:52 INFO - PROCESS | 1641 | RSS: Main: 363065344 08:43:52 INFO - PROCESS | 1641 | 08:45:35 INFO - PROCESS | 1641 | ************************* 08:45:35 INFO - PROCESS | 1641 | A coding exception was thrown and uncaught in a Task. 08:45:35 INFO - PROCESS | 1641 | 08:45:35 ERROR - PROCESS | 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:45:35 INFO - PROCESS | 1641 | Full stack: 08:45:35 INFO - PROCESS | 1641 | ************************* 08:46:55 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-ext.html (next: http://localhost:49241/tests/dromaeo/cssquery-jquery.html) 08:46:55 INFO - PROCESS | 1641 | RSS: Main: 344911872 08:46:55 INFO - PROCESS | 1641 | 08:49:58 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49241/tests/dromaeo/cssquery-mootools.html) 08:49:58 INFO - PROCESS | 1641 | RSS: Main: 330285056 08:49:58 INFO - PROCESS | 1641 | 08:53:01 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49241/tests/dromaeo/cssquery-prototype.html) 08:53:01 INFO - PROCESS | 1641 | RSS: Main: 335654912 08:53:01 INFO - PROCESS | 1641 | 08:56:05 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49241/tests/dromaeo/cssquery-yui.html) 08:56:05 INFO - PROCESS | 1641 | RSS: Main: 314683392 08:56:05 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | Cycle 1(1): loaded http://localhost:49241/tests/dromaeo/cssquery-yui.html (next: http://localhost:49241/tests/dromaeo/cssquery-dojo.html) 08:59:09 INFO - PROCESS | 1641 | RSS: Main: 422957056 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | __start_tp_report 08:59:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load 08:59:09 INFO - PROCESS | 1641 | _x_x_mozilla_page_load_details 08:59:09 INFO - PROCESS | 1641 | |i|pagename|runs| 08:59:09 INFO - PROCESS | 1641 | |0;dojo.html;2771.0716909835205;3372.662733726629;3380.611229708583;3394.8981530554092;3434.2959693262883;5576.832695019151;5705.4294570542925;5722.284714410699;5753.9642864284415;5794.246747922764;5722.256106706122;5757.769689212437;5779.884402311951;5796.623219490741;5826.533877289818;5198.298229738984;5280.815171468999;5286.4449232830575;5291.206319052135;5334.0665383557725;5190.584753219752;5194.766235519411;5207.557357624606;5343.545798607123;5348.652337598063;5277.70972596507;5294;5306.4958828911385;5307.442718514559;5325.3875804282425;5291.100512912814;5296.496832800878;5300.469953004707;5303.60222983274;5307.65500242483;5105.412877519058;5239.764210610531;5254.842354729326;5255.632105752599;5259.342582177228;5842.386549412293;5880.117982302646;5880.617759845639;5895.528357731371;5909.674967876765;6256.906146407808;6258.561900666955;6274.058891166362;6294.244690637107;6307.18006659136;6167.383261673796;6205.596636218676;6243.344448832852;6300.21247344082;6316.6841657917375;5839.649621022752;5840.269966254218;5843.941560584382;5917.31950825652;5966.164736936832;6256.436920677161;6309.810705678837;6320.083587879732;6323.810285691437;6332.366763323631;6105.572609917352;6221.813345599639;6235.906461403082;6238.625682459067;6251.937170680995;6155.630662160284;6204;6224.1597384352535;6226.1905952226825;6279.654618995908;6248.875022499525;6257.9687101564205;6261.123442718023;6261.499080073583;6262.906056409158;6246.843828904313;6257.405546473078;6281.560290779602;6294.685265736694;6321.273053598895;4866.416030076413;4873.415190177201;4907.288443175719;4958.578520825698;4964.925526117111;3623.4564815277918;3777.6033516480375;4454.064646424287;4470.977645111753;4501.3022981437925;5909.3795151508475;5909.970450147722;5917.437843404956;5932.40675932412;5949.9107513387335;6145.494353883327;6243.219597550306;6282.120503129474;6282.340354262737;6287.622742635456;5858.648481091148;5904.380040095814;5921.644701317935;5961.672108034099;5978.940210597925;5378.085725426608;5436.510714035756;5441.891162176735;5514.503694667499;5518.779248830082;6272.874542509125;6275.592086514362;6288.9056664150075;6290.842728931813;6292.716827742671;6233.563650544418;6254.749810007731;6295.96852015737;6314.242290925118;6328.335524769833;6210;6364.745410183541;6369.522285828489;6391.2330520337855;6392.488600911823;6109.0403061477;6132.661461321519;6145.647957449354;6210.658413787104;6262.405071518198;6130.846728831815;6327.588706734047;6331.018692102732;6335.809925702236;6350.174477317919;6134.938650613437;6179.752809887555;6188.9690551546955;6197.783077592263;6203.16257305258;5946.494547963472;6001.669908155093;6102.389761023862;6118.010103775335;6135.723892424762;6001.309849367204;6021.337652858269;6022.066579680157;6030.698465076816;6049.818505444844;6162.568620196723;6169.907451388322;6170.784022559189;6180.6291622502795;6180.876382472416;5327.467253274642;5663.065594177079;5861.443162899518;5899.557533185114;5922.289325280994 08:59:09 INFO - PROCESS | 1641 | |1;ext.html;492.5025724018742;494.83423053277176;497.99751001244994;499.15891722447657;508.8829569199085;30490.695093049064;40448;42057.579424205745;42401.36397954033;44099.77950110245;43187.78406107965;43565;43661.345079823746;43672.78163609178;43709;8474.957625211866;9226.584803683816;9433.292503062263;9434;9447.669331573396;8430.873536896952;11123.777524449506;11397.544098236061;11423.942880285587;11552.711182220428;8821.338399620054;9920.404775713445;11361.943190284037;11364.090872730203;11420.71448213793;8529.74410767678;8732.257758090569;11422.143339249547;11501.769964600702;11521.539138434453;11008.779824403548;11317.886821131766;11349.319040857576;11486.138539609563;11489.482973266224;40531;41009.179816403506;41062.178756425004;41105.383419248734;41172;3857;3951.7826519541413;3975.403689446577;3978.920421591581;4015.3575427931682;29480.410391792044;29547.113586592222;29602.55596166038;31213.90751323693;31268.9055883043;3251.138448311199;3264.281857991238;3274.0832566881313;3334.5164951081956;3376.3584918865336;452834.73582632205;456218;456894;457900.71049644874;460996;59806.700966494886;59862;60139;60298.698506507186;60381.698091509264;64098.35901641017;64123;64137;64173;64254.357456424834;16081.839181608035;16838.915805420893;16842.32630694783;16850.83149168517;16875.24061417239;11685.240459370114;11952.163348565517;11969.760604787856;12989.740205196033;13113.344332783323;16272.837271627131;16553.420630277884;16568.917155414387;16589.91705041491;16725.414610488817;6520.054592084121;6523.119378883885;6545.869082618321;6563.245226798884;6573.506986975996;757.5416872791992;848.893888263967;885.9955700221458;889.8309321228945;899.8290324838259;28866;29775.66009529533;29812.571554271104;30273.545896811567;30346.84826575853;1073.6886302972207;1078.1267173589417;1115.9274647147909;1129.785340785248;1228.791105512065;15133.772993405108;15147.924260378848;15328.386864525293;15462.072275663497;15776.842231577766;569894;571104;572091;572582;574273;59524.40475595189;59634.40365596375;59646;59760.20719378339;59963;1433.4224691443424;1448.594393569781;1461.6126726417508;1487.6280929767559;1490.0984904133277;27315.043973460834;29042;29104.854475727487;29767.851160744056;29781.702182977893;49120.75439622779;49504;49581.752091239316;49665.751671241414;49678.751606241734;488649;492111;492128;493915;493965;23211.187608433625;23725.52548948977;23771.286861394186;26035.479290414463;26422.207333780018;45137.548624514646;46668.53331466642;47206.76396617995;48972.020559587894;50731.74634126806;38739.03152421212;38882.0279493015;38979.61020389873;39108.2178356437;40402.39396409115;38978.4153237696;39084;40050.59949400469;40338.596614033486;40476;34620.480692789126;35618.10954726153;35669.64330356767;38499.03752406212;38609.80695096507;29781.70218297876;31247.87521247857;31709.890153844506;31950.840245798623;32006;23574.764252357258;23608.881955590114;23775.405614859767;23844.403889902893;24097.638535421633 08:59:09 INFO - PROCESS | 1641 | |2;jquery.html;353.1964780125215;361.9800910949897;383.72180169377185;400.3534292118229;408.4057696052244;15284.770728439082;15518.84481155188;17386;20380.082896269665;20978.160873565077;19608.803911960877;20074.293685036788;21291.36125916225;21468.4632884178;21881.90590470478;5663.7168141592965;5679;5827.242458480391;5887.705614719268;5893.764249430018;5691;5777.59556831022;5821.56338274629;5840.766369345221;5849.649021058729;4165.708400411972;4393.736375817445;5761.625494342854;5803.738831752581;6423.421892029716;6302.401271879165;6490.415862572368;6521.054447105191;6524.249711283216;6534.117894084309;5649.180868774005;5720.570957178186;5733.684647344395;5736.7992120276;5756.251687280668;25442.872785635955;25702.871485642638;25728.614070788954;25753.484930301478;26679.67175424364;2720.945581088387;2836.5817091454273;2844.6017557541754;2849.28767808048;2865.555838844982;14984.700305993929;15151.166685832275;15291;15374.000689955117;16072;1741.5210817025293;1786.3211979447856;1787.3922866225546;1789.1769785898503;1812.7643406357222;116370;122279;122280.38859805732;122487.16269255968;126091;11461.598844040418;11502.769944601228;11579.536818527165;11623.76752464946;11722.886325799036;12042.698932526582;13996.510122145675;14526.709465810625;14561.635959100895;14602.12387256768;8709.042005379402;8858;8885.777855553533;8919.554022298858;8977.461352318882;7340.265973402617;8818.030692429533;8841.381103322834;8953.507557084293;9082.091790820998;7027.508074434741;7123.180834204029;7153.7138514459875;7170.892436613455;7254.673539690727;1639.4098124675108;1877.1271358818215;1906.0816165805252;1921.1354890299422;1921.817427344428;1021.9489025548693;1050.9360987104365;1060.3054998975683;1061.6549621373085;1083.8320060390652;22632.547349053257;22835.543289134122;22845.200417985292;22865.542689146456;22886;1067.6067731610167;1074.1084899533369;1080.3032044331362;1096.1066730614523;1109.389835590422;11277.379744113989;11863.822042669366;11905.107116966263;11906.52373905034;11931.642050738492;372038;646582;714431;715668;742108;98009;105775.4711226439;116320;121988;124505;977.6578197630772;1001.3191030099603;1028.804527139841;1031.1544533482472;1039.3555995282973;19017;19321.613567728848;19624.41126766199;19668.606627867077;19687.507812304808;52537;53765.46234537761;54841.72579137079;55212.44787552073;56827;70109.64945175241;71194.28805712084;71531.64234178796;71962.64018679873;74626.88059679001;7722.227777222233;7741.225877412214;7879.409044321814;8059.919400805917;8269.710560130367;11662.416879156179;12759.298238596595;12879.098463107492;12987;13236.073474856668;11615.825762613398;11820.349880756297;11943.16397852142;11945.223560468541;12098.818517722066;11298.661040168809;11510.194286399872;11600.883991160317;11606;11931.985781208694;11642.76714465689;11754;11937.701557461132;12026.338551379758;12147.635570932885;6993.790186294419;8056.395770317366;8064.798380040546;8268.751937441886;8396.034456037634;11469.598564050219;11549.36478493691;11662.183647144619;11929.463174157358;11961.342126183143 08:59:09 INFO - PROCESS | 1641 | |3;mootools.html;1264.184600932398;1329.1094966372532;1387.4796951143321;1445.5952333346663;1459.9343029563668;8022.318102961248;8297.626606802693;8455.788605284872;8475.194856488639;8500.532470714115;7413.592252426114;7648.7322943696845;7649.502782319146;8055.476394034384;8299.294559962394;2280.9885950570224;2332.93001209964;2334.486412989148;2339.6607491913664;2342.601757701195;2314.3404129823;2355.9057637694473;2357.8938947747306;2360.3155085025323;2367.4318163640687;1955.5795503966644;2020.0909590684178;2251.7860803223666;2290.5533420982915;2303.251443280932;2314.4213946513373;2334.4047267946735;2336.3575016870327;2339.754325795801;2344.75380085092;2246.382244882654;2287.79409853114;2288.7253529576556;2359.362971997551;2363.976360236393;8333.66665333386;8407.95796021022;8408;8425.11536288687;8432.32541396693;4995.450500444948;5138.974305128488;5143.3570803649545;5148.433672296044;5155.02054609623;3392.8473218705217;3528.947065794015;3550.804705741209;3600.6039335673054;3613.8012409317475;1164.935928523931;1220.6948262934266;1257.4404390046434;1259.2633309513944;1266.5756971414587;10340;10764.56941722338;10784.514696838583;10856.240063195579;10972.561097556169;3634.47300141478;4450.287953927356;4509.616682581952;4564.680472366969;4565.771711414416;8658.653653853775;8662.653493860302;8666.263367613823;8667.609957551798;8680.479171249744;5126.410462796826;5129.692218466904;5142.6400151989;5143.022825663112;5145.4597267287145;3837.9232415351535;4173.895652608649;4722.740249286322;4760.690555113906;4815.061063092663;3674.81625918703;3688.354537955847;3735.869244576427;3768.5854555998817;3772.7170462215445;1146.656003199037;1152.3546813784308;1155.8324043013715;1164.3828770751516;1166.4692564882957;666.6799936030733;761.6953218712559;794.5629903553023;802.1056521977953;819.2377225785633;5069.8732531687;5462.2079798429;5539.196816461671;5672.347680016768;5701.287339082614;540.1546579602909;540.446042806119;568.6843801690019;575.9107338362561;577.7226931073109;3613.9413395011347;3668.9816550917076;3759.605241449663;3807.409851472971;3835.8657446989223;9648.807023859483;10027.849582256273;10034.946330635179;10043.497825108861;10066.899331006744;9958.950205249072;9990.200783937267;10107.039831216023;10109.443980581138;10152.340097893613;7611.961940190264;7680.884786728092;7831.060272767304;7845.215478452224;7875.330596899328;5391.892162156813;5464.398916119144;5544.500994910478;5646.322441307069;5708.628939118944;9057.864132037894;9142.680006199751;9152.862707059532;9235.630574776933;9365.812683746151;9187.816243675223;9375.249980001447;9388.95305523468;9489.525523723649;9532.380395274286;6185.350538193605;6389.7763578274535;6435.485161187187;6538.542302038812;6683.298253683293;7663.808404789702;8007.919920800718;8008.83982320362;8019.358451323764;8027.237412445808;6114.938850611437;7162.283771622796;7425.591592462466;7429.294217049372;7450.6647200875;7187.712491500281;7212.855742885218;7219.278072192738;7313.524620899624;7434.628268586657;6182.567220294536;6423.646699431576;6432.839179020562;6437.452816510649;6441.13044738973;4908.460069392293;4942.233953737176;4976.104301225814;4980.875478112931;5009.9479109385975;4803.9414922355945;4875.536824001714;4943.887625284282;5078.984203159309;5107.87230319245 08:59:09 INFO - PROCESS | 1641 | |4;prototype.html;353.13481969175524;353.1947160474118;363.3260302139531;370.2145561632312;376.3752171395482;4889.7066176029375;5093.987874789461;5114.590832733382;5175.66358186718;5202.505761952618;4483.910321793563;4845.933894543204;5076.441591424949;5081.551757749042;5133.307003554519;3738.7569807962464;3797.0886987123167;3970.880873573783;4066.8169932352966;4078.0008897820076;3451.0674022547328;3852.576216616184;3936.6457018868296;3981.3828856527284;3981.4625025621604;3477.4436090225568;3634.8913581357674;3947.3486874665646;3951.5258169019758;3985.847642273169;3381.763276570641;3760.2855457463133;3969.761814291152;4005.1989602079702;4029.7783621900785;3214.8426566436297;3316.585426821647;3518.7185025197923;3565.358235517606;3579.266250418658;3761.153740408414;4214.4099826024385;4698.318743782167;4959.355283813117;4966.751662416865;4538.682292239545;4554;4567.6574256930535;4572.788211124055;4601.930971035404;5331.76066052259;5350.357957045179;5380.838574842761;5393.6763794172475;5405.648632838853;3228.9192770180935;3921.901952451183;3948.466956960831;3977.4630424892557;3998.200359927997;5129.358830146231;5401;5568.49883510486;5582.637128586629;5655;3660.1215708230366;3922.1371298314325;3954.8813535593977;4068.2880495913096;4070.61329173728;3157.826319552401;3213.4055199788113;3806.847726090981;3898.571157172709;4169.833206671699;4381.868543943687;4622.121796858586;4636.026434448803;4665.4168228971375;4686.5782079613;5435.1953632457435;5446.918296225481;5472.261244731911;5486.972565137149;5500.724963751796;1124.791913496006;1143.7769634921276;1294.0294778915813;1317.92751398674;1357.696611253208;911.4986757283467;959.5777857742571;963.8361478548663;977.2182254196614;981.3621146254991;4087.89780255496;4344.046917305301;4703.435587729495;4724.858254252378;4775.880602984953;4718.598919091847;5267.841964741064;5268.209768534751;5271.841844744664;5287;4076.592340765959;4321.524632290446;4725.740084295397;4750.61995040396;4762.714237145782;2604.6353510508543;2762.392273699783;2780.569011803174;2781.3324802047364;2782.3461486550646;4646.907061858744;4892.902141957141;5099.464556221617;5142.845714628567;5447.855950250412;4728.503507131771;5232.712200828915;5391.174898465851;5397.70312632809;5441.646292990943;4872.317875497433;5718.313802343745;5723.713814309268;5766.855828604318;5788.552861784554;4592.632589392907;4683.812647494062;5037.395512538519;5536.806211782568;5575.609707320448;4912.94371710084;5041.798328066836;5471.753771080232;5507.246376811594;5516.9448305516435;4926.413632842786;5437.918431223456;5586.770910399706;5600.607957443103;5711.5716321276905;3428.451447768345;3717.3308804415465;3725.1990821973404;3765.1904840459433;3770.981145094257;5521.122818241817;5527.2538207341495;5540.806071787468;5542.667439953616;5544.861378465571;5161.303224064854;5243.868903277448;5359.732013399236;5390.272313237662;5390.541803946709;4095.9385609215287;5315.920261196163;5336.493033161843;5376.596755243294;5424;4291.785410729389;4872.464028956882;5251.2123181523075;5272.362070171569;5306.814261500984;4554.294084416921;4652.952700811632;5239.895202096013;5355.464453554613;5374.543163830962;4433.623142032963;4562.429696287964;4793.065352256276;5216.895662086661;5272.815451459181 08:59:09 INFO - PROCESS | 1641 | |5;yui.html;708.8653155900379;763.9923600763991;816.3102178659033;823.7388368927295;824.8020475085981;14086.506972255973;15958.601034974132;15981.600459988505;16015.999999999985;16016.359345626175;14321.78517322241;14462.276886155703;14477.986540942138;14489.347979340928;14503.92748036261;680.0649107477219;707.052549583558;710.7725527831096;850.5662112322728;859.3597769661595;869.1612593846936;873.8339715454075;880.1638443478689;884.3190743127788;884.3941899798658;894.0924961164424;894.2622336572354;900.5767289373983;941.4962994797785;942.2838642631581;937.1003836317144;938.2400255792795;938.4088024544493;938.760616042908;939.6053657463814;532.7602578839537;555.469526602094;671.2850813883199;695.1137299942563;703.1210986267166;15061.849381506154;15080.245987700571;15085.924570377189;15102.5469235922;15157.848421515753;13694.863051369459;13754;13826.65433364174;13880.51418200358;13959.302034898315;12346.81479777804;12431.502739890393;12448.25310481374;12737.172083814523;13287.66780830487;4236.872893813191;4282.571742825724;4568.725876447424;4607.562281583278;4616.630669546428;99276;100745.99999999927;105486.94513054924;105989.47005265001;106028;9906.293164270153;10855.348679079281;10916.890831091747;10994.890051099546;11209.663710088547;21896;21912.89043554804;22073;22265.220717274817;22284;7358.41132709382;7634.236576342321;7780.260875216845;7789.7273595423885;7902.209779022051;4137.668986481074;4185.204811085884;4216.599423054805;4352.39066530686;4400.933985990213;3323.916902077419;3392.1519620094978;3445.758796884244;3449.9310013800086;3490.336836001152;641.913341698874;647.4076220258411;649.6719156825867;668.211510417702;715.0882624653608;570.6861226325505;587.6356658871441;591.4351794036685;591.7011908985935;599.4574909706636;9340.57967391469;10473.371597704163;10484.685459436228;10619.415932123653;10634.521446534924;541.9647722898;567.3645517020963;597.7698586044404;654;707.8979199301947;4984.327115839315;5090.949090509048;5104.336436263262;5133.973205358943;5137.6660517066275;101520;104269;104319;105954.47022764992;108823;92422.53788731148;93229.06770932204;94028.52985735028;95669;95803.56294655586;67465.66267168632;77448.2255177441;79722;82293.76559351495;83878.58060709658;9529.857052144362;9841;9872.160866326443;9902.702918912444;9936.403815771077;44382.77808611065;49130;51582;52433.213501798265;53266.73366633142;68118;69249.96125058028;70336;71620.28379716136;71771.64114179395;11604.76790464169;11746.29522228669;11840.585579504675;11996.220245684352;12360.44378002974;12400.13199076056;12436.19164754288;12497.937510312753;12684.429200685807;13150.27673477968;11804.468798903898;12176.39118044112;12207.145499815282;12399.00807935381;12721.55474558386;11924.701882453008;11984.10119241043;12201.267923924594;12465.376731163587;12502.937485312515;11575.993842028023;11687.919080587382;12017.098717596391;12030.69155982112;12052.879471205526;9725.270604704534;9804.313698041069;10182.74543136398;10306.12397946183;10513.896040915593;8104.068032176375;9851.064148905842;9983.700488985342;10018.59925602997;10028.348157369748 08:59:09 INFO - PROCESS | 1641 | __end_tp_report 08:59:09 INFO - PROCESS | 1641 | __start_cc_report 08:59:09 INFO - PROCESS | 1641 | _x_x_mozilla_cycle_collect,1550 08:59:09 INFO - PROCESS | 1641 | __end_cc_report 08:59:09 INFO - PROCESS | 1641 | __startTimestamp1486400349532__endTimestamp 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | ------- Summary: start ------- 08:59:09 INFO - PROCESS | 1641 | Number of tests: 6 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#0] dojo.html Cycles:165 Average:5784.05 Median:6001.67 stddev:675.46 (11.3%) stddev-sans-first:634.84 08:59:09 INFO - PROCESS | 1641 | Values: 2771.1 3372.7 3380.6 3394.9 3434.3 5576.8 5705.4 5722.3 5754.0 5794.2 5722.3 5757.8 5779.9 5796.6 5826.5 5198.3 5280.8 5286.4 5291.2 5334.1 5190.6 5194.8 5207.6 5343.5 5348.7 5277.7 5294.0 5306.5 5307.4 5325.4 5291.1 5296.5 5300.5 5303.6 5307.7 5105.4 5239.8 5254.8 5255.6 5259.3 5842.4 5880.1 5880.6 5895.5 5909.7 6256.9 6258.6 6274.1 6294.2 6307.2 6167.4 6205.6 6243.3 6300.2 6316.7 5839.6 5840.3 5843.9 5917.3 5966.2 6256.4 6309.8 6320.1 6323.8 6332.4 6105.6 6221.8 6235.9 6238.6 6251.9 6155.6 6204.0 6224.2 6226.2 6279.7 6248.9 6258.0 6261.1 6261.5 6262.9 6246.8 6257.4 6281.6 6294.7 6321.3 4866.4 4873.4 4907.3 4958.6 4964.9 3623.5 3777.6 4454.1 4471.0 4501.3 5909.4 5910.0 5917.4 5932.4 5949.9 6145.5 6243.2 6282.1 6282.3 6287.6 5858.6 5904.4 5921.6 5961.7 5978.9 5378.1 5436.5 5441.9 5514.5 5518.8 6272.9 6275.6 6288.9 6290.8 6292.7 6233.6 6254.7 6296.0 6314.2 6328.3 6210.0 6364.7 6369.5 6391.2 6392.5 6109.0 6132.7 6145.6 6210.7 6262.4 6130.8 6327.6 6331.0 6335.8 6350.2 6134.9 6179.8 6189.0 6197.8 6203.2 5946.5 6001.7 6102.4 6118.0 6135.7 6001.3 6021.3 6022.1 6030.7 6049.8 6162.6 6169.9 6170.8 6180.6 6180.9 5327.5 5663.1 5861.4 5899.6 5922.3 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#1] ext.html Cycles:180 Average:65058.31 Median:39031.81 stddev:135562.20 (347.3%) stddev-sans-first:135855.81 08:59:09 INFO - PROCESS | 1641 | Values: 492.5 494.8 498.0 499.2 508.9 30490.7 40448.0 42057.6 42401.4 44099.8 43187.8 43565.0 43661.3 43672.8 43709.0 8475.0 9226.6 9433.3 9434.0 9447.7 8430.9 11123.8 11397.5 11423.9 11552.7 8821.3 9920.4 11361.9 11364.1 11420.7 8529.7 8732.3 11422.1 11501.8 11521.5 11008.8 11317.9 11349.3 11486.1 11489.5 40531.0 41009.2 41062.2 41105.4 41172.0 3857.0 3951.8 3975.4 3978.9 4015.4 29480.4 29547.1 29602.6 31213.9 31268.9 3251.1 3264.3 3274.1 3334.5 3376.4 452834.7 456218.0 456894.0 457900.7 460996.0 59806.7 59862.0 60139.0 60298.7 60381.7 64098.4 64123.0 64137.0 64173.0 64254.4 16081.8 16838.9 16842.3 16850.8 16875.2 11685.2 11952.2 11969.8 12989.7 13113.3 16272.8 16553.4 16568.9 16589.9 16725.4 6520.1 6523.1 6545.9 6563.2 6573.5 757.5 848.9 886.0 889.8 899.8 28866.0 29775.7 29812.6 30273.5 30346.8 1073.7 1078.1 1115.9 1129.8 1228.8 15133.8 15147.9 15328.4 15462.1 15776.8 569894.0 571104.0 572091.0 572582.0 574273.0 59524.4 59634.4 59646.0 59760.2 59963.0 1433.4 1448.6 1461.6 1487.6 1490.1 27315.0 29042.0 29104.9 29767.9 29781.7 49120.8 49504.0 49581.8 49665.8 49678.8 488649.0 492111.0 492128.0 493915.0 493965.0 23211.2 23725.5 23771.3 26035.5 26422.2 45137.5 46668.5 47206.8 48972.0 50731.7 38739.0 38882.0 38979.6 39108.2 40402.4 38978.4 39084.0 40050.6 40338.6 40476.0 34620.5 35618.1 35669.6 38499.0 38609.8 29781.7 31247.9 31709.9 31950.8 32006.0 23574.8 23608.9 23775.4 23844.4 24097.6 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#2] jquery.html Cycles:180 Average:36080.37 Median:21379.91 stddev:108227.79 (506.2%) stddev-sans-first:108498.15 08:59:09 INFO - PROCESS | 1641 | Values: 353.2 362.0 383.7 400.4 408.4 15284.8 15518.8 17386.0 20380.1 20978.2 19608.8 20074.3 21291.4 21468.5 21881.9 5663.7 5679.0 5827.2 5887.7 5893.8 5691.0 5777.6 5821.6 5840.8 5849.6 4165.7 4393.7 5761.6 5803.7 6423.4 6302.4 6490.4 6521.1 6524.2 6534.1 5649.2 5720.6 5733.7 5736.8 5756.3 25442.9 25702.9 25728.6 25753.5 26679.7 2720.9 2836.6 2844.6 2849.3 2865.6 14984.7 15151.2 15291.0 15374.0 16072.0 1741.5 1786.3 1787.4 1789.2 1812.8 116370.0 122279.0 122280.4 122487.2 126091.0 11461.6 11502.8 11579.5 11623.8 11722.9 12042.7 13996.5 14526.7 14561.6 14602.1 8709.0 8858.0 8885.8 8919.6 8977.5 7340.3 8818.0 8841.4 8953.5 9082.1 7027.5 7123.2 7153.7 7170.9 7254.7 1639.4 1877.1 1906.1 1921.1 1921.8 1021.9 1050.9 1060.3 1061.7 1083.8 22632.5 22835.5 22845.2 22865.5 22886.0 1067.6 1074.1 1080.3 1096.1 1109.4 11277.4 11863.8 11905.1 11906.5 11931.6 372038.0 646582.0 714431.0 715668.0 742108.0 98009.0 105775.5 116320.0 121988.0 124505.0 977.7 1001.3 1028.8 1031.2 1039.4 19017.0 19321.6 19624.4 19668.6 19687.5 52537.0 53765.5 54841.7 55212.4 56827.0 70109.6 71194.3 71531.6 71962.6 74626.9 7722.2 7741.2 7879.4 8059.9 8269.7 11662.4 12759.3 12879.1 12987.0 13236.1 11615.8 11820.3 11943.2 11945.2 12098.8 11298.7 11510.2 11600.9 11606.0 11932.0 11642.8 11754.0 11937.7 12026.3 12147.6 6993.8 8056.4 8064.8 8268.8 8396.0 11469.6 11549.4 11662.2 11929.5 11961.3 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#3] mootools.html Cycles:180 Average:5364.87 Median:5134.33 stddev:2958.71 (57.6%) stddev-sans-first:2950.96 08:59:09 INFO - PROCESS | 1641 | Values: 1264.2 1329.1 1387.5 1445.6 1459.9 8022.3 8297.6 8455.8 8475.2 8500.5 7413.6 7648.7 7649.5 8055.5 8299.3 2281.0 2332.9 2334.5 2339.7 2342.6 2314.3 2355.9 2357.9 2360.3 2367.4 1955.6 2020.1 2251.8 2290.6 2303.3 2314.4 2334.4 2336.4 2339.8 2344.8 2246.4 2287.8 2288.7 2359.4 2364.0 8333.7 8408.0 8408.0 8425.1 8432.3 4995.5 5139.0 5143.4 5148.4 5155.0 3392.8 3528.9 3550.8 3600.6 3613.8 1164.9 1220.7 1257.4 1259.3 1266.6 10340.0 10764.6 10784.5 10856.2 10972.6 3634.5 4450.3 4509.6 4564.7 4565.8 8658.7 8662.7 8666.3 8667.6 8680.5 5126.4 5129.7 5142.6 5143.0 5145.5 3837.9 4173.9 4722.7 4760.7 4815.1 3674.8 3688.4 3735.9 3768.6 3772.7 1146.7 1152.4 1155.8 1164.4 1166.5 666.7 761.7 794.6 802.1 819.2 5069.9 5462.2 5539.2 5672.3 5701.3 540.2 540.4 568.7 575.9 577.7 3613.9 3669.0 3759.6 3807.4 3835.9 9648.8 10027.8 10034.9 10043.5 10066.9 9959.0 9990.2 10107.0 10109.4 10152.3 7612.0 7680.9 7831.1 7845.2 7875.3 5391.9 5464.4 5544.5 5646.3 5708.6 9057.9 9142.7 9152.9 9235.6 9365.8 9187.8 9375.2 9389.0 9489.5 9532.4 6185.4 6389.8 6435.5 6538.5 6683.3 7663.8 8007.9 8008.8 8019.4 8027.2 6114.9 7162.3 7425.6 7429.3 7450.7 7187.7 7212.9 7219.3 7313.5 7434.6 6182.6 6423.6 6432.8 6437.5 6441.1 4908.5 4942.2 4976.1 4980.9 5009.9 4803.9 4875.5 4943.9 5079.0 5107.9 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#4] prototype.html Cycles:180 Average:4333.13 Median:4727.12 stddev:1292.84 (27.3%) stddev-sans-first:1261.49 08:59:09 INFO - PROCESS | 1641 | Values: 353.1 353.2 363.3 370.2 376.4 4889.7 5094.0 5114.6 5175.7 5202.5 4483.9 4845.9 5076.4 5081.6 5133.3 3738.8 3797.1 3970.9 4066.8 4078.0 3451.1 3852.6 3936.6 3981.4 3981.5 3477.4 3634.9 3947.3 3951.5 3985.8 3381.8 3760.3 3969.8 4005.2 4029.8 3214.8 3316.6 3518.7 3565.4 3579.3 3761.2 4214.4 4698.3 4959.4 4966.8 4538.7 4554.0 4567.7 4572.8 4601.9 5331.8 5350.4 5380.8 5393.7 5405.6 3228.9 3921.9 3948.5 3977.5 3998.2 5129.4 5401.0 5568.5 5582.6 5655.0 3660.1 3922.1 3954.9 4068.3 4070.6 3157.8 3213.4 3806.8 3898.6 4169.8 4381.9 4622.1 4636.0 4665.4 4686.6 5435.2 5446.9 5472.3 5487.0 5500.7 1124.8 1143.8 1294.0 1317.9 1357.7 911.5 959.6 963.8 977.2 981.4 4087.9 4344.0 4703.4 4724.9 4775.9 4718.6 5267.8 5268.2 5271.8 5287.0 4076.6 4321.5 4725.7 4750.6 4762.7 2604.6 2762.4 2780.6 2781.3 2782.3 4646.9 4892.9 5099.5 5142.8 5447.9 4728.5 5232.7 5391.2 5397.7 5441.6 4872.3 5718.3 5723.7 5766.9 5788.6 4592.6 4683.8 5037.4 5536.8 5575.6 4912.9 5041.8 5471.8 5507.2 5516.9 4926.4 5437.9 5586.8 5600.6 5711.6 3428.5 3717.3 3725.2 3765.2 3771.0 5521.1 5527.3 5540.8 5542.7 5544.9 5161.3 5243.9 5359.7 5390.3 5390.5 4095.9 5315.9 5336.5 5376.6 5424.0 4291.8 4872.5 5251.2 5272.4 5306.8 4554.3 4653.0 5239.9 5355.5 5374.5 4433.6 4562.4 4793.1 5216.9 5272.8 08:59:09 INFO - PROCESS | 1641 | 08:59:09 INFO - PROCESS | 1641 | [#5] yui.html Cycles:180 Average:20504.91 Median:4588.14 stddev:29902.50 (651.7%) stddev-sans-first:29949.44 08:59:09 INFO - PROCESS | 1641 | Values: 708.9 764.0 816.3 823.7 824.8 14086.5 15958.6 15981.6 16016.0 16016.4 14321.8 14462.3 14478.0 14489.3 14503.9 680.1 707.1 710.8 850.6 859.4 869.2 873.8 880.2 884.3 884.4 894.1 894.3 900.6 941.5 942.3 937.1 938.2 938.4 938.8 939.6 532.8 555.5 671.3 695.1 703.1 15061.8 15080.2 15085.9 15102.5 15157.8 13694.9 13754.0 13826.7 13880.5 13959.3 12346.8 12431.5 12448.3 12737.2 13287.7 4236.9 4282.6 4568.7 4607.6 4616.6 99276.0 100746.0 105486.9 105989.5 106028.0 9906.3 10855.3 10916.9 10994.9 11209.7 21896.0 21912.9 22073.0 22265.2 22284.0 7358.4 7634.2 7780.3 7789.7 7902.2 4137.7 4185.2 4216.6 4352.4 4400.9 3323.9 3392.2 3445.8 3449.9 3490.3 641.9 647.4 649.7 668.2 715.1 570.7 587.6 591.4 591.7 599.5 9340.6 10473.4 10484.7 10619.4 10634.5 542.0 567.4 597.8 654.0 707.9 4984.3 5090.9 5104.3 5134.0 5137.7 101520.0 104269.0 104319.0 105954.5 108823.0 92422.5 93229.1 94028.5 95669.0 95803.6 67465.7 77448.2 79722.0 82293.8 83878.6 9529.9 9841.0 9872.2 9902.7 9936.4 44382.8 49130.0 51582.0 52433.2 53266.7 68118.0 69250.0 70336.0 71620.3 71771.6 11604.8 11746.3 11840.6 11996.2 12360.4 12400.1 12436.2 12497.9 12684.4 13150.3 11804.5 12176.4 12207.1 12399.0 12721.6 11924.7 11984.1 12201.3 12465.4 12502.9 11576.0 11687.9 12017.1 12030.7 12052.9 9725.3 9804.3 10182.7 10306.1 10513.9 8104.1 9851.1 9983.7 10018.6 10028.3 08:59:09 INFO - PROCESS | 1641 | -------- Summary: end -------- 08:59:09 INFO - PROCESS | 1641 | 08:59:10 INFO - TEST-INFO | 1641: exit 0 08:59:10 INFO - TEST-OK | dromaeo_css | took 1119043ms 08:59:10 INFO - TEST-START | kraken 08:59:10 INFO - operating with platform_type : mac_ 08:59:10 INFO - Initialising browser for kraken test... 08:59:10 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmppSj8fh/profile http://localhost:49241/getInfo.html) 08:59:12 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 08:59:12 INFO - PROCESS | 1651 | colorDepth:24 08:59:12 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 08:59:12 INFO - PROCESS | 1651 | __metrics 08:59:12 INFO - PROCESS | 1651 | 1486400352479 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: ReferenceError: LogManager is not defined (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:65:3) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/Resources/browser/features/shield-recipe-client@bootstrap.js:65:3 < callBootstrapMethod@XPIProvider.jsm:4903:11 < observe@XPIProvider.jsm:2884:15 < canQuitApplication@quit.js:67:3 < goQuitApplication@quit.js:109:8 < whenDone@quit.js:100:7 < promise callback*goQuitApplication@quit.js:103:5 < onload@getInfo.html:7:5 08:59:12 INFO - PROCESS | 1651 | 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:59:12 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:59:13 INFO - TEST-INFO | 1651: exit 0 08:59:13 INFO - Browser initialized. 08:59:13 INFO - Running cycle 1/1 for kraken test... 08:59:13 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/T/tmppSj8fh/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:59:25 INFO - PROCESS | 1652 | RSS: Main: 213315584 08:59:25 INFO - PROCESS | 1652 | 08:59:28 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection) 08:59:28 INFO - PROCESS | 1652 | RSS: Main: 265953280 08:59:28 INFO - PROCESS | 1652 | 08:59:31 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-dft) 08:59:32 INFO - PROCESS | 1652 | RSS: Main: 276381696 08:59:32 INFO - PROCESS | 1652 | 08:59:35 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-fft) 08:59:35 INFO - PROCESS | 1652 | RSS: Main: 373772288 08:59:35 INFO - PROCESS | 1652 | 08:59:37 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator) 08:59:38 INFO - PROCESS | 1652 | RSS: Main: 276320256 08:59:38 INFO - PROCESS | 1652 | 08:59:40 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur) 08:59:41 INFO - PROCESS | 1652 | RSS: Main: 278802432 08:59:41 INFO - PROCESS | 1652 | 08:59:43 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom) 08:59:44 INFO - PROCESS | 1652 | RSS: Main: 319406080 08:59:44 INFO - PROCESS | 1652 | 08:59:47 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate) 08:59:48 INFO - PROCESS | 1652 | RSS: Main: 318971904 08:59:48 INFO - PROCESS | 1652 | 08:59:50 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial) 08:59:51 INFO - PROCESS | 1652 | RSS: Main: 317800448 08:59:51 INFO - PROCESS | 1652 | 08:59:53 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox) 08:59:53 INFO - PROCESS | 1652 | RSS: Main: 293883904 08:59:53 INFO - PROCESS | 1652 | 08:59:55 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes) 08:59:56 INFO - PROCESS | 1652 | RSS: Main: 298561536 08:59:56 INFO - PROCESS | 1652 | 08:59:58 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm) 08:59:59 INFO - PROCESS | 1652 | RSS: Main: 299413504 08:59:59 INFO - PROCESS | 1652 | 09:00:02 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:00:02 INFO - PROCESS | 1652 | RSS: Main: 314531840 09:00:02 INFO - PROCESS | 1652 | 09:00:05 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:00:06 INFO - PROCESS | 1652 | RSS: Main: 308711424 09:00:06 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49241/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49241/tests/kraken/driver.html?testName=ai-astar) 09:00:08 INFO - PROCESS | 1652 | RSS: Main: 353255424 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | __start_tp_report 09:00:08 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:00:08 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:00:08 INFO - PROCESS | 1652 | |i|pagename|runs| 09:00:08 INFO - PROCESS | 1652 | |0;ai-astar;115;115;114;113;115;113;113;115;115;115 09:00:08 INFO - PROCESS | 1652 | |1;audio-beat-detection;160;137;110;141;111;109;109;111;129;110 09:00:08 INFO - PROCESS | 1652 | |2;audio-dft;187;181;182;185;185;186;184;186;184;184 09:00:08 INFO - PROCESS | 1652 | |3;audio-fft;82;57;60;90;61;59;58;58;75;59 09:00:08 INFO - PROCESS | 1652 | |4;audio-oscillator;89;71;87;69;67;66;82;68;69;83 09:00:08 INFO - PROCESS | 1652 | |5;imaging-gaussian-blur;87;87;87;82;88;71;135;131;96;84 09:00:08 INFO - PROCESS | 1652 | |6;imaging-darkroom;108;95;96;95;95;95;96;95;94;95 09:00:08 INFO - PROCESS | 1652 | |7;imaging-desaturate;88;88;87;86;85;87;90;88;68;129 09:00:08 INFO - PROCESS | 1652 | |8;json-parse-financial;59;58;55;55;56;58;58;56;58;58 09:00:08 INFO - PROCESS | 1652 | |9;json-stringify-tinderbox;69;71;81;71;72;69;69;70;71;70 09:00:08 INFO - PROCESS | 1652 | |10;stanford-crypto-aes;87;85;85;83;85;82;92;103;87;82 09:00:08 INFO - PROCESS | 1652 | |11;stanford-crypto-ccm;137;128;151;146;142;144;142;151;194;150 09:00:08 INFO - PROCESS | 1652 | |12;stanford-crypto-pbkdf2;172;167;169;170;169;166;166;165;167;166 09:00:08 INFO - PROCESS | 1652 | |13;stanford-crypto-sha256-iterative;67;67;67;66;67;66;66;66;65;65 09:00:08 INFO - PROCESS | 1652 | __end_tp_report 09:00:08 INFO - PROCESS | 1652 | __start_cc_report 09:00:08 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,2030 09:00:08 INFO - PROCESS | 1652 | __end_cc_report 09:00:08 INFO - PROCESS | 1652 | __startTimestamp1486400408413__endTimestamp 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:00:08 INFO - PROCESS | 1652 | Number of tests: 14 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#0] ai-astar Cycles:10 Average:114.30 Median:115.00 stddev:0.95 (0.8%) stddev-sans-first:0.97 09:00:08 INFO - PROCESS | 1652 | Values: 115.0 115.0 114.0 113.0 115.0 113.0 113.0 115.0 115.0 115.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:120.00 stddev:18.08 (15.1%) stddev-sans-first:13.21 09:00:08 INFO - PROCESS | 1652 | Values: 160.0 137.0 110.0 141.0 111.0 109.0 109.0 111.0 129.0 110.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#2] audio-dft Cycles:10 Average:184.40 Median:185.00 stddev:1.84 (1.0%) stddev-sans-first:1.69 09:00:08 INFO - PROCESS | 1652 | Values: 187.0 181.0 182.0 185.0 185.0 186.0 184.0 186.0 184.0 184.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#3] audio-fft Cycles:10 Average:65.90 Median:60.50 stddev:11.93 (19.7%) stddev-sans-first:11.14 09:00:08 INFO - PROCESS | 1652 | Values: 82.0 57.0 60.0 90.0 61.0 59.0 58.0 58.0 75.0 59.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#4] audio-oscillator Cycles:10 Average:75.10 Median:76.50 stddev:9.04 (11.8%) stddev-sans-first:8.06 09:00:08 INFO - PROCESS | 1652 | Values: 89.0 71.0 87.0 69.0 67.0 66.0 82.0 68.0 69.0 83.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#5] imaging-gaussian-blur Cycles:10 Average:94.80 Median:87.00 stddev:21.09 (24.2%) stddev-sans-first:22.18 09:00:08 INFO - PROCESS | 1652 | Values: 87.0 87.0 87.0 82.0 88.0 71.0 135.0 131.0 96.0 84.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#6] imaging-darkroom Cycles:10 Average:96.40 Median:95.00 stddev:4.12 (4.3%) stddev-sans-first:0.60 09:00:08 INFO - PROCESS | 1652 | Values: 108.0 95.0 96.0 95.0 95.0 95.0 96.0 95.0 94.0 95.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#7] imaging-desaturate Cycles:10 Average:89.60 Median:87.50 stddev:15.18 (17.4%) stddev-sans-first:16.09 09:00:08 INFO - PROCESS | 1652 | Values: 88.0 88.0 87.0 86.0 85.0 87.0 90.0 88.0 68.0 129.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:58.00 stddev:1.45 (2.5%) stddev-sans-first:1.36 09:00:08 INFO - PROCESS | 1652 | Values: 59.0 58.0 55.0 55.0 56.0 58.0 58.0 56.0 58.0 58.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#9] json-stringify-tinderbox Cycles:10 Average:71.30 Median:71.00 stddev:3.56 (5.0%) stddev-sans-first:3.68 09:00:08 INFO - PROCESS | 1652 | Values: 69.0 71.0 81.0 71.0 72.0 69.0 69.0 70.0 71.0 70.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#10] stanford-crypto-aes Cycles:10 Average:87.10 Median:85.00 stddev:6.31 (7.4%) stddev-sans-first:6.70 09:00:08 INFO - PROCESS | 1652 | Values: 87.0 85.0 85.0 83.0 85.0 82.0 92.0 103.0 87.0 82.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#11] stanford-crypto-ccm Cycles:10 Average:148.50 Median:148.00 stddev:17.48 (11.8%) stddev-sans-first:18.03 09:00:08 INFO - PROCESS | 1652 | Values: 137.0 128.0 151.0 146.0 142.0 144.0 142.0 151.0 194.0 150.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.70 Median:168.00 stddev:2.21 (1.3%) stddev-sans-first:1.72 09:00:08 INFO - PROCESS | 1652 | Values: 172.0 167.0 169.0 170.0 169.0 166.0 166.0 165.0 167.0 166.0 09:00:08 INFO - PROCESS | 1652 | 09:00:08 INFO - PROCESS | 1652 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.20 Median:66.50 stddev:0.79 (1.2%) stddev-sans-first:0.78 09:00:08 INFO - PROCESS | 1652 | Values: 67.0 67.0 67.0 66.0 67.0 66.0 66.0 66.0 65.0 65.0 09:00:08 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:00:08 INFO - PROCESS | 1652 | 09:00:09 INFO - TEST-INFO | 1652: exit 0 09:00:09 INFO - TEST-OK | kraken | took 59114ms 09:00:09 INFO - SUITE-END | took 1178s 09:00:09 INFO - Completed test suite (00:19:38) 09:00:09 INFO - javascript benchmark 09:00:09 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2771.0716909835205, 3372.662733726629, 3380.611229708583, 3394.8981530554092, 3434.2959693262883, 5576.832695019151, 5705.4294570542925, 5722.284714410699, 5753.9642864284415, 5794.246747922764, 5722.256106706122, 5757.769689212437, 5779.884402311951, 5796.623219490741, 5826.533877289818, 5198.298229738984, 5280.815171468999, 5286.4449232830575, 5291.206319052135, 5334.0665383557725, 5190.584753219752, 5194.766235519411, 5207.557357624606, 5343.545798607123, 5348.652337598063, 5277.70972596507, 5294.0, 5306.4958828911385, 5307.442718514559, 5325.3875804282425, 5291.100512912814, 5296.496832800878, 5300.469953004707, 5303.60222983274, 5307.65500242483, 5105.412877519058, 5239.764210610531, 5254.842354729326, 5255.632105752599, 5259.342582177228, 5842.386549412293, 5880.117982302646, 5880.617759845639, 5895.528357731371, 5909.674967876765, 6256.906146407808, 6258.561900666955, 6274.058891166362, 6294.244690637107, 6307.18006659136, 6167.383261673796, 6205.596636218676, 6243.344448832852, 6300.21247344082, 6316.6841657917375, 5839.649621022752, 5840.269966254218, 5843.941560584382, 5917.31950825652, 5966.164736936832, 6256.436920677161, 6309.810705678837, 6320.083587879732, 6323.810285691437, 6332.366763323631, 6105.572609917352, 6221.813345599639, 6235.906461403082, 6238.625682459067, 6251.937170680995, 6155.630662160284, 6204.0, 6224.1597384352535, 6226.1905952226825, 6279.654618995908, 6248.875022499525, 6257.9687101564205, 6261.123442718023, 6261.499080073583, 6262.906056409158, 6246.843828904313, 6257.405546473078, 6281.560290779602, 6294.685265736694, 6321.273053598895, 4866.416030076413, 4873.415190177201, 4907.288443175719, 4958.578520825698, 4964.925526117111, 3623.4564815277918, 3777.6033516480375, 4454.064646424287, 4470.977645111753, 4501.3022981437925, 5909.3795151508475, 5909.970450147722, 5917.437843404956, 5932.40675932412, 5949.9107513387335, 6145.494353883327, 6243.219597550306, 6282.120503129474, 6282.340354262737, 6287.622742635456, 5858.648481091148, 5904.380040095814, 5921.644701317935, 5961.672108034099, 5978.940210597925, 5378.085725426608, 5436.510714035756, 5441.891162176735, 5514.503694667499, 5518.779248830082, 6272.874542509125, 6275.592086514362, 6288.9056664150075, 6290.842728931813, 6292.716827742671, 6233.563650544418, 6254.749810007731, 6295.96852015737, 6314.242290925118, 6328.335524769833, 6210.0, 6364.745410183541, 6369.522285828489, 6391.2330520337855, 6392.488600911823, 6109.0403061477, 6132.661461321519, 6145.647957449354, 6210.658413787104, 6262.405071518198, 6130.846728831815, 6327.588706734047, 6331.018692102732, 6335.809925702236, 6350.174477317919, 6134.938650613437, 6179.752809887555, 6188.9690551546955, 6197.783077592263, 6203.16257305258, 5946.494547963472, 6001.669908155093, 6102.389761023862, 6118.010103775335, 6135.723892424762, 6001.309849367204, 6021.337652858269, 6022.066579680157, 6030.698465076816, 6049.818505444844, 6162.568620196723, 6169.907451388322, 6170.784022559189, 6180.6291622502795, 6180.876382472416, 5327.467253274642, 5663.065594177079, 5861.443162899518, 5899.557533185114, 5922.289325280994], "value": 5737.060250205618, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [492.5025724018742, 494.83423053277176, 497.99751001244994, 499.15891722447657, 508.8829569199085, 30490.695093049064, 40448.0, 42057.579424205745, 42401.36397954033, 44099.77950110245, 43187.78406107965, 43565.0, 43661.345079823746, 43672.78163609178, 43709.0, 8474.957625211866, 9226.584803683816, 9433.292503062263, 9434.0, 9447.669331573396, 8430.873536896952, 11123.777524449506, 11397.544098236061, 11423.942880285587, 11552.711182220428, 8821.338399620054, 9920.404775713445, 11361.943190284037, 11364.090872730203, 11420.71448213793, 8529.74410767678, 8732.257758090569, 11422.143339249547, 11501.769964600702, 11521.539138434453, 11008.779824403548, 11317.886821131766, 11349.319040857576, 11486.138539609563, 11489.482973266224, 40531.0, 41009.179816403506, 41062.178756425004, 41105.383419248734, 41172.0, 3857.0, 3951.7826519541413, 3975.403689446577, 3978.920421591581, 4015.3575427931682, 29480.410391792044, 29547.113586592222, 29602.55596166038, 31213.90751323693, 31268.9055883043, 3251.138448311199, 3264.281857991238, 3274.0832566881313, 3334.5164951081956, 3376.3584918865336, 452834.73582632205, 456218.0, 456894.0, 457900.71049644874, 460996.0, 59806.700966494886, 59862.0, 60139.0, 60298.698506507186, 60381.698091509264, 64098.35901641017, 64123.0, 64137.0, 64173.0, 64254.357456424834, 16081.839181608035, 16838.915805420893, 16842.32630694783, 16850.83149168517, 16875.24061417239, 11685.240459370114, 11952.163348565517, 11969.760604787856, 12989.740205196033, 13113.344332783323, 16272.837271627131, 16553.420630277884, 16568.917155414387, 16589.91705041491, 16725.414610488817, 6520.054592084121, 6523.119378883885, 6545.869082618321, 6563.245226798884, 6573.506986975996, 757.5416872791992, 848.893888263967, 885.9955700221458, 889.8309321228945, 899.8290324838259, 28866.0, 29775.66009529533, 29812.571554271104, 30273.545896811567, 30346.84826575853, 1073.6886302972207, 1078.1267173589417, 1115.9274647147909, 1129.785340785248, 1228.791105512065, 15133.772993405108, 15147.924260378848, 15328.386864525293, 15462.072275663497, 15776.842231577766, 569894.0, 571104.0, 572091.0, 572582.0, 574273.0, 59524.40475595189, 59634.40365596375, 59646.0, 59760.20719378339, 59963.0, 1433.4224691443424, 1448.594393569781, 1461.6126726417508, 1487.6280929767559, 1490.0984904133277, 27315.043973460834, 29042.0, 29104.854475727487, 29767.851160744056, 29781.702182977893, 49120.75439622779, 49504.0, 49581.752091239316, 49665.751671241414, 49678.751606241734, 488649.0, 492111.0, 492128.0, 493915.0, 493965.0, 23211.187608433625, 23725.52548948977, 23771.286861394186, 26035.479290414463, 26422.207333780018, 45137.548624514646, 46668.53331466642, 47206.76396617995, 48972.020559587894, 50731.74634126806, 38739.03152421212, 38882.0279493015, 38979.61020389873, 39108.2178356437, 40402.39396409115, 38978.4153237696, 39084.0, 40050.59949400469, 40338.596614033486, 40476.0, 34620.480692789126, 35618.10954726153, 35669.64330356767, 38499.03752406212, 38609.80695096507, 29781.70218297876, 31247.87521247857, 31709.890153844506, 31950.840245798623, 32006.0, 23574.764252357258, 23608.881955590114, 23775.405614859767, 23844.403889902893, 24097.638535421633], "value": 19858.338910375984, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.1964780125215, 361.9800910949897, 383.72180169377185, 400.3534292118229, 408.4057696052244, 15284.770728439082, 15518.84481155188, 17386.0, 20380.082896269665, 20978.160873565077, 19608.803911960877, 20074.293685036788, 21291.36125916225, 21468.4632884178, 21881.90590470478, 5663.7168141592965, 5679.0, 5827.242458480391, 5887.705614719268, 5893.764249430018, 5691.0, 5777.59556831022, 5821.56338274629, 5840.766369345221, 5849.649021058729, 4165.708400411972, 4393.736375817445, 5761.625494342854, 5803.738831752581, 6423.421892029716, 6302.401271879165, 6490.415862572368, 6521.054447105191, 6524.249711283216, 6534.117894084309, 5649.180868774005, 5720.570957178186, 5733.684647344395, 5736.7992120276, 5756.251687280668, 25442.872785635955, 25702.871485642638, 25728.614070788954, 25753.484930301478, 26679.67175424364, 2720.945581088387, 2836.5817091454273, 2844.6017557541754, 2849.28767808048, 2865.555838844982, 14984.700305993929, 15151.166685832275, 15291.0, 15374.000689955117, 16072.0, 1741.5210817025293, 1786.3211979447856, 1787.3922866225546, 1789.1769785898503, 1812.7643406357222, 116370.0, 122279.0, 122280.38859805732, 122487.16269255968, 126091.0, 11461.598844040418, 11502.769944601228, 11579.536818527165, 11623.76752464946, 11722.886325799036, 12042.698932526582, 13996.510122145675, 14526.709465810625, 14561.635959100895, 14602.12387256768, 8709.042005379402, 8858.0, 8885.777855553533, 8919.554022298858, 8977.461352318882, 7340.265973402617, 8818.030692429533, 8841.381103322834, 8953.507557084293, 9082.091790820998, 7027.508074434741, 7123.180834204029, 7153.7138514459875, 7170.892436613455, 7254.673539690727, 1639.4098124675108, 1877.1271358818215, 1906.0816165805252, 1921.1354890299422, 1921.817427344428, 1021.9489025548693, 1050.9360987104365, 1060.3054998975683, 1061.6549621373085, 1083.8320060390652, 22632.547349053257, 22835.543289134122, 22845.200417985292, 22865.542689146456, 22886.0, 1067.6067731610167, 1074.1084899533369, 1080.3032044331362, 1096.1066730614523, 1109.389835590422, 11277.379744113989, 11863.822042669366, 11905.107116966263, 11906.52373905034, 11931.642050738492, 372038.0, 646582.0, 714431.0, 715668.0, 742108.0, 98009.0, 105775.4711226439, 116320.0, 121988.0, 124505.0, 977.6578197630772, 1001.3191030099603, 1028.804527139841, 1031.1544533482472, 1039.3555995282973, 19017.0, 19321.613567728848, 19624.41126766199, 19668.606627867077, 19687.507812304808, 52537.0, 53765.46234537761, 54841.72579137079, 55212.44787552073, 56827.0, 70109.64945175241, 71194.28805712084, 71531.64234178796, 71962.64018679873, 74626.88059679001, 7722.227777222233, 7741.225877412214, 7879.409044321814, 8059.919400805917, 8269.710560130367, 11662.416879156179, 12759.298238596595, 12879.098463107492, 12987.0, 13236.073474856668, 11615.825762613398, 11820.349880756297, 11943.16397852142, 11945.223560468541, 12098.818517722066, 11298.661040168809, 11510.194286399872, 11600.883991160317, 11606.0, 11931.985781208694, 11642.76714465689, 11754.0, 11937.701557461132, 12026.338551379758, 12147.635570932885, 6993.790186294419, 8056.395770317366, 8064.798380040546, 8268.751937441886, 8396.034456037634, 11469.598564050219, 11549.36478493691, 11662.183647144619, 11929.463174157358, 11961.342126183143], "value": 10001.041286751491, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1264.184600932398, 1329.1094966372532, 1387.4796951143321, 1445.5952333346663, 1459.9343029563668, 8022.318102961248, 8297.626606802693, 8455.788605284872, 8475.194856488639, 8500.532470714115, 7413.592252426114, 7648.7322943696845, 7649.502782319146, 8055.476394034384, 8299.294559962394, 2280.9885950570224, 2332.93001209964, 2334.486412989148, 2339.6607491913664, 2342.601757701195, 2314.3404129823, 2355.9057637694473, 2357.8938947747306, 2360.3155085025323, 2367.4318163640687, 1955.5795503966644, 2020.0909590684178, 2251.7860803223666, 2290.5533420982915, 2303.251443280932, 2314.4213946513373, 2334.4047267946735, 2336.3575016870327, 2339.754325795801, 2344.75380085092, 2246.382244882654, 2287.79409853114, 2288.7253529576556, 2359.362971997551, 2363.976360236393, 8333.66665333386, 8407.95796021022, 8408.0, 8425.11536288687, 8432.32541396693, 4995.450500444948, 5138.974305128488, 5143.3570803649545, 5148.433672296044, 5155.02054609623, 3392.8473218705217, 3528.947065794015, 3550.804705741209, 3600.6039335673054, 3613.8012409317475, 1164.935928523931, 1220.6948262934266, 1257.4404390046434, 1259.2633309513944, 1266.5756971414587, 10340.0, 10764.56941722338, 10784.514696838583, 10856.240063195579, 10972.561097556169, 3634.47300141478, 4450.287953927356, 4509.616682581952, 4564.680472366969, 4565.771711414416, 8658.653653853775, 8662.653493860302, 8666.263367613823, 8667.609957551798, 8680.479171249744, 5126.410462796826, 5129.692218466904, 5142.6400151989, 5143.022825663112, 5145.4597267287145, 3837.9232415351535, 4173.895652608649, 4722.740249286322, 4760.690555113906, 4815.061063092663, 3674.81625918703, 3688.354537955847, 3735.869244576427, 3768.5854555998817, 3772.7170462215445, 1146.656003199037, 1152.3546813784308, 1155.8324043013715, 1164.3828770751516, 1166.4692564882957, 666.6799936030733, 761.6953218712559, 794.5629903553023, 802.1056521977953, 819.2377225785633, 5069.8732531687, 5462.2079798429, 5539.196816461671, 5672.347680016768, 5701.287339082614, 540.1546579602909, 540.446042806119, 568.6843801690019, 575.9107338362561, 577.7226931073109, 3613.9413395011347, 3668.9816550917076, 3759.605241449663, 3807.409851472971, 3835.8657446989223, 9648.807023859483, 10027.849582256273, 10034.946330635179, 10043.497825108861, 10066.899331006744, 9958.950205249072, 9990.200783937267, 10107.039831216023, 10109.443980581138, 10152.340097893613, 7611.961940190264, 7680.884786728092, 7831.060272767304, 7845.215478452224, 7875.330596899328, 5391.892162156813, 5464.398916119144, 5544.500994910478, 5646.322441307069, 5708.628939118944, 9057.864132037894, 9142.680006199751, 9152.862707059532, 9235.630574776933, 9365.812683746151, 9187.816243675223, 9375.249980001447, 9388.95305523468, 9489.525523723649, 9532.380395274286, 6185.350538193605, 6389.7763578274535, 6435.485161187187, 6538.542302038812, 6683.298253683293, 7663.808404789702, 8007.919920800718, 8008.83982320362, 8019.358451323764, 8027.237412445808, 6114.938850611437, 7162.283771622796, 7425.591592462466, 7429.294217049372, 7450.6647200875, 7187.712491500281, 7212.855742885218, 7219.278072192738, 7313.524620899624, 7434.628268586657, 6182.567220294536, 6423.646699431576, 6432.839179020562, 6437.452816510649, 6441.13044738973, 4908.460069392293, 4942.233953737176, 4976.104301225814, 4980.875478112931, 5009.9479109385975, 4803.9414922355945, 4875.536824001714, 4943.887625284282, 5078.984203159309, 5107.87230319245], "value": 4291.506341455338, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.13481969175524, 353.1947160474118, 363.3260302139531, 370.2145561632312, 376.3752171395482, 4889.7066176029375, 5093.987874789461, 5114.590832733382, 5175.66358186718, 5202.505761952618, 4483.910321793563, 4845.933894543204, 5076.441591424949, 5081.551757749042, 5133.307003554519, 3738.7569807962464, 3797.0886987123167, 3970.880873573783, 4066.8169932352966, 4078.0008897820076, 3451.0674022547328, 3852.576216616184, 3936.6457018868296, 3981.3828856527284, 3981.4625025621604, 3477.4436090225568, 3634.8913581357674, 3947.3486874665646, 3951.5258169019758, 3985.847642273169, 3381.763276570641, 3760.2855457463133, 3969.761814291152, 4005.1989602079702, 4029.7783621900785, 3214.8426566436297, 3316.585426821647, 3518.7185025197923, 3565.358235517606, 3579.266250418658, 3761.153740408414, 4214.4099826024385, 4698.318743782167, 4959.355283813117, 4966.751662416865, 4538.682292239545, 4554.0, 4567.6574256930535, 4572.788211124055, 4601.930971035404, 5331.76066052259, 5350.357957045179, 5380.838574842761, 5393.6763794172475, 5405.648632838853, 3228.9192770180935, 3921.901952451183, 3948.466956960831, 3977.4630424892557, 3998.200359927997, 5129.358830146231, 5401.0, 5568.49883510486, 5582.637128586629, 5655.0, 3660.1215708230366, 3922.1371298314325, 3954.8813535593977, 4068.2880495913096, 4070.61329173728, 3157.826319552401, 3213.4055199788113, 3806.847726090981, 3898.571157172709, 4169.833206671699, 4381.868543943687, 4622.121796858586, 4636.026434448803, 4665.4168228971375, 4686.5782079613, 5435.1953632457435, 5446.918296225481, 5472.261244731911, 5486.972565137149, 5500.724963751796, 1124.791913496006, 1143.7769634921276, 1294.0294778915813, 1317.92751398674, 1357.696611253208, 911.4986757283467, 959.5777857742571, 963.8361478548663, 977.2182254196614, 981.3621146254991, 4087.89780255496, 4344.046917305301, 4703.435587729495, 4724.858254252378, 4775.880602984953, 4718.598919091847, 5267.841964741064, 5268.209768534751, 5271.841844744664, 5287.0, 4076.592340765959, 4321.524632290446, 4725.740084295397, 4750.61995040396, 4762.714237145782, 2604.6353510508543, 2762.392273699783, 2780.569011803174, 2781.3324802047364, 2782.3461486550646, 4646.907061858744, 4892.902141957141, 5099.464556221617, 5142.845714628567, 5447.855950250412, 4728.503507131771, 5232.712200828915, 5391.174898465851, 5397.70312632809, 5441.646292990943, 4872.317875497433, 5718.313802343745, 5723.713814309268, 5766.855828604318, 5788.552861784554, 4592.632589392907, 4683.812647494062, 5037.395512538519, 5536.806211782568, 5575.609707320448, 4912.94371710084, 5041.798328066836, 5471.753771080232, 5507.246376811594, 5516.9448305516435, 4926.413632842786, 5437.918431223456, 5586.770910399706, 5600.607957443103, 5711.5716321276905, 3428.451447768345, 3717.3308804415465, 3725.1990821973404, 3765.1904840459433, 3770.981145094257, 5521.122818241817, 5527.2538207341495, 5540.806071787468, 5542.667439953616, 5544.861378465571, 5161.303224064854, 5243.868903277448, 5359.732013399236, 5390.272313237662, 5390.541803946709, 4095.9385609215287, 5315.920261196163, 5336.493033161843, 5376.596755243294, 5424.0, 4291.785410729389, 4872.464028956882, 5251.2123181523075, 5272.362070171569, 5306.814261500984, 4554.294084416921, 4652.952700811632, 5239.895202096013, 5355.464453554613, 5374.543163830962, 4433.623142032963, 4562.429696287964, 4793.065352256276, 5216.895662086661, 5272.815451459181], "value": 3947.037484999726, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.8653155900379, 763.9923600763991, 816.3102178659033, 823.7388368927295, 824.8020475085981, 14086.506972255973, 15958.601034974132, 15981.600459988505, 16015.999999999985, 16016.359345626175, 14321.78517322241, 14462.276886155703, 14477.986540942138, 14489.347979340928, 14503.92748036261, 680.0649107477219, 707.052549583558, 710.7725527831096, 850.5662112322728, 859.3597769661595, 869.1612593846936, 873.8339715454075, 880.1638443478689, 884.3190743127788, 884.3941899798658, 894.0924961164424, 894.2622336572354, 900.5767289373983, 941.4962994797785, 942.2838642631581, 937.1003836317144, 938.2400255792795, 938.4088024544493, 938.760616042908, 939.6053657463814, 532.7602578839537, 555.469526602094, 671.2850813883199, 695.1137299942563, 703.1210986267166, 15061.849381506154, 15080.245987700571, 15085.924570377189, 15102.5469235922, 15157.848421515753, 13694.863051369459, 13754.0, 13826.65433364174, 13880.51418200358, 13959.302034898315, 12346.81479777804, 12431.502739890393, 12448.25310481374, 12737.172083814523, 13287.66780830487, 4236.872893813191, 4282.571742825724, 4568.725876447424, 4607.562281583278, 4616.630669546428, 99276.0, 100745.99999999927, 105486.94513054924, 105989.47005265001, 106028.0, 9906.293164270153, 10855.348679079281, 10916.890831091747, 10994.890051099546, 11209.663710088547, 21896.0, 21912.89043554804, 22073.0, 22265.220717274817, 22284.0, 7358.41132709382, 7634.236576342321, 7780.260875216845, 7789.7273595423885, 7902.209779022051, 4137.668986481074, 4185.204811085884, 4216.599423054805, 4352.39066530686, 4400.933985990213, 3323.916902077419, 3392.1519620094978, 3445.758796884244, 3449.9310013800086, 3490.336836001152, 641.913341698874, 647.4076220258411, 649.6719156825867, 668.211510417702, 715.0882624653608, 570.6861226325505, 587.6356658871441, 591.4351794036685, 591.7011908985935, 599.4574909706636, 9340.57967391469, 10473.371597704163, 10484.685459436228, 10619.415932123653, 10634.521446534924, 541.9647722898, 567.3645517020963, 597.7698586044404, 654.0, 707.8979199301947, 4984.327115839315, 5090.949090509048, 5104.336436263262, 5133.973205358943, 5137.6660517066275, 101520.0, 104269.0, 104319.0, 105954.47022764992, 108823.0, 92422.53788731148, 93229.06770932204, 94028.52985735028, 95669.0, 95803.56294655586, 67465.66267168632, 77448.2255177441, 79722.0, 82293.76559351495, 83878.58060709658, 9529.857052144362, 9841.0, 9872.160866326443, 9902.702918912444, 9936.403815771077, 44382.77808611065, 49130.0, 51582.0, 52433.213501798265, 53266.73366633142, 68118.0, 69249.96125058028, 70336.0, 71620.28379716136, 71771.64114179395, 11604.76790464169, 11746.29522228669, 11840.585579504675, 11996.220245684352, 12360.44378002974, 12400.13199076056, 12436.19164754288, 12497.937510312753, 12684.429200685807, 13150.27673477968, 11804.468798903898, 12176.39118044112, 12207.145499815282, 12399.00807935381, 12721.55474558386, 11924.701882453008, 11984.10119241043, 12201.267923924594, 12465.376731163587, 12502.937485312515, 11575.993842028023, 11687.919080587382, 12017.098717596391, 12030.69155982112, 12052.879471205526, 9725.270604704534, 9804.313698041069, 10182.74543136398, 10306.12397946183, 10513.896040915593, 8104.068032176375, 9851.064148905842, 9983.700488985342, 10018.59925602997, 10028.348157369748], "value": 7387.905803985384, "unit": "score"}], "name": "dromaeo_css", "value": 7228.0597261550765, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 115.0, 114.0, 113.0, 115.0, 113.0, 113.0, 115.0, 115.0, 115.0], "value": 114.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [160.0, 137.0, 110.0, 141.0, 111.0, 109.0, 109.0, 111.0, 129.0, 110.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 181.0, 182.0, 185.0, 185.0, 186.0, 184.0, 186.0, 184.0, 184.0], "value": 184.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 57.0, 60.0, 90.0, 61.0, 59.0, 58.0, 58.0, 75.0, 59.0], "value": 65.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 87.0, 69.0, 67.0, 66.0, 82.0, 68.0, 69.0, 83.0], "value": 75.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 87.0, 87.0, 82.0, 88.0, 71.0, 135.0, 131.0, 96.0, 84.0], "value": 94.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 95.0, 96.0, 95.0, 95.0, 95.0, 96.0, 95.0, 94.0, 95.0], "value": 96.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 86.0, 85.0, 87.0, 90.0, 88.0, 68.0, 129.0], "value": 89.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 55.0, 55.0, 56.0, 58.0, 58.0, 56.0, 58.0, 58.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 81.0, 71.0, 72.0, 69.0, 69.0, 70.0, 71.0, 70.0], "value": 71.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 85.0, 83.0, 85.0, 82.0, 92.0, 103.0, 87.0, 82.0], "value": 87.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 128.0, 151.0, 146.0, 142.0, 144.0, 142.0, 151.0, 194.0, 150.0], "value": 148.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 167.0, 169.0, 170.0, 169.0, 166.0, 166.0, 165.0, 167.0, 166.0], "value": 167.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 66.0, 65.0, 65.0], "value": 66.2, "unit": "score"}], "name": "kraken", "value": 1441.1, "alertThreshold": 2.0}]} 09:00:09 INFO - Return code: 0 09:00:09 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:00:09 ERROR - # TBPL SUCCESS # 09:00:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:09 INFO - 2017-02-06 09:00:09.711 screenresolution[1656:10073] starting screenresolution argv=screenresolution get 09:00:09 INFO - 2017-02-06 09:00:09.714 screenresolution[1656:10073] Display 0: 1600x1200x32@50 09:00:09 INFO - 2017-02-06 09:00:09.720 screenresolution[1657:10076] starting screenresolution argv=screenresolution list 09:00:09 INFO - Available Modes on Display 0 09:00:09 INFO - 1600x1200x32@50 1152x870x32@75 1280x1024x32@75 09:00:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:09 INFO - 640x480x32@60 1280x1024x32@60 1600x1200x32@60 09:00:09 INFO - 1280x960x32@60 1280x960x32@75 1344x1008x32@50 09:00:09 INFO - Graphics/Displays: 09:00:09 INFO - Intel Iris: 09:00:09 INFO - Chipset Model: Intel Iris 09:00:09 INFO - Type: GPU 09:00:09 INFO - Bus: Built-In 09:00:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:09 INFO - Vendor: Intel (0x8086) 09:00:09 INFO - Device ID: 0x0a2e 09:00:09 INFO - Revision ID: 0x0009 09:00:09 INFO - Displays: 09:00:09 INFO - Display: 09:00:09 INFO - Resolution: 1600 x 1200 @ 50 Hz 09:00:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:09 INFO - Display Serial Number: 1600x1200 50 09:00:09 INFO - Main Display: Yes 09:00:09 INFO - Mirror: Off 09:00:09 INFO - Online: Yes 09:00:09 INFO - Rotation: Supported 09:00:09 INFO - Return code: 0 09:00:09 INFO - Running post-action listener: _resource_record_post_action 09:00:09 INFO - [mozharness: 2017-02-06 17:00:09.958686Z] Finished run-tests step (success) 09:00:09 INFO - Running post-run listener: _resource_record_post_run 09:00:10 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 39846912; Write bytes: 508140032; Read time: 387; Write time: 1805 09:00:10 INFO - TinderboxPrint: CPU usage
53.5% 09:00:10 INFO - TinderboxPrint: I/O read bytes / time
39,846,912 / 387 09:00:10 INFO - TinderboxPrint: I/O write bytes / time
508,140,032 / 1,805 09:00:10 INFO - TinderboxPrint: CPU idle
2,230.7 (46.5%) 09:00:10 INFO - TinderboxPrint: CPU system
958.3 (20.0%) 09:00:10 INFO - TinderboxPrint: CPU user
1,608.6 (33.5%) 09:00:10 INFO - TinderboxPrint: Swap in / out
246,714,368 / 0 09:00:10 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205392384; Write bytes: 201517056; Read time: 15736; Write time: 437 09:00:10 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 35173376; Write bytes: 295514624; Read time: 352; Write time: 1335 09:00:10 INFO - Running post-run listener: _upload_blobber_files 09:00:10 INFO - Blob upload gear active. 09:00:10 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:00:10 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:00:10 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:00:10 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:00:10 INFO - (blobuploader) - INFO - Open directory for files ... 09:00:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:00:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:00:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:12 INFO - (blobuploader) - INFO - Done attempting. 09:00:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:00:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:00:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:13 INFO - (blobuploader) - INFO - Done attempting. 09:00:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:00:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:14 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:00:14 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:14 INFO - (blobuploader) - INFO - Done attempting. 09:00:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:00:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:16 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:00:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:16 INFO - (blobuploader) - INFO - Done attempting. 09:00:16 INFO - (blobuploader) - INFO - Iteration through files over. 09:00:16 INFO - Return code: 0 09:00:16 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:00:16 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:00:16 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d14d93a347f35392f6be6d6ddca0e928c6bc1d792a9d09a7d992b384995d1dac3a81545ccee8414fe4be15e861cc71d28eff626fe28e2ac2a17f887a161fc1cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce49fe1704037ba54d93c6e701a44fc1f42be1980084be5394adff1a2a4868910d39c31f07895ee5e391c141d6cfd4dc16f70fdd25c64028ad057fff7334fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4e74393eb4f6fe20eeac3808831ec20e4b3d9aed59afc49b5cdc7d1feb56c71818e6a40c29e38e4c87c8cc8a33baf9d080908e8ad7ec4b1a1f08c27e96599af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ec1e6dee8e5903ae6948cd2c5d1907965b9e9fdab5d6b13bfe22b21cb5bd3e9224c100133fe216be485b6b2aa89f00c041feb666a62ba3aa0571e7b0f5c9b46"} 09:00:16 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:00:16 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:00:16 INFO - Contents: 09:00:16 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/d14d93a347f35392f6be6d6ddca0e928c6bc1d792a9d09a7d992b384995d1dac3a81545ccee8414fe4be15e861cc71d28eff626fe28e2ac2a17f887a161fc1cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce49fe1704037ba54d93c6e701a44fc1f42be1980084be5394adff1a2a4868910d39c31f07895ee5e391c141d6cfd4dc16f70fdd25c64028ad057fff7334fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4e74393eb4f6fe20eeac3808831ec20e4b3d9aed59afc49b5cdc7d1feb56c71818e6a40c29e38e4c87c8cc8a33baf9d080908e8ad7ec4b1a1f08c27e96599af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ec1e6dee8e5903ae6948cd2c5d1907965b9e9fdab5d6b13bfe22b21cb5bd3e9224c100133fe216be485b6b2aa89f00c041feb666a62ba3aa0571e7b0f5c9b46"} 09:00:16 INFO - Running post-run listener: copy_logs_to_upload_dir 09:00:16 INFO - Copying logs to upload dir... 09:00:16 INFO - mkdir: /builds/slave/test/build/upload/logs 09:00:16 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1262.303125 ========= master_lag: 0.35 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 2 secs) (at 2017-02-06 09:00:16.976758) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:00:16.980489) ========= 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.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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/d14d93a347f35392f6be6d6ddca0e928c6bc1d792a9d09a7d992b384995d1dac3a81545ccee8414fe4be15e861cc71d28eff626fe28e2ac2a17f887a161fc1cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce49fe1704037ba54d93c6e701a44fc1f42be1980084be5394adff1a2a4868910d39c31f07895ee5e391c141d6cfd4dc16f70fdd25c64028ad057fff7334fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4e74393eb4f6fe20eeac3808831ec20e4b3d9aed59afc49b5cdc7d1feb56c71818e6a40c29e38e4c87c8cc8a33baf9d080908e8ad7ec4b1a1f08c27e96599af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ec1e6dee8e5903ae6948cd2c5d1907965b9e9fdab5d6b13bfe22b21cb5bd3e9224c100133fe216be485b6b2aa89f00c041feb666a62ba3aa0571e7b0f5c9b46"} build_url:https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011372 build_url: 'https://queue.taskcluster.net/v1/task/P_MAMtOsQuak6C5TbdFO3A/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/d14d93a347f35392f6be6d6ddca0e928c6bc1d792a9d09a7d992b384995d1dac3a81545ccee8414fe4be15e861cc71d28eff626fe28e2ac2a17f887a161fc1cb", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ce49fe1704037ba54d93c6e701a44fc1f42be1980084be5394adff1a2a4868910d39c31f07895ee5e391c141d6cfd4dc16f70fdd25c64028ad057fff7334fc0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/f4e74393eb4f6fe20eeac3808831ec20e4b3d9aed59afc49b5cdc7d1feb56c71818e6a40c29e38e4c87c8cc8a33baf9d080908e8ad7ec4b1a1f08c27e96599af", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/9ec1e6dee8e5903ae6948cd2c5d1907965b9e9fdab5d6b13bfe22b21cb5bd3e9224c100133fe216be485b6b2aa89f00c041feb666a62ba3aa0571e7b0f5c9b46"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-06 09:00:17.021200) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:00:17.021567) ========= 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.DlLRXdrcRR/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.vwCVfzsChN/Listeners TMPDIR=/var/folders/gx/gg1mk0bs7f17x8sk8mkdg3_h00000w/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.005127 ========= master_lag: 0.73 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-06 09:00:17.752666) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:00:17.752972) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-06 09:00:17.754235) ========= ========= Total master_lag: 2.99 =========