builder: mozilla-aurora_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0172 starttime: 1486486206.56 results: success (0) buildid: 20170207072756 builduid: d53d0f91353448a08f10ca46c03c9da5 revision: 9b8c5ed2e94a4311c450017696923fb52021880a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.562689) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.563432) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.563902) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.598141) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.598741) ========= 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.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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-07 08:50:06-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 46.6M=0s 2017-02-07 08:50:06 (46.6 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150709 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.776768) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.777078) ========= 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.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.020199 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:06.829057) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 08:50:06.829413) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev 9b8c5ed2e94a4311c450017696923fb52021880a --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 9b8c5ed2e94a4311c450017696923fb52021880a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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-07 08:50:06,915 truncating revision to first 12 chars 2017-02-07 08:50:06,915 Setting DEBUG logging. 2017-02-07 08:50:06,915 attempt 1/10 2017-02-07 08:50:06,915 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/9b8c5ed2e94a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-07 08:50:08,094 unpacking tar archive at: mozilla-aurora-9b8c5ed2e94a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.453025 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 08:50:08.307626) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:08.307943) ========= script_repo_revision: 9b8c5ed2e94a4311c450017696923fb52021880a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:08.311356) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:08.311635) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-07 08:50:08.331129) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-07 08:50:08.331534) ========= /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.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:08 INFO - MultiFileLogger online at 20170207 08:50:08 in /builds/slave/test 08:50:08 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:50:08 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:50:08 INFO - {'append_to_log': False, 08:50:08 INFO - 'base_work_dir': '/builds/slave/test', 08:50:08 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 08:50:08 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:50:08 INFO - 'branch': 'Mozilla-Aurora', 08:50:08 INFO - 'buildbot_json_path': 'buildprops.json', 08:50:08 INFO - 'config_files': ('talos/mac_config.py',), 08:50:08 INFO - 'default_actions': ('clobber', 08:50:08 INFO - 'read-buildbot-config', 08:50:08 INFO - 'download-and-extract', 08:50:08 INFO - 'populate-webroot', 08:50:08 INFO - 'create-virtualenv', 08:50:08 INFO - 'install', 08:50:08 INFO - 'run-tests'), 08:50:08 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:50:08 INFO - 'download_minidump_stackwalk': True, 08:50:08 INFO - 'download_symbols': 'ondemand', 08:50:08 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:50:08 INFO - 'tooltool.py': '/tools/tooltool.py', 08:50:08 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:50:08 INFO - '/tools/misc-python/virtualenv.py')}, 08:50:08 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:50:08 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:50:08 INFO - 'installer_path': 'installer.exe', 08:50:08 INFO - 'log_level': 'info', 08:50:08 INFO - 'log_name': 'talos', 08:50:08 INFO - 'log_to_console': True, 08:50:08 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:50:08 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:50:08 INFO - 'opt_config_files': (), 08:50:08 INFO - 'pip_index': False, 08:50:08 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:50:08 INFO - 'cmd': ('bash', 08:50:08 INFO - '-c', 08:50:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:50:08 INFO - 'enabled': True, 08:50:08 INFO - 'halt_on_failure': False, 08:50:08 INFO - 'name': 'check_screen_resolution'},), 08:50:08 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:50:08 INFO - 'cmd': ('bash', 08:50:08 INFO - '-c', 08:50:08 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:50:08 INFO - 'enabled': True, 08:50:08 INFO - 'halt_on_failure': False, 08:50:08 INFO - 'name': 'check_screen_resolution'},), 08:50:08 INFO - 'run_cmd_checks_enabled': True, 08:50:08 INFO - 'sps_profile': False, 08:50:08 INFO - 'sps_profile_interval': 0, 08:50:08 INFO - 'suite': 'dromaeojs', 08:50:08 INFO - 'system_bits': '32', 08:50:08 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:50:08 INFO - 'title': 't-yosemite-r7-0172', 08:50:08 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:50:08 INFO - 'use_talos_json': True, 08:50:08 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:50:08 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:50:08 INFO - 'work_dir': 'build'} 08:50:08 INFO - [mozharness: 2017-02-07 16:50:08.541491Z] Running clobber step. 08:50:08 INFO - Running pre-action listener: _resource_record_pre_action 08:50:08 INFO - Running main action method: clobber 08:50:08 INFO - rmtree: /builds/slave/test/build 08:50:08 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:50:10 INFO - Running post-action listener: _resource_record_post_action 08:50:10 INFO - [mozharness: 2017-02-07 16:50:10.724771Z] Finished clobber step (success) 08:50:10 INFO - [mozharness: 2017-02-07 16:50:10.724983Z] Running read-buildbot-config step. 08:50:10 INFO - Running pre-action listener: _resource_record_pre_action 08:50:10 INFO - Running main action method: read_buildbot_config 08:50:10 INFO - Using buildbot properties: 08:50:10 INFO - { 08:50:10 INFO - "project": "", 08:50:10 INFO - "product": "firefox", 08:50:10 INFO - "script_repo_revision": "production", 08:50:10 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs", 08:50:10 INFO - "repository": "", 08:50:10 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs", 08:50:10 INFO - "buildid": "20170207072756", 08:50:10 INFO - "pgo_build": "False", 08:50:10 INFO - "basedir": "/builds/slave/test", 08:50:10 INFO - "buildnumber": 2, 08:50:10 INFO - "slavename": "t-yosemite-r7-0172", 08:50:10 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:50:10 INFO - "platform": "yosemite_r7", 08:50:10 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 08:50:10 INFO - "branch": "mozilla-aurora", 08:50:10 INFO - "repo_path": "releases/mozilla-aurora", 08:50:10 INFO - "revision": "9b8c5ed2e94a4311c450017696923fb52021880a", 08:50:10 INFO - "stage_platform": "macosx64", 08:50:10 INFO - "builduid": "d53d0f91353448a08f10ca46c03c9da5", 08:50:10 INFO - "slavebuilddir": "test" 08:50:10 INFO - } 08:50:10 INFO - Found installer url https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg. 08:50:10 INFO - Running post-action listener: _resource_record_post_action 08:50:10 INFO - [mozharness: 2017-02-07 16:50:10.729653Z] Finished read-buildbot-config step (success) 08:50:10 INFO - [mozharness: 2017-02-07 16:50:10.729808Z] Running download-and-extract step. 08:50:10 INFO - Running pre-action listener: _resource_record_pre_action 08:50:10 INFO - Running main action method: download_and_extract 08:50:10 INFO - mkdir: /builds/slave/test/build/tests 08:50: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:50:10 INFO - trying https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.test_packages.json 08:50:10 INFO - Downloading https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/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:50:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/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:50:12 INFO - Downloaded 1199 bytes. 08:50:12 INFO - Reading from file /builds/slave/test/build/firefox-53.0a2.en-US.mac.test_packages.json 08:50:12 INFO - Using the following test package requirements: 08:50:12 INFO - {u'common': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:50:12 INFO - u'cppunittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.cppunittest.tests.zip'], 08:50:12 INFO - u'gtest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.gtest.tests.zip'], 08:50:12 INFO - u'jittest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'jsshell-mac.zip'], 08:50:12 INFO - u'mochitest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.mochitest.tests.zip'], 08:50:12 INFO - u'mozbase': [u'firefox-53.0a2.en-US.mac.common.tests.zip'], 08:50:12 INFO - u'reftest': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.reftest.tests.zip'], 08:50:12 INFO - u'talos': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.talos.tests.zip'], 08:50:12 INFO - u'web-platform': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.web-platform.tests.zip'], 08:50:12 INFO - u'xpcshell': [u'firefox-53.0a2.en-US.mac.common.tests.zip', 08:50:12 INFO - u'firefox-53.0a2.en-US.mac.xpcshell.tests.zip']} 08:50:12 INFO - Downloading packages: [u'firefox-53.0a2.en-US.mac.common.tests.zip'] for test suite category: common 08:50:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:50:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:50:12 INFO - Fetch https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:50:12 INFO - Expected file size: 16356899 08:50:12 INFO - Obtained file size: 16356899 08:50:19 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:50:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip 08:50:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip'}, attempt #1 08:50:19 INFO - Fetch https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.common.tests.zip into memory 08:50:20 INFO - Expected file size: 16356899 08:50:20 INFO - Obtained file size: 16356899 08:50:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip 08:50:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip'}, attempt #1 08:50:27 INFO - Fetch https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.talos.tests.zip into memory 08:50:28 INFO - Expected file size: 14403485 08:50:28 INFO - Obtained file size: 14403485 08:50:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:28 INFO - trying https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:50:28 INFO - Downloading https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a2.en-US.mac.dmg 08:50:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/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:50:30 INFO - Downloaded 73817520 bytes. 08:50:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:50:30 INFO - mkdir: /builds/slave/test/properties 08:50:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:50:30 INFO - Writing to file /builds/slave/test/properties/build_url 08:50:30 INFO - Contents: 08:50:30 INFO - build_url:https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg 08:50:31 INFO - Running post-action listener: _resource_record_post_action 08:50:31 INFO - Running post-action listener: set_extra_try_arguments 08:50:31 INFO - [mozharness: 2017-02-07 16:50:31.150248Z] Finished download-and-extract step (success) 08:50:31 INFO - [mozharness: 2017-02-07 16:50:31.150396Z] Running populate-webroot step. 08:50:31 INFO - Running pre-action listener: _resource_record_pre_action 08:50:31 INFO - Running main action method: populate_webroot 08:50:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:50:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:50:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:50:31 INFO - '--tppagecycles', 08:50:31 INFO - '7'], 08:50:31 INFO - 'tests': ['tsvgm']}}, 08:50:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:50:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:50:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:50:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:50:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:50:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['damp', 'tps']}, 08:50:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'tests': ['damp', 'tps']}, 08:50:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['dromaeo_dom']}, 08:50:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:50:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['basic_compositor_video']}, 08:50:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:50:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'other-e10s': {'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['a11yr', 08:50:31 INFO - 'ts_paint', 08:50:31 INFO - 'tpaint', 08:50:31 INFO - 'sessionrestore', 08:50:31 INFO - 'sessionrestore_no_auto_restore', 08:50:31 INFO - 'tabpaint']}, 08:50:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['tsvgx', 08:50:31 INFO - 'tsvgr_opacity', 08:50:31 INFO - 'tart', 08:50:31 INFO - 'tscrollx', 08:50:31 INFO - 'cart', 08:50:31 INFO - 'tsvg_static']}, 08:50:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:50:31 INFO - 'tsvgr_opacity', 08:50:31 INFO - 'tart', 08:50:31 INFO - 'tscrollx', 08:50:31 INFO - 'cart', 08:50:31 INFO - 'tsvg_static']}, 08:50:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'talos_options': ['--disable-e10s'], 08:50:31 INFO - 'tests': ['tp5o']}, 08:50:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:50:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'talos_options': ['--disable-e10s', 08:50:31 INFO - '--xperf_path', 08:50:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:31 INFO - 'tests': ['tp5n']}, 08:50:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:50:31 INFO - 'talos_options': ['--xperf_path', 08:50:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:50:31 INFO - 'tests': ['tp5n']}}, 08:50:31 INFO - 'talos.zip': {'path': '', 08:50:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:50:31 INFO - Running post-action listener: _resource_record_post_action 08:50:31 INFO - [mozharness: 2017-02-07 16:50:31.169204Z] Finished populate-webroot step (success) 08:50:31 INFO - [mozharness: 2017-02-07 16:50:31.169298Z] Running create-virtualenv step. 08:50:31 INFO - Running pre-action listener: _resource_record_pre_action 08:50:31 INFO - Running main action method: create_virtualenv 08:50:31 INFO - Creating virtualenv /builds/slave/test/build/venv 08:50:31 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:50:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:50:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:50:31 INFO - Using real prefix '/tools/python27' 08:50:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:50:32 INFO - Installing distribute.............................................................................................................................................................................................done. 08:50:34 INFO - Installing pip.................done. 08:50:34 INFO - Return code: 0 08:50:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:50:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:50:35 INFO - Reading from file tmpfile_stdout 08:50:35 INFO - Output received: 08:50:35 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:50:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:50:35 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:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:35 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:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:35 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:35 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:50:35 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:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:35 INFO - 'HOME': '/Users/cltbld', 08:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:35 INFO - 'LOGNAME': 'cltbld', 08:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:35 INFO - 'MOZ_NO_REMOTE': '1', 08:50:35 INFO - 'NO_EM_RESTART': '1', 08:50:35 INFO - 'PAGER': '/bin/cat', 08:50:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:35 INFO - 'PWD': '/builds/slave/test', 08:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:35 INFO - 'SHELL': '/bin/bash', 08:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:35 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:35 INFO - 'USER': 'cltbld', 08:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:35 INFO - 'XPC_FLAGS': '0x0', 08:50:35 INFO - 'XPC_SERVICE_NAME': '0', 08:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:35 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:50:35 INFO - Cleaning up... 08:50:35 INFO - Return code: 0 08:50:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:50:35 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:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:35 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:50:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:35 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:35 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:50:35 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:50:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:35 INFO - 'HOME': '/Users/cltbld', 08:50:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:35 INFO - 'LOGNAME': 'cltbld', 08:50:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:35 INFO - 'MOZ_NO_REMOTE': '1', 08:50:35 INFO - 'NO_EM_RESTART': '1', 08:50:35 INFO - 'PAGER': '/bin/cat', 08:50:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:35 INFO - 'PWD': '/builds/slave/test', 08:50:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:35 INFO - 'SHELL': '/bin/bash', 08:50:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:35 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:35 INFO - 'USER': 'cltbld', 08:50:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:35 INFO - 'XPC_FLAGS': '0x0', 08:50:35 INFO - 'XPC_SERVICE_NAME': '0', 08:50:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:35 INFO - Downloading/unpacking psutil>=3.1.1 08:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:50:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:39 INFO - Installing collected packages: psutil 08:50:39 INFO - Running setup.py install for psutil 08:50:39 INFO - building 'psutil._psutil_osx' extension 08:50:39 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:50:39 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:50:39 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:50:39 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:50:39 INFO - building 'psutil._psutil_posix' extension 08:50:39 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:50:39 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:50:39 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:50:39 INFO - ^ 08:50:39 INFO - 1 warning generated. 08:50:39 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:50:39 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:50:39 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:50:39 INFO - Successfully installed psutil 08:50:39 INFO - Cleaning up... 08:50:40 INFO - Return code: 0 08:50:40 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:40 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:40 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:50:40 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:50:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:40 INFO - 'HOME': '/Users/cltbld', 08:50:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:40 INFO - 'LOGNAME': 'cltbld', 08:50:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:40 INFO - 'MOZ_NO_REMOTE': '1', 08:50:40 INFO - 'NO_EM_RESTART': '1', 08:50:40 INFO - 'PAGER': '/bin/cat', 08:50:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:40 INFO - 'PWD': '/builds/slave/test', 08:50:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:40 INFO - 'SHELL': '/bin/bash', 08:50:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:40 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:40 INFO - 'USER': 'cltbld', 08:50:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:40 INFO - 'XPC_FLAGS': '0x0', 08:50:40 INFO - 'XPC_SERVICE_NAME': '0', 08:50:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:40 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:50:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:50:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:50:42 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:50:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:50:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:50:42 INFO - Installing collected packages: mozsystemmonitor 08:50:42 INFO - Running setup.py install for mozsystemmonitor 08:50:43 INFO - Successfully installed mozsystemmonitor 08:50:43 INFO - Cleaning up... 08:50:43 INFO - Return code: 0 08:50:43 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:50:43 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:50:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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:50:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:43 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:43 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:50:43 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:50:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:43 INFO - 'HOME': '/Users/cltbld', 08:50:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:43 INFO - 'LOGNAME': 'cltbld', 08:50:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:43 INFO - 'MOZ_NO_REMOTE': '1', 08:50:43 INFO - 'NO_EM_RESTART': '1', 08:50:43 INFO - 'PAGER': '/bin/cat', 08:50:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:43 INFO - 'PWD': '/builds/slave/test', 08:50:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:43 INFO - 'SHELL': '/bin/bash', 08:50:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:43 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:43 INFO - 'USER': 'cltbld', 08:50:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:43 INFO - 'XPC_FLAGS': '0x0', 08:50:43 INFO - 'XPC_SERVICE_NAME': '0', 08:50:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:43 INFO - Downloading/unpacking jsonschema==2.5.1 08:50:43 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:50:43 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:50:43 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:50:43 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:50:43 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:50:43 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:50:46 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:50:46 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:50: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:50: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:50: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:50: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:50: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:50: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:50:46 INFO - Downloading functools32-3.2.3-2.tar.gz 08:50:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:50:46 INFO - warning: no files found matching '*.txt' 08:50:46 INFO - no previously-included directories found matching 'build' 08:50:46 INFO - no previously-included directories found matching 'dist' 08:50:46 INFO - no previously-included directories found matching '.git*' 08:50:46 INFO - Installing collected packages: jsonschema, functools32 08:50:46 INFO - Running setup.py install for functools32 08:50:46 INFO - warning: no files found matching '*.txt' 08:50:46 INFO - no previously-included directories found matching 'build' 08:50:46 INFO - no previously-included directories found matching 'dist' 08:50:46 INFO - no previously-included directories found matching '.git*' 08:50:46 INFO - Successfully installed jsonschema functools32 08:50:46 INFO - Cleaning up... 08:50:46 INFO - Return code: 0 08:50:46 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:50: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:50:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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:50:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50: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', '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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:50: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 functools32==3.2.3-2 08:50:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:46 INFO - 'HOME': '/Users/cltbld', 08:50:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:46 INFO - 'LOGNAME': 'cltbld', 08:50:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:46 INFO - 'MOZ_NO_REMOTE': '1', 08:50:46 INFO - 'NO_EM_RESTART': '1', 08:50:46 INFO - 'PAGER': '/bin/cat', 08:50:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:46 INFO - 'PWD': '/builds/slave/test', 08:50:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:46 INFO - 'SHELL': '/bin/bash', 08:50:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:46 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:46 INFO - 'USER': 'cltbld', 08:50:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:46 INFO - 'XPC_FLAGS': '0x0', 08:50:46 INFO - 'XPC_SERVICE_NAME': '0', 08:50:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:47 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:50:47 INFO - Cleaning up... 08:50:47 INFO - Return code: 0 08:50:47 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:50:47 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:50:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:47 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:50:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:47 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:47 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:50:47 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:50:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:47 INFO - 'HOME': '/Users/cltbld', 08:50:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:47 INFO - 'LOGNAME': 'cltbld', 08:50:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:47 INFO - 'MOZ_NO_REMOTE': '1', 08:50:47 INFO - 'NO_EM_RESTART': '1', 08:50:47 INFO - 'PAGER': '/bin/cat', 08:50:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:47 INFO - 'PWD': '/builds/slave/test', 08:50:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:47 INFO - 'SHELL': '/bin/bash', 08:50:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:47 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:47 INFO - 'USER': 'cltbld', 08:50:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:47 INFO - 'XPC_FLAGS': '0x0', 08:50:47 INFO - 'XPC_SERVICE_NAME': '0', 08:50:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:47 INFO - Downloading/unpacking blobuploader==1.2.4 08:50:47 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:50:47 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:50:47 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:50:47 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:50:47 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:50:47 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:50:49 INFO - Downloading blobuploader-1.2.4.tar.gz 08:50:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:50:50 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:50: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:50: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:50: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:50: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:50: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:50: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:50:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:50:50 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:50: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:50: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:50: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:50: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:50: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:50: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:50:51 INFO - Downloading docopt-0.6.1.tar.gz 08:50:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:50:51 INFO - Installing collected packages: blobuploader, requests, docopt 08:50:51 INFO - Running setup.py install for blobuploader 08:50:51 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:50:51 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:50:51 INFO - Running setup.py install for requests 08:50:51 INFO - Running setup.py install for docopt 08:50:52 INFO - Successfully installed blobuploader requests docopt 08:50:52 INFO - Cleaning up... 08:50:52 INFO - Return code: 0 08:50:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:50:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:52 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:52 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:50:52 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:50:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:52 INFO - 'HOME': '/Users/cltbld', 08:50:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:52 INFO - 'LOGNAME': 'cltbld', 08:50:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:52 INFO - 'MOZ_NO_REMOTE': '1', 08:50:52 INFO - 'NO_EM_RESTART': '1', 08:50:52 INFO - 'PAGER': '/bin/cat', 08:50:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:52 INFO - 'PWD': '/builds/slave/test', 08:50:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:52 INFO - 'SHELL': '/bin/bash', 08:50:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:52 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:52 INFO - 'USER': 'cltbld', 08:50:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:52 INFO - 'XPC_FLAGS': '0x0', 08:50:52 INFO - 'XPC_SERVICE_NAME': '0', 08:50:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-y8uUVz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-JnlqAN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-IBzOvR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-iooN_s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-a0GKGt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:52 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-JJI_BO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-0h6pot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-UK0Q5J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-XGp38i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-MR31ZH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-zKMC7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-W4bbx3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-a0cRew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-BZrhsS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:53 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-RPlcKv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:54 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Xv7kDX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:54 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-w7ks0y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:54 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:50:54 INFO - Running setup.py install for manifestparser 08:50:54 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:50:54 INFO - Running setup.py install for mozcrash 08:50:54 INFO - Running setup.py install for mozdebug 08:50:54 INFO - Running setup.py install for mozdevice 08:50:54 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:50:54 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:50:54 INFO - Running setup.py install for mozfile 08:50:54 INFO - Running setup.py install for mozhttpd 08:50:55 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Running setup.py install for mozinfo 08:50:55 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Running setup.py install for mozInstall 08:50:55 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Running setup.py install for mozleak 08:50:55 INFO - Running setup.py install for mozlog 08:50:55 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Running setup.py install for moznetwork 08:50:55 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:50:55 INFO - Running setup.py install for mozprocess 08:50:55 INFO - Running setup.py install for mozprofile 08:50:56 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:50:56 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:50:56 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:50:56 INFO - Running setup.py install for mozrunner 08:50:56 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:50:56 INFO - Running setup.py install for mozscreenshot 08:50:56 INFO - Running setup.py install for moztest 08:50:56 INFO - Running setup.py install for mozversion 08:50:56 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:50:56 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:50:56 INFO - Cleaning up... 08:50:56 INFO - Return code: 0 08:50:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:50:56 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:50:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:50:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:56 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:50:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:50:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:50:56 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:50:56 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:50:56 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:50:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:50:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:50:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:50:56 INFO - 'HOME': '/Users/cltbld', 08:50:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:50:56 INFO - 'LOGNAME': 'cltbld', 08:50:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:50:56 INFO - 'MOZ_NO_REMOTE': '1', 08:50:56 INFO - 'NO_EM_RESTART': '1', 08:50:56 INFO - 'PAGER': '/bin/cat', 08:50:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:50:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:50:56 INFO - 'PWD': '/builds/slave/test', 08:50:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:50:56 INFO - 'SHELL': '/bin/bash', 08:50:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:50:56 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:50:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:50:56 INFO - 'USER': 'cltbld', 08:50:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:50:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:50:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:50:56 INFO - 'XPC_FLAGS': '0x0', 08:50:56 INFO - 'XPC_SERVICE_NAME': '0', 08:50:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:50:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Dz36YI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-eS3r_F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-7whIcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-Yn3wtm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-pyhC75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-LmhEdJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-guqMWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-pQRGoL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:50:57 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:50:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:50:57 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-BVOgXZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-ICddkj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-jl2mWI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-H5MwzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-dAiON0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-zGh_sA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-E2tF69-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-e7H1m7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:50:58 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:50:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:50:58 INFO - Running setup.py (path:/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/pip-3RxLDl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:50:58 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:50:58 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:50:58 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:50:58 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:50:58 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:50:58 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:50:58 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:50:58 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:50:58 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:50:58 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:50:58 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:50:58 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:50:58 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:51:01 INFO - Downloading blessings-1.6.tar.gz 08:51:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:51:01 INFO - Installing collected packages: blessings 08:51:01 INFO - Running setup.py install for blessings 08:51:01 INFO - Successfully installed blessings 08:51:01 INFO - Cleaning up... 08:51:01 INFO - Return code: 0 08:51:01 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:51:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:02 INFO - Reading from file tmpfile_stdout 08:51:02 INFO - Current package versions: 08:51:02 INFO - blessings == 1.6 08:51:02 INFO - blobuploader == 1.2.4 08:51:02 INFO - docopt == 0.6.1 08:51:02 INFO - functools32 == 3.2.3-2 08:51:02 INFO - jsonschema == 2.5.1 08:51:02 INFO - manifestparser == 1.1 08:51:02 INFO - mozInstall == 1.12 08:51:02 INFO - mozcrash == 1.0 08:51:02 INFO - mozdebug == 0.1 08:51:02 INFO - mozdevice == 0.49 08:51:02 INFO - mozfile == 1.2 08:51:02 INFO - mozhttpd == 0.7 08:51:02 INFO - mozinfo == 0.9 08:51:02 INFO - mozleak == 0.1 08:51:02 INFO - mozlog == 3.4 08:51:02 INFO - moznetwork == 0.27 08:51:02 INFO - mozprocess == 0.24 08:51:02 INFO - mozprofile == 0.28 08:51:02 INFO - mozrunner == 6.13 08:51:02 INFO - mozscreenshot == 0.1 08:51:02 INFO - mozsystemmonitor == 0.3 08:51:02 INFO - moztest == 0.8 08:51:02 INFO - mozversion == 1.4 08:51:02 INFO - psutil == 3.1.1 08:51:02 INFO - requests == 1.2.3 08:51:02 INFO - wsgiref == 0.1.2 08:51:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:51:02 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:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:02 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:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:02 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:51:02 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:51:02 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:51:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:51:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:02 INFO - 'HOME': '/Users/cltbld', 08:51:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:02 INFO - 'LOGNAME': 'cltbld', 08:51:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:02 INFO - 'MOZ_NO_REMOTE': '1', 08:51:02 INFO - 'NO_EM_RESTART': '1', 08:51:02 INFO - 'PAGER': '/bin/cat', 08:51:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:02 INFO - 'PWD': '/builds/slave/test', 08:51:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:02 INFO - 'SHELL': '/bin/bash', 08:51:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:51:02 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:51:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:02 INFO - 'USER': 'cltbld', 08:51:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:02 INFO - 'XPC_FLAGS': '0x0', 08:51:02 INFO - 'XPC_SERVICE_NAME': '0', 08:51:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 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:51:02 INFO - Cleaning up... 08:51:02 INFO - Return code: 0 08:51:02 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:51:02 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:51:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:51:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:02 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:51:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:51:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:51:03 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 0x10b9a9b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b9ac768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8532cb01c0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, '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.LSSNS8QAVB/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.9xR20QrT6K/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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:51:03 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:51:03 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:51:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:51:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:03 INFO - 'HOME': '/Users/cltbld', 08:51:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:03 INFO - 'LOGNAME': 'cltbld', 08:51:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:03 INFO - 'MOZ_NO_REMOTE': '1', 08:51:03 INFO - 'NO_EM_RESTART': '1', 08:51:03 INFO - 'PAGER': '/bin/cat', 08:51:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:03 INFO - 'PWD': '/builds/slave/test', 08:51:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:03 INFO - 'SHELL': '/bin/bash', 08:51:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:51:03 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:51:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:03 INFO - 'USER': 'cltbld', 08:51:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:03 INFO - 'XPC_FLAGS': '0x0', 08:51:03 INFO - 'XPC_SERVICE_NAME': '0', 08:51:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:51:03 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:51:03 INFO - Cleaning up... 08:51:03 INFO - Return code: 0 08:51:03 INFO - Running post-action listener: _resource_record_post_action 08:51:03 INFO - Running post-action listener: _start_resource_monitoring 08:51:03 INFO - Starting resource monitoring. 08:51:03 INFO - [mozharness: 2017-02-07 16:51:03.642220Z] Finished create-virtualenv step (success) 08:51:03 INFO - [mozharness: 2017-02-07 16:51:03.643234Z] Running install step. 08:51:03 INFO - Running pre-action listener: _resource_record_pre_action 08:51:03 INFO - Running main action method: install 08:51:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:51:03 INFO - Reading from file tmpfile_stdout 08:51:03 INFO - Detecting whether we're running mozinstall >=1.0... 08:51:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:51:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:51:04 INFO - Reading from file tmpfile_stdout 08:51:04 INFO - Output received: 08:51:04 INFO - Usage: mozinstall [options] installer 08:51:04 INFO - Options: 08:51:04 INFO - -h, --help show this help message and exit 08:51:04 INFO - -d DEST, --destination=DEST 08:51:04 INFO - Directory to install application into. [default: 08:51:04 INFO - "/builds/slave/test"] 08:51:04 INFO - --app=APP Application being installed. [default: firefox] 08:51:04 INFO - mkdir: /builds/slave/test/build/application 08:51:04 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:51:04 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:51:24 INFO - Reading from file tmpfile_stdout 08:51:24 INFO - Output received: 08:51:24 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 08:51:24 INFO - Running post-action listener: _resource_record_post_action 08:51:24 INFO - [mozharness: 2017-02-07 16:51:24.065996Z] Finished install step (success) 08:51:24 INFO - [mozharness: 2017-02-07 16:51:24.066185Z] Running run-tests step. 08:51:24 INFO - Running pre-action listener: _resource_record_pre_action 08:51:24 INFO - Running main action method: run_tests 08:51:24 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:51:24 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:51:24 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:51:24 INFO - 2017-02-07 08:51:24.080 screenresolution[1954:10139] starting screenresolution argv=screenresolution get 08:51:24 INFO - 2017-02-07 08:51:24.083 screenresolution[1954:10139] Display 0: 1600x1200x32@60 08:51:24 INFO - 2017-02-07 08:51:24.089 screenresolution[1955:10141] starting screenresolution argv=screenresolution list 08:51:24 INFO - Available Modes on Display 0 08:51:24 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:51:24 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:51:24 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:51:24 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:51:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:51:24 INFO - Intel Iris: 08:51:24 INFO - Chipset Model: Intel Iris 08:51:24 INFO - Type: GPU 08:51:24 INFO - Bus: Built-In 08:51:24 INFO - VRAM (Dynamic, Max): 1536 MB 08:51:24 INFO - Vendor: Intel (0x8086) 08:51:24 INFO - Device ID: 0x0a2e 08:51:24 INFO - Revision ID: 0x0009 08:51:24 INFO - Displays: 08:51:24 INFO - Display: 08:51:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:51:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:51:24 INFO - Display Serial Number: 1600x1200 60 08:51:24 INFO - Main Display: Yes 08:51:24 INFO - Mirror: Off 08:51:24 INFO - Online: Yes 08:51:24 INFO - Rotation: Supported 08:51:24 INFO - Return code: 0 08:51:24 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:51:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:51:24 INFO - Python 2.7.3 08:51:24 INFO - Return code: 0 08:51:24 INFO - grabbing minidump binary from tooltool 08:51:24 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:51:24 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 0x10b787d30>, '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 0x10b6aedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10b77cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:51:24 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:51:24 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:51:24 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:51:24 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:51:24 INFO - Return code: 0 08:51:24 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:51:24 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:51:24 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:51:24 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:51:24 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:51:24 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/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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:51:24 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/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0172 --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:51:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LSSNS8QAVB/Render', 08:51:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:51:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:51:24 INFO - 'HOME': '/Users/cltbld', 08:51:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:51:24 INFO - 'LOGNAME': 'cltbld', 08:51:24 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:51:24 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:51:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:51:24 INFO - 'MOZ_NO_REMOTE': '1', 08:51:24 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:51:24 INFO - 'NO_EM_RESTART': '1', 08:51:24 INFO - 'PAGER': '/bin/cat', 08:51:24 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:51:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:51:24 INFO - 'PWD': '/builds/slave/test', 08:51:24 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:51:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:51:24 INFO - 'SHELL': '/bin/bash', 08:51:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.9xR20QrT6K/Listeners', 08:51:24 INFO - 'TMPDIR': '/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/', 08:51:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:51:24 INFO - 'USER': 'cltbld', 08:51:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:51:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:51:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:51:24 INFO - 'XPC_FLAGS': '0x0', 08:51:24 INFO - 'XPC_SERVICE_NAME': '0', 08:51:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:51:24 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/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0172', '--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:51:24 INFO - mozversion application_buildid: 20170207072756 08:51:24 INFO - mozversion application_changeset: 9b8c5ed2e94a4311c450017696923fb52021880a 08:51:24 INFO - mozversion application_display_name: FirefoxDeveloperEdition 08:51:24 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:51:24 INFO - mozversion application_name: Firefox 08:51:24 INFO - mozversion application_remotingname: firefox-dev 08:51:24 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:51:24 INFO - mozversion application_vendor: Mozilla 08:51:24 INFO - mozversion application_version: 53.0a2 08:51:24 INFO - mozversion platform_buildid: 20170207072756 08:51:24 INFO - mozversion platform_changeset: 9b8c5ed2e94a4311c450017696923fb52021880a 08:51:24 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 08:51:24 INFO - mozversion platform_version: 53.0a2 08:51:24 INFO - using testdate: 1486486284 08:51:24 INFO - actual date: 1486486284 08:51:24 INFO - starting webserver on 'localhost:49344' 08:51:24 INFO - SUITE-START | Running 2 tests 08:51:24 INFO - TEST-START | dromaeo_css 08:51:24 INFO - operating with platform_type : mac_ 08:51:24 INFO - Initialising browser for dromaeo_css test... 08:51:24 INFO - TEST-INFO | started process 1965 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpIfreqw/profile http://localhost:49344/getInfo.html) 08:51:26 INFO - PROCESS | 1965 | __metrics Screen width/height:1600/1200 08:51:26 INFO - PROCESS | 1965 | colorDepth:24 08:51:26 INFO - PROCESS | 1965 | Browser inner width/height: 1024/689 08:51:26 INFO - PROCESS | 1965 | __metrics 08:51:26 INFO - PROCESS | 1965 | 1486486286909 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:51:26 INFO - PROCESS | 1965 | 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:51:26 INFO - PROCESS | 1965 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 08:51:27 INFO - TEST-INFO | 1965: exit 0 08:51:27 INFO - Browser initialized. 08:51:27 INFO - Running cycle 1/1 for dromaeo_css test... 08:51:27 INFO - TEST-INFO | started process 1967 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpIfreqw/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:51:39 INFO - PROCESS | 1967 | RSS: Main: 213176320 08:51:39 INFO - PROCESS | 1967 | 08:54:43 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49344/tests/dromaeo/cssquery-ext.html) 08:54:44 INFO - PROCESS | 1967 | RSS: Main: 344047616 08:54:44 INFO - PROCESS | 1967 | 08:56:29 INFO - PROCESS | 1967 | ************************* 08:56:29 INFO - PROCESS | 1967 | A coding exception was thrown and uncaught in a Task. 08:56:29 INFO - PROCESS | 1967 | 08:56:29 ERROR - PROCESS | 1967 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:56:29 INFO - PROCESS | 1967 | Full stack: 08:56:29 INFO - PROCESS | 1967 | ************************* 08:57:48 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-ext.html (next: http://localhost:49344/tests/dromaeo/cssquery-jquery.html) 08:57:48 INFO - PROCESS | 1967 | RSS: Main: 347344896 08:57:48 INFO - PROCESS | 1967 | 09:00:51 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49344/tests/dromaeo/cssquery-mootools.html) 09:00:51 INFO - PROCESS | 1967 | RSS: Main: 316452864 09:00:51 INFO - PROCESS | 1967 | 09:03:54 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49344/tests/dromaeo/cssquery-prototype.html) 09:03:54 INFO - PROCESS | 1967 | RSS: Main: 317419520 09:03:54 INFO - PROCESS | 1967 | 09:06:58 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49344/tests/dromaeo/cssquery-yui.html) 09:06:58 INFO - PROCESS | 1967 | RSS: Main: 315146240 09:06:58 INFO - PROCESS | 1967 | 09:10:01 INFO - PROCESS | 1967 | Cycle 1(1): loaded http://localhost:49344/tests/dromaeo/cssquery-yui.html (next: http://localhost:49344/tests/dromaeo/cssquery-dojo.html) 09:10:02 INFO - PROCESS | 1967 | RSS: Main: 389156864 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | __start_tp_report 09:10:02 INFO - PROCESS | 1967 | _x_x_mozilla_page_load 09:10:02 INFO - PROCESS | 1967 | _x_x_mozilla_page_load_details 09:10:02 INFO - PROCESS | 1967 | |i|pagename|runs| 09:10:02 INFO - PROCESS | 1967 | |0;dojo.html;3308.636050034496;3322.2691007978237;3361.5629968104145;3363.3945889739853;3412.726981841453;5737.741801618927;5762.510186634141;5774.307083150028;5786.768529258829;5822.883542329151;5731.856703582413;5809.04150815115;5812.041013232812;5819.9418005819925;5838.649681019142;5205.947940520594;5243.842684719445;5249.947500525003;5252.395974462928;5301.284326615915;5119.436861945183;5124.975004999015;5139.845804625848;5312.2828418163635;5313.628046036779;5213.791448342062;5239.318888544484;5240.60695447841;5241.685498870061;5243.842684719464;5237.502437268454;5241.8165364212455;5241.9213711794355;5247;5249.553787928012;5096.923546146811;5189.688618682853;5193.480651934814;5194;5248.448912864133;4805.75971201442;5800.738966746507;5801.854953626151;5808.506276966463;5811.418858114197;6171.6297022178815;6178.38216178383;6206.410391012847;6222.253329600431;6237.7816776413065;6219.844503887393;6231.688415579249;6247.812565623039;6249.500039996835;6252.906206406908;5880.852978675567;5894.764209431618;5896.174535565025;5922.733476993546;5949.702514874239;6166.352532984062;6202;6318.052292156168;6331.556791024676;6349.682515874188;6134.4978165938865;6138.449706860586;6141.263048434127;6151.5386346024225;6223.533235007392;6315.810525684236;6319.304876463585;6323.20959880015;6330.905036424458;6332.0818481320875;6294.716737746812;6301.338359472281;6305.684715764193;6306.49548036156;6311.337309582428;6026.367231440723;6131.682521312785;6143.723532440961;6173.660448675366;6295.874082518324;4911.238757992517;4993.27597498368;5000.199968005102;5001.7499125043605;5041.621878359138;4427.180971520214;4434.11317736454;4446.5331140230455;4457.732536047847;4468.151051300308;5663.433656634386;5686.0333743263745;5814.447627475383;5837.795677151279;5877.882442351214;6263.091851681481;6265.561410701298;6304.747810087637;6326.335734747877;6342.270638876496;5752.3097228332035;5872.559558033079;5896.616719913192;5898.498627616615;5899.380565040695;5493.478119578714;5497.945020549823;5509.779608815603;5516.889662206733;5525;6260.62436253808;6265.843353916189;6272.560920735505;6290.961991271389;6295.905561416491;5692.544596432358;5929.466348028698;6266.154069200599;6275.748970041147;6319.052142178526;6235.127082208403;6281.277653069773;6281.371862813865;6297.338779428277;6362.300146983735;6148.907766383419;6166.475849552839;6272.40412160844;6276;6277.403646653561;6163.7842675506145;6210.720517576629;6233.657148856856;6251.562390632612;6348.809535713936;6192.504599632109;6205.03821907605;6214.25428948529;6214.471769899428;6215.875682486416;6015.488683461955;6173.259208894961;6182.969085154546;6202.317745048132;6209.192804935329;4899.779509922133;5877.265341832271;5885.117232415172;6040.244969378828;6059.0608455689435;6113.749968914014;6160.075988601746;6183.690815459119;6204.193454850841;6209.627422354673;6014.0678194879865;6034.2155519782145;6039.969800150971;6048.3649216833355;6086.421789929949 09:10:02 INFO - PROCESS | 1967 | |1;ext.html;425.2982578745071;480.31555034076456;520.3911423634347;526.6655673647233;536.4179864846642;38180.61819381805;40733.59266407335;40762.59237407625;40772.79613601928;41081;36784.63215367845;40988.795056024755;41560.37659435111;41663;41816.79091604545;8400;8679.869801952977;9044.54777261136;9255.722328330126;9266.166045055943;11084.61203857865;11377.544898204062;11397.373144477051;11402.885971140266;11551.075913926865;9650.75873103171;11285.435728213597;11361.375124368156;11412.372319522423;11432;8417.242448179662;8617.181367770052;11156.832647510293;11358.60244891429;11412.828807567894;9631.951840240825;11214.102871770321;11219.551217951272;11258.718532036683;11310.038646715038;39719.60280397188;40056.59801906919;40093.59906400928;40121.79939100315;40192;3965.9603403966166;4004.5795191504762;4044.6562042226446;4091.1817636472824;4107.383892416132;26846;27558.448831023466;27611.03361382342;27840.582391264146;29223.561646575105;3192.058342788883;3223.7421006319437;3244.30247496787;3278.0821370016433;3313.188268874117;410179;412828;414000;415136;417178;57142.42857571371;57270.42729572734;57435.425645743846;57522;57640.71179644075;59582.70208648929;59639.701801490715;59658.70170649206;59787;59838;15722.606934826721;15793.21033948298;16003.919980400024;16005.919970400073;16062.036277823368;11264.098872090213;11573.76852462946;11656.592019279455;12342.75314493705;12371;17669.381571645186;18055.368062117763;18116.90941545284;18154.455366339032;18270.99509526989;6723.428508576826;7019.911913653392;7821.569813660303;7931.524406601117;8021.679132834622;855.4054931822324;903.814717982812;907.8138981508774;909.5679552212646;930.8882934047957;24430.511389772106;30426.39147217043;30667.07998760041;31336.529952050736;31344.686553134634;920.7799665443314;936.864154697565;1021.305512251676;1073.983890241647;1124.8706398764248;14523.564293071226;14544.41822327095;14677.339519721425;14868.405263789544;14878.925605371904;576089;582438;583771;585946;586346;51633.225501617504;51986.74006630018;52016.739916300176;52095.21857172145;52237.21644175341;1395.6231817409243;1415.9575212743634;1446.3925151436213;1448.7319845828556;1450.4343306110836;23569;25230.747692523575;26771.598426023236;27563.173104806887;28110.01614943549;43043.9239019027;46609.533904661876;46663;46664;46678.53321466878;432325;433364;435300;436391;437602;24674.13640522573;25033.87483062573;25686.357841053377;33121.83439082789;33933.830330848185;37029.629703702616;37467.87596372113;43232.35151472667;43663.781681091394;46979;35441.29117417689;35751.82124089467;36144.457833132;36154.8192259037;36615.90152295435;36490.087747806516;36814.63185368112;37255;37423.06442338855;37585.24829503449;35202.823985879906;35419.29161416706;35779.821100894325;35959.64040359668;36149;31075.53386699156;31721.5241771369;31845.044648660576;31936.26807809009;32103.518447223778;22324;24279.271621851374;24387.611635873724;24468.755312446647;24708.876455618327 09:10:02 INFO - PROCESS | 1967 | |2;jquery.html;330.618136052859;385.8013123241531;399.46539208720657;408.9959100408995;420.09469593027035;19465;21783.346499605;21820.236291729772;22114.44713882154;22136.893155342248;20485.38543843687;21163.365099047016;21413.571728565457;21957.890210548925;22477.775222247772;5065.924011139827;5250.737463126848;5307.2835167252315;5342.1452567589195;5364.168553874156;3926.1166237596453;5223;5314.707691076989;5320.281761962144;5321.281626980366;4134.586541345871;6005.3394126646035;6047.3650266722025;6102.11519329697;6103.05402662588;5896.262967129109;6004.18943442634;6009.09863520471;6070.484008859252;6108.114323423101;5888.322842873082;5904.0258357370985;5910.290765108171;5911.881762364728;5914;10685.41230232337;13374.732505349839;22955.96698148587;24598;25449.618255726178;2511.7990560755097;2532.480841427503;2628.1852625686097;2629.8290611110215;2681.8256813307266;10117.392956422638;10124.341917775395;10157.593696252141;10623.309484883457;10637.840432393521;1549.2331296008547;1790.9641807163914;1829.6432195721839;1837.8162183781649;1848.3992702371784;122287.38856305662;128387;132192.01711974328;136132.59668508268;136791;11904.880951190551;12013.939930300292;12018.579349722893;12056.276623402624;12215;15543.922280388526;15830.52508424749;15930.522084337488;15951.60120996961;15979.201039947957;8768.693095741619;8863.290936725047;8882.689105881263;8972.596233169523;8991.415557988841;7164.82087947795;8730.912690873138;8734;8744.825103497895;8795.956020219857;4672.08894265615;4796.8081276748535;4806.4712881583;4834.516548345137;4877.439094504178;1978.3174804692358;2013.3959812056326;2016.2237538547472;2021.8382529397613;2031.756189257269;805.7784109369853;814.1410811593707;913.8492148795374;922.8661844032578;1009.1321463541349;22036.559268814854;22321.55356892853;22398.10407583711;22459.989300481517;22534.887325563268;898.4564338575199;1036.6164519127972;1038.3925403638805;1058.1746237934424;1069.7272195590074;11384.316940983568;11425.257358271852;11566.942165289289;11583.189176757545;11747.118966077578;735672;737644;743344;748032;762917;124850;149280;152018;155047;157732.63401048994;947.1570302430843;994.7015895231547;999.8750156230471;1012.0891197921782;1024.1755386913605;18693.15880785395;19302.4209273722;19672.90163549173;19677.21291148338;19747.210111595374;59824.70087649534;60822.69588652028;62111;62294;62787.37212627998;85616;86986.56506717426;88342.11657883339;88632;91712;8068.11250762404;8150;8253.876191857007;8425.747227583182;8671.826563468569;12848.550300739429;12905.935470322589;13036.934815325863;13120.278384688934;13401.86598134006;10157.288989770645;10362.585496580052;11399.772004560029;11412.600558980395;11514.94242528782;10955.287906286067;11053;11067.446627668745;11371.48828302745;11529.884701153216;11401.543938242377;11416.885831141582;11420.82868756986;11591.6522504325;11799;6797.898031529432;7391.445641577011;7591.240875912365;7677;7823.569703666353;10134;10901.509432075418;11537.423128843691;11588.188826782045;11975 09:10:02 INFO - PROCESS | 1967 | |3;mootools.html;1323.9007074469416;1449.3767679897646;1471.021770522602;1474.1155306815917;1476.3946781819457;8071.233232842877;8383.91616083839;8423.789405264855;8439.156084391565;8440;7414.443916706242;7678.193789652089;7689.192634773352;8058.11360750317;8240.175982401757;2314.571804216221;2351.259353303704;2352.8941197646063;2353.211674089182;2361.9763802362013;2344.1795371619883;2361.197192954395;2379.1791831817995;2380.1788383007834;2381.1189859751917;2069.509952833957;2145.1741079684357;2258.9209377671787;2337.520808234308;2349.9765002350014;2327.0342807734855;2344.5779759643256;2345.5895218336723;2346.4368551547504;2348.2250857217077;2107.8722883257537;2194.418479103039;2347.2019513365367;2349.3774149850306;2384.6542251373453;8379.994400671898;8445.95777021111;8481.406301558893;8489.872651910226;8492.405531612789;5055.545000949932;5213.817516386946;5216.087184742692;5223.869403264911;5235.031519168928;3018.864151113183;3373.4602463605706;3519.8064106474135;3578.3380074686265;3605.837737301828;1174.2426135142784;1180.6753142885689;1196.216478206776;1214.3321173354707;1256.5036810459817;10378.221633377527;10993.395363255018;11014.614488492944;11019.008289254005;11043.613473528469;4421.29259318514;4514.751688657156;4529.071540334224;4582.908341833211;4620.1221767864;8609.397342186112;8613.61238744258;8633.481991080555;8751.956240218886;8810.823783524422;5161.38063432383;5169.870753231124;5170.8965820684125;5173.741312934339;5182.170852663556;4598.057398233354;4702.9764851176205;4736.810527578928;4843.515648435199;4860.8298709545;3657.6708096271464;3724.1248306647917;3752.549694036733;3786.469894214812;3797.2595343908224;1154.7401834587151;1158.3108050709982;1174.9823752643717;1177.6525924852017;1182.7575347053833;921.3044151665583;924.847400178963;934.3179478980246;951.8667386565886;1000.3897622450299;5524.530414914777;5621.718914054362;5662.745176467069;5687.146927960756;5726.455986681259;510.7956817273046;543.6574957776576;578.3378032153245;580.9360970293264;586.3901542395946;3714.331420344364;3747.512823332949;3759.191773768653;3881.4954055972544;3951.328274193394;9562.235021198287;9898.60405583783;9917.65288214909;10042.69871903844;10058.497075146213;9913.008699130029;9986.001399859955;10073.093421592092;10082.647107351208;10086.041826026516;6594.472442204612;7747.767566973;7752.341051010727;7862.528248305006;7875.212478752079;5353.54494867925;5518.448155184449;5625.493705566519;5633.183188437735;5725.456081672234;9058.230050445785;9075.364724469224;9081.273498120003;9214.585343659417;9290.58192381358;7477.700322314582;7994.760157195294;9390.154886060287;9527.857082143635;9540.284478664267;7215.60314182725;7290.088738907637;7325.340719335285;7391.186969433251;7406.185319614946;6785.762498312535;7807.492512986638;7899.447038707235;7984.640691169027;7997.880031799411;7214.2785721427435;7224.13310402734;7251.419886408969;7279.4540409468445;7425.9628701856145;7013.894791578028;7071.328223818729;7095.29047095307;7123.074000379917;7128.322809333105;6390.201224846895;6403.167588213689;6441.13044738973;6444.484441244783;6557.508186885907;5013.523715247045;5015.247712842956;5042.218456139304;5072.315237442898;5081.974590127025;4482.506924238396;4947.975260123676;4984.5575667923185;5135.691858488353;5280.1815718563685 09:10:02 INFO - PROCESS | 1967 | |4;prototype.html;353.8956007977647;360.6267513123917;364.6626870145115;369.3388833987163;372.2647770652959;5246.711430871305;5257.605679574028;5258;5260.316158899343;5302.734863256831;4371.803268852901;5031.371078615173;5156.613254005946;5218.7651555679995;5241.5020573045595;3176.5235214717864;3210.855511501988;3353.882614108507;3435.4110121131384;3796.126890815114;3687.8709245176424;4018.6985976051765;4039.5354534228645;4044.170944956277;4046.231216068952;3361.722545432732;3364.9495257571157;4027.758334499925;4042.7265411395315;4069.2065047315787;3380.7464440166964;3709.684676802475;3743.925121497582;4072.8981775455704;4084.7549147050977;3393.592768867727;3393.911992215516;3448.689617934398;3594.5327107476123;3604.5854726706502;4366.279563871957;4782.234842425195;5047.3438453001245;5068.873278168038;5088.338515992898;4241.597048280409;4635.930461043087;4639.698419602715;4642.860714178546;4686.7656617169005;4449.154660614473;4929.679570827885;5434.793471851626;5483.8080667176855;5502.312210973629;3355.597328320617;3660.0117968148716;3791.33651610967;3792.469054332396;3794.829232684536;4761.59526440253;5500.614956953015;5524.640898341595;5677.858053548653;5801.332846722638;3118.0645806258485;3780.1477276135047;3788.6590206881515;3798.6011468795377;3810.904727381838;3844.4041173618134;3865.8646947356706;3866.4288248314556;3876.4185372194397;3876.8643097491454;4322.956770432318;4450.863585478988;4459.7958551190995;4468.262736648481;4470.8435204767675;4069.8575549856205;5090.872728181751;5282.943411317752;5468.808591699271;5486.1770734389365;1097.8133717268083;1190.4404929683035;1208.1361826294242;1229.8968763414662;1245.9688507787196;803.6182813163817;805.1063319715112;813.4956327077132;849.2738708404225;857.6998050682212;4223.873283801491;4576.832907608605;4604.562566556172;4747.762611869462;4885.975570122127;4970.751462426936;5185.818496352609;5331.626786124934;5390.191471279261;5450.710701505377;4158.584141585818;4500.594946454835;4522.140793249272;4846.684965477232;4850.320955066293;2534.467761770012;2795.8741856616093;2807.5367564352064;2819.379736457976;2819.6193513875774;4870.905510630463;5042.470540593258;5304.2839216706025;5584.86037849057;5605.663660180402;5034.841986343088;5054.848354549369;5076;5217.139172036572;5249.658772179796;5829.766809327664;5857.736401861926;5865.120231965239;5868.501177399883;5917.201177840936;5455.8363249102595;5625.746841392229;5666.34836993751;5713.285839270091;5748.568857335633;5411.458854114714;5475.5072043516275;5502.862428439161;5587.860303492445;5592.384837667934;5151.150060240168;5525.364583072837;5659.801906933238;5866.624017273011;5878.706064696663;3553.307105114478;3670;3726.6646001859963;3756.9436458452597;3757.5345615210636;5171.268289515052;5645.745941432563;5671.943280567307;5679.318481782213;5699.9145012825675;4771.570558649738;5241.895162096813;5384.623076384615;5507.449255074461;5518.862028449321;4752.2158843790385;4789.9042019160115;5358.079846483598;5480.342358916955;5495.752691128989;4552.954470455253;5289.7355132244;5397.568194544348;5429.511343979061;5444.618876678753;5260.552853007384;5479.972600136974;5516.641418307797;5527.806526771544;5532.873926231666;4465.1962646723905;5328.907573947409;5403.675779453245;5425.294711687456;5427.968685949815 09:10:02 INFO - PROCESS | 1967 | |5;yui.html;718.4934621092127;718.8346680263543;767.3324207939094;820.2330820682662;843.4644001059329;14403.639909002268;15261.93166478347;15264.847351526481;15293;15305.005174663638;12074.215176013553;14059.718805623881;14093.859061409383;14240.860731141509;14254.429822807075;693.4972145194744;716.4805516000883;717.2146499582948;718.1848601836926;860.8937515292849;879.8548239540468;883.8409086364452;887.5074333744769;889.4618755652834;891.2424439226638;919.6551293265026;949.5299826585849;949.8195342884829;951.8001219743862;953.9523023848815;937.878075850142;939.1125386509751;940.0458534587399;940.7836197674574;944.6646440513588;535.6705626039997;553.8144721518296;554.4677109974389;558.6759679385947;707.9221285658573;12032.81950770739;14441.49454769078;14974.850251497563;14991.700165996619;15352.923235383863;14154.079984800956;14349.28253587327;14352.210628415432;14355.354009069513;14378.28108594566;11050.392228427434;11774.88225117755;11804.763904721944;11964.222325548812;12157.635270941886;3680.8159592020293;3685.5208822852856;3759.172981943968;3825.7704537727827;3909.4135879618;99247;101002.4949875246;101213;101910;102397;10918.781624367468;11108.16688748347;11149.944250278697;11153.330800152016;11218.831717524243;22410.215642452436;22706.772932270796;23002.19492317761;23006.309810705705;23022.65466018011;7823.29590336861;7924.405669574805;7945.841083178304;7961.283484486424;8032.919670803217;4265.87202383929;4336.82652693895;4348.586884245991;4372.6939114261695;4372.737635741865;3333.100062982981;3410.2838403935452;3434.4676575130893;3465.047112044208;3465.6361082086523;649.8635286589869;650.1515522243424;658.9011648252705;665.012456502094;665.1519312876121;678.2742465561802;694.2536772969079;710.4671496377716;729.6060127531074;749.5577609210592;8443.071262161156;10107.91366906472;10292.536835842255;10328.690139295833;10422.739431514121;696.7665831946355;703.9225685174627;719.5718547464251;725.989110163348;726.1757904778115;4640.234361330343;4925.270725813646;4984.27727979441;5002.52476014778;5012.774425150877;82825.58587207025;104938;105822.35444113959;106955.46522267339;106986.46506767572;86928;87441;88133.67799483013;88332.5583372079;93791.0620893796;66605.667886642;79127;80805.99999999882;82373.58813205895;83999.5800020996;9207.953960230156;9646.083622055894;9692.46691431978;9696.6121355145;9740.853887191555;39038.02404939899;52436.73781631068;53414.73292633512;53693.463065368844;55124.72437637947;69508.87210075458;70223;71243.93134103097;72313.55372892618;72608.63695681488;11503.367314797431;11749.76500470003;11937.880621193677;11996.520139194334;12006.879931200576;12577.30824804608;12683.87316126827;12812.743745125232;12986.805197921849;13370.799438008633;11872.091337622338;12019.098567607289;12136.939315303367;12566.434510446868;12703;11793.882061179278;11980;12393.690157746129;12631.684207894876;12722.173058751152;11595.710107247387;11955.462004209658;12071.939640301742;12163.81754273669;12494.500219991098;10034.69895903124;10212.131968782736;10797;11118.221724479208;11575.826362604736;10268.58925642966;10831.234256927215;11012;11017.338959662446;11028.172887033665 09:10:02 INFO - PROCESS | 1967 | __end_tp_report 09:10:02 INFO - PROCESS | 1967 | __start_cc_report 09:10:02 INFO - PROCESS | 1967 | _x_x_mozilla_cycle_collect,1620 09:10:02 INFO - PROCESS | 1967 | __end_cc_report 09:10:02 INFO - PROCESS | 1967 | __startTimestamp1486487402049__endTimestamp 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | ------- Summary: start ------- 09:10:02 INFO - PROCESS | 1967 | Number of tests: 6 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#0] dojo.html Cycles:165 Average:5775.09 Median:6026.37 stddev:647.96 (10.8%) stddev-sans-first:620.39 09:10:02 INFO - PROCESS | 1967 | Values: 3308.6 3322.3 3361.6 3363.4 3412.7 5737.7 5762.5 5774.3 5786.8 5822.9 5731.9 5809.0 5812.0 5819.9 5838.6 5205.9 5243.8 5249.9 5252.4 5301.3 5119.4 5125.0 5139.8 5312.3 5313.6 5213.8 5239.3 5240.6 5241.7 5243.8 5237.5 5241.8 5241.9 5247.0 5249.6 5096.9 5189.7 5193.5 5194.0 5248.4 4805.8 5800.7 5801.9 5808.5 5811.4 6171.6 6178.4 6206.4 6222.3 6237.8 6219.8 6231.7 6247.8 6249.5 6252.9 5880.9 5894.8 5896.2 5922.7 5949.7 6166.4 6202.0 6318.1 6331.6 6349.7 6134.5 6138.4 6141.3 6151.5 6223.5 6315.8 6319.3 6323.2 6330.9 6332.1 6294.7 6301.3 6305.7 6306.5 6311.3 6026.4 6131.7 6143.7 6173.7 6295.9 4911.2 4993.3 5000.2 5001.7 5041.6 4427.2 4434.1 4446.5 4457.7 4468.2 5663.4 5686.0 5814.4 5837.8 5877.9 6263.1 6265.6 6304.7 6326.3 6342.3 5752.3 5872.6 5896.6 5898.5 5899.4 5493.5 5497.9 5509.8 5516.9 5525.0 6260.6 6265.8 6272.6 6291.0 6295.9 5692.5 5929.5 6266.2 6275.7 6319.1 6235.1 6281.3 6281.4 6297.3 6362.3 6148.9 6166.5 6272.4 6276.0 6277.4 6163.8 6210.7 6233.7 6251.6 6348.8 6192.5 6205.0 6214.3 6214.5 6215.9 6015.5 6173.3 6183.0 6202.3 6209.2 4899.8 5877.3 5885.1 6040.2 6059.1 6113.7 6160.1 6183.7 6204.2 6209.6 6014.1 6034.2 6040.0 6048.4 6086.4 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#1] ext.html Cycles:180 Average:61605.14 Median:36799.63 stddev:128625.60 (349.5%) stddev-sans-first:128904.41 09:10:02 INFO - PROCESS | 1967 | Values: 425.3 480.3 520.4 526.7 536.4 38180.6 40733.6 40762.6 40772.8 41081.0 36784.6 40988.8 41560.4 41663.0 41816.8 8400.0 8679.9 9044.5 9255.7 9266.2 11084.6 11377.5 11397.4 11402.9 11551.1 9650.8 11285.4 11361.4 11412.4 11432.0 8417.2 8617.2 11156.8 11358.6 11412.8 9632.0 11214.1 11219.6 11258.7 11310.0 39719.6 40056.6 40093.6 40121.8 40192.0 3966.0 4004.6 4044.7 4091.2 4107.4 26846.0 27558.4 27611.0 27840.6 29223.6 3192.1 3223.7 3244.3 3278.1 3313.2 410179.0 412828.0 414000.0 415136.0 417178.0 57142.4 57270.4 57435.4 57522.0 57640.7 59582.7 59639.7 59658.7 59787.0 59838.0 15722.6 15793.2 16003.9 16005.9 16062.0 11264.1 11573.8 11656.6 12342.8 12371.0 17669.4 18055.4 18116.9 18154.5 18271.0 6723.4 7019.9 7821.6 7931.5 8021.7 855.4 903.8 907.8 909.6 930.9 24430.5 30426.4 30667.1 31336.5 31344.7 920.8 936.9 1021.3 1074.0 1124.9 14523.6 14544.4 14677.3 14868.4 14878.9 576089.0 582438.0 583771.0 585946.0 586346.0 51633.2 51986.7 52016.7 52095.2 52237.2 1395.6 1416.0 1446.4 1448.7 1450.4 23569.0 25230.7 26771.6 27563.2 28110.0 43043.9 46609.5 46663.0 46664.0 46678.5 432325.0 433364.0 435300.0 436391.0 437602.0 24674.1 25033.9 25686.4 33121.8 33933.8 37029.6 37467.9 43232.4 43663.8 46979.0 35441.3 35751.8 36144.5 36154.8 36615.9 36490.1 36814.6 37255.0 37423.1 37585.2 35202.8 35419.3 35779.8 35959.6 36149.0 31075.5 31721.5 31845.0 31936.3 32103.5 22324.0 24279.3 24387.6 24468.8 24708.9 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#2] jquery.html Cycles:180 Average:40601.22 Median:22429.05 stddev:124100.14 (553.3%) stddev-sans-first:124411.43 09:10:02 INFO - PROCESS | 1967 | Values: 330.6 385.8 399.5 409.0 420.1 19465.0 21783.3 21820.2 22114.4 22136.9 20485.4 21163.4 21413.6 21957.9 22477.8 5065.9 5250.7 5307.3 5342.1 5364.2 3926.1 5223.0 5314.7 5320.3 5321.3 4134.6 6005.3 6047.4 6102.1 6103.1 5896.3 6004.2 6009.1 6070.5 6108.1 5888.3 5904.0 5910.3 5911.9 5914.0 10685.4 13374.7 22956.0 24598.0 25449.6 2511.8 2532.5 2628.2 2629.8 2681.8 10117.4 10124.3 10157.6 10623.3 10637.8 1549.2 1791.0 1829.6 1837.8 1848.4 122287.4 128387.0 132192.0 136132.6 136791.0 11904.9 12013.9 12018.6 12056.3 12215.0 15543.9 15830.5 15930.5 15951.6 15979.2 8768.7 8863.3 8882.7 8972.6 8991.4 7164.8 8730.9 8734.0 8744.8 8796.0 4672.1 4796.8 4806.5 4834.5 4877.4 1978.3 2013.4 2016.2 2021.8 2031.8 805.8 814.1 913.8 922.9 1009.1 22036.6 22321.6 22398.1 22460.0 22534.9 898.5 1036.6 1038.4 1058.2 1069.7 11384.3 11425.3 11566.9 11583.2 11747.1 735672.0 737644.0 743344.0 748032.0 762917.0 124850.0 149280.0 152018.0 155047.0 157732.6 947.2 994.7 999.9 1012.1 1024.2 18693.2 19302.4 19672.9 19677.2 19747.2 59824.7 60822.7 62111.0 62294.0 62787.4 85616.0 86986.6 88342.1 88632.0 91712.0 8068.1 8150.0 8253.9 8425.7 8671.8 12848.6 12905.9 13036.9 13120.3 13401.9 10157.3 10362.6 11399.8 11412.6 11514.9 10955.3 11053.0 11067.4 11371.5 11529.9 11401.5 11416.9 11420.8 11591.7 11799.0 6797.9 7391.4 7591.2 7677.0 7823.6 10134.0 10901.5 11537.4 11588.2 11975.0 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#3] mootools.html Cycles:180 Average:5393.98 Median:5177.96 stddev:2931.70 (56.6%) stddev-sans-first:2923.96 09:10:02 INFO - PROCESS | 1967 | Values: 1323.9 1449.4 1471.0 1474.1 1476.4 8071.2 8383.9 8423.8 8439.2 8440.0 7414.4 7678.2 7689.2 8058.1 8240.2 2314.6 2351.3 2352.9 2353.2 2362.0 2344.2 2361.2 2379.2 2380.2 2381.1 2069.5 2145.2 2258.9 2337.5 2350.0 2327.0 2344.6 2345.6 2346.4 2348.2 2107.9 2194.4 2347.2 2349.4 2384.7 8380.0 8446.0 8481.4 8489.9 8492.4 5055.5 5213.8 5216.1 5223.9 5235.0 3018.9 3373.5 3519.8 3578.3 3605.8 1174.2 1180.7 1196.2 1214.3 1256.5 10378.2 10993.4 11014.6 11019.0 11043.6 4421.3 4514.8 4529.1 4582.9 4620.1 8609.4 8613.6 8633.5 8752.0 8810.8 5161.4 5169.9 5170.9 5173.7 5182.2 4598.1 4703.0 4736.8 4843.5 4860.8 3657.7 3724.1 3752.5 3786.5 3797.3 1154.7 1158.3 1175.0 1177.7 1182.8 921.3 924.8 934.3 951.9 1000.4 5524.5 5621.7 5662.7 5687.1 5726.5 510.8 543.7 578.3 580.9 586.4 3714.3 3747.5 3759.2 3881.5 3951.3 9562.2 9898.6 9917.7 10042.7 10058.5 9913.0 9986.0 10073.1 10082.6 10086.0 6594.5 7747.8 7752.3 7862.5 7875.2 5353.5 5518.4 5625.5 5633.2 5725.5 9058.2 9075.4 9081.3 9214.6 9290.6 7477.7 7994.8 9390.2 9527.9 9540.3 7215.6 7290.1 7325.3 7391.2 7406.2 6785.8 7807.5 7899.4 7984.6 7997.9 7214.3 7224.1 7251.4 7279.5 7426.0 7013.9 7071.3 7095.3 7123.1 7128.3 6390.2 6403.2 6441.1 6444.5 6557.5 5013.5 5015.2 5042.2 5072.3 5082.0 4482.5 4948.0 4984.6 5135.7 5280.2 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#4] prototype.html Cycles:180 Average:4381.16 Median:4878.44 stddev:1357.24 (27.8%) stddev-sans-first:1326.96 09:10:02 INFO - PROCESS | 1967 | Values: 353.9 360.6 364.7 369.3 372.3 5246.7 5257.6 5258.0 5260.3 5302.7 4371.8 5031.4 5156.6 5218.8 5241.5 3176.5 3210.9 3353.9 3435.4 3796.1 3687.9 4018.7 4039.5 4044.2 4046.2 3361.7 3364.9 4027.8 4042.7 4069.2 3380.7 3709.7 3743.9 4072.9 4084.8 3393.6 3393.9 3448.7 3594.5 3604.6 4366.3 4782.2 5047.3 5068.9 5088.3 4241.6 4635.9 4639.7 4642.9 4686.8 4449.2 4929.7 5434.8 5483.8 5502.3 3355.6 3660.0 3791.3 3792.5 3794.8 4761.6 5500.6 5524.6 5677.9 5801.3 3118.1 3780.1 3788.7 3798.6 3810.9 3844.4 3865.9 3866.4 3876.4 3876.9 4323.0 4450.9 4459.8 4468.3 4470.8 4069.9 5090.9 5282.9 5468.8 5486.2 1097.8 1190.4 1208.1 1229.9 1246.0 803.6 805.1 813.5 849.3 857.7 4223.9 4576.8 4604.6 4747.8 4886.0 4970.8 5185.8 5331.6 5390.2 5450.7 4158.6 4500.6 4522.1 4846.7 4850.3 2534.5 2795.9 2807.5 2819.4 2819.6 4870.9 5042.5 5304.3 5584.9 5605.7 5034.8 5054.8 5076.0 5217.1 5249.7 5829.8 5857.7 5865.1 5868.5 5917.2 5455.8 5625.7 5666.3 5713.3 5748.6 5411.5 5475.5 5502.9 5587.9 5592.4 5151.2 5525.4 5659.8 5866.6 5878.7 3553.3 3670.0 3726.7 3756.9 3757.5 5171.3 5645.7 5671.9 5679.3 5699.9 4771.6 5241.9 5384.6 5507.4 5518.9 4752.2 4789.9 5358.1 5480.3 5495.8 4553.0 5289.7 5397.6 5429.5 5444.6 5260.6 5480.0 5516.6 5527.8 5532.9 4465.2 5328.9 5403.7 5425.3 5428.0 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - PROCESS | 1967 | [#5] yui.html Cycles:180 Average:20252.29 Median:21431.90 stddev:29232.72 (136.4%) stddev-sans-first:29277.93 09:10:02 INFO - PROCESS | 1967 | Values: 718.5 718.8 767.3 820.2 843.5 14403.6 15261.9 15264.8 15293.0 15305.0 12074.2 14059.7 14093.9 14240.9 14254.4 693.5 716.5 717.2 718.2 860.9 879.9 883.8 887.5 889.5 891.2 919.7 949.5 949.8 951.8 954.0 937.9 939.1 940.0 940.8 944.7 535.7 553.8 554.5 558.7 707.9 12032.8 14441.5 14974.9 14991.7 15352.9 14154.1 14349.3 14352.2 14355.4 14378.3 11050.4 11774.9 11804.8 11964.2 12157.6 3680.8 3685.5 3759.2 3825.8 3909.4 99247.0 101002.5 101213.0 101910.0 102397.0 10918.8 11108.2 11149.9 11153.3 11218.8 22410.2 22706.8 23002.2 23006.3 23022.7 7823.3 7924.4 7945.8 7961.3 8032.9 4265.9 4336.8 4348.6 4372.7 4372.7 3333.1 3410.3 3434.5 3465.0 3465.6 649.9 650.2 658.9 665.0 665.2 678.3 694.3 710.5 729.6 749.6 8443.1 10107.9 10292.5 10328.7 10422.7 696.8 703.9 719.6 726.0 726.2 4640.2 4925.3 4984.3 5002.5 5012.8 82825.6 104938.0 105822.4 106955.5 106986.5 86928.0 87441.0 88133.7 88332.6 93791.1 66605.7 79127.0 80806.0 82373.6 83999.6 9208.0 9646.1 9692.5 9696.6 9740.9 39038.0 52436.7 53414.7 53693.5 55124.7 69508.9 70223.0 71243.9 72313.6 72608.6 11503.4 11749.8 11937.9 11996.5 12006.9 12577.3 12683.9 12812.7 12986.8 13370.8 11872.1 12019.1 12136.9 12566.4 12703.0 11793.9 11980.0 12393.7 12631.7 12722.2 11595.7 11955.5 12071.9 12163.8 12494.5 10034.7 10212.1 10797.0 11118.2 11575.8 10268.6 10831.2 11012.0 11017.3 11028.2 09:10:02 INFO - PROCESS | 1967 | -------- Summary: end -------- 09:10:02 INFO - PROCESS | 1967 | 09:10:02 INFO - TEST-INFO | 1967: exit 0 09:10:02 INFO - TEST-OK | dromaeo_css | took 1118007ms 09:10:02 INFO - TEST-START | kraken 09:10:02 INFO - operating with platform_type : mac_ 09:10:02 INFO - Initialising browser for kraken test... 09:10:02 INFO - TEST-INFO | started process 1970 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpxOTrXX/profile http://localhost:49344/getInfo.html) 09:10:05 INFO - PROCESS | 1970 | __metrics Screen width/height:1600/1200 09:10:05 INFO - PROCESS | 1970 | colorDepth:24 09:10:05 INFO - PROCESS | 1970 | Browser inner width/height: 1024/689 09:10:05 INFO - PROCESS | 1970 | __metrics 09:10:05 INFO - PROCESS | 1970 | 1486487405106 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 09:10:05 INFO - PROCESS | 1970 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 09:10:05 INFO - PROCESS | 1970 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:10:05 INFO - TEST-INFO | 1970: exit 0 09:10:05 INFO - Browser initialized. 09:10:05 INFO - Running cycle 1/1 for kraken test... 09:10:05 INFO - TEST-INFO | started process 1971 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/T/tmpxOTrXX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:10:18 INFO - PROCESS | 1971 | RSS: Main: 213295104 09:10:18 INFO - PROCESS | 1971 | 09:10:20 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-beat-detection) 09:10:21 INFO - PROCESS | 1971 | RSS: Main: 265826304 09:10:21 INFO - PROCESS | 1971 | 09:10:24 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-dft) 09:10:24 INFO - PROCESS | 1971 | RSS: Main: 274849792 09:10:24 INFO - PROCESS | 1971 | 09:10:27 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-fft) 09:10:28 INFO - PROCESS | 1971 | RSS: Main: 371011584 09:10:28 INFO - PROCESS | 1971 | 09:10:30 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49344/tests/kraken/driver.html?testName=audio-oscillator) 09:10:31 INFO - PROCESS | 1971 | RSS: Main: 274694144 09:10:31 INFO - PROCESS | 1971 | 09:10:33 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:10:33 INFO - PROCESS | 1971 | RSS: Main: 275959808 09:10:33 INFO - PROCESS | 1971 | 09:10:36 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-darkroom) 09:10:37 INFO - PROCESS | 1971 | RSS: Main: 316567552 09:10:37 INFO - PROCESS | 1971 | 09:10:40 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49344/tests/kraken/driver.html?testName=imaging-desaturate) 09:10:41 INFO - PROCESS | 1971 | RSS: Main: 316829696 09:10:41 INFO - PROCESS | 1971 | 09:10:43 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49344/tests/kraken/driver.html?testName=json-parse-financial) 09:10:44 INFO - PROCESS | 1971 | RSS: Main: 313745408 09:10:44 INFO - PROCESS | 1971 | 09:10:46 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49344/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:10:46 INFO - PROCESS | 1971 | RSS: Main: 294989824 09:10:46 INFO - PROCESS | 1971 | 09:10:48 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:10:49 INFO - PROCESS | 1971 | RSS: Main: 298106880 09:10:49 INFO - PROCESS | 1971 | 09:10:51 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:10:52 INFO - PROCESS | 1971 | RSS: Main: 300961792 09:10:52 INFO - PROCESS | 1971 | 09:10:55 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:10:55 INFO - PROCESS | 1971 | RSS: Main: 317292544 09:10:55 INFO - PROCESS | 1971 | 09:10:58 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:10:59 INFO - PROCESS | 1971 | RSS: Main: 311652352 09:10:59 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | Cycle 1(1): loaded http://localhost:49344/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49344/tests/kraken/driver.html?testName=ai-astar) 09:11:01 INFO - PROCESS | 1971 | RSS: Main: 356327424 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | __start_tp_report 09:11:01 INFO - PROCESS | 1971 | _x_x_mozilla_page_load 09:11:01 INFO - PROCESS | 1971 | _x_x_mozilla_page_load_details 09:11:01 INFO - PROCESS | 1971 | |i|pagename|runs| 09:11:01 INFO - PROCESS | 1971 | |0;ai-astar;114;113;114;112;112;112;113;112;114;113 09:11:01 INFO - PROCESS | 1971 | |1;audio-beat-detection;145;113;109;145;96;115;136;142;127;110 09:11:01 INFO - PROCESS | 1971 | |2;audio-dft;184;184;182;182;184;183;184;183;182;182 09:11:01 INFO - PROCESS | 1971 | |3;audio-fft;83;58;58;90;59;58;58;58;72;57 09:11:01 INFO - PROCESS | 1971 | |4;audio-oscillator;86;92;72;92;90;100;115;97;70;83 09:11:01 INFO - PROCESS | 1971 | |5;imaging-gaussian-blur;89;87;88;86;89;85;89;86;89;86 09:11:01 INFO - PROCESS | 1971 | |6;imaging-darkroom;132;133;94;93;92;93;94;93;94;94 09:11:01 INFO - PROCESS | 1971 | |7;imaging-desaturate;86;88;87;84;87;84;85;85;84;87 09:11:01 INFO - PROCESS | 1971 | |8;json-parse-financial;57;55;55;55;53;55;78;84;84;88 09:11:01 INFO - PROCESS | 1971 | |9;json-stringify-tinderbox;72;70;68;76;72;69;71;71;71;71 09:11:01 INFO - PROCESS | 1971 | |10;stanford-crypto-aes;83;83;85;86;83;83;83;83;85;85 09:11:01 INFO - PROCESS | 1971 | |11;stanford-crypto-ccm;143;140;146;144;138;140;133;145;202;165 09:11:01 INFO - PROCESS | 1971 | |12;stanford-crypto-pbkdf2;168;194;168;168;220;217;168;168;169;169 09:11:01 INFO - PROCESS | 1971 | |13;stanford-crypto-sha256-iterative;66;67;68;66;66;65;64;66;66;64 09:11:01 INFO - PROCESS | 1971 | __end_tp_report 09:11:01 INFO - PROCESS | 1971 | __start_cc_report 09:11:01 INFO - PROCESS | 1971 | _x_x_mozilla_cycle_collect,2002 09:11:01 INFO - PROCESS | 1971 | __end_cc_report 09:11:01 INFO - PROCESS | 1971 | __startTimestamp1486487461419__endTimestamp 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | ------- Summary: start ------- 09:11:01 INFO - PROCESS | 1971 | Number of tests: 14 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#0] ai-astar Cycles:10 Average:112.90 Median:113.00 stddev:0.88 (0.8%) stddev-sans-first:0.83 09:11:01 INFO - PROCESS | 1971 | Values: 114.0 113.0 114.0 112.0 112.0 112.0 113.0 112.0 114.0 113.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#1] audio-beat-detection Cycles:10 Average:123.80 Median:139.00 stddev:17.53 (12.6%) stddev-sans-first:16.83 09:11:01 INFO - PROCESS | 1971 | Values: 145.0 113.0 109.0 145.0 96.0 115.0 136.0 142.0 127.0 110.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#2] audio-dft Cycles:10 Average:183.00 Median:183.50 stddev:0.94 (0.5%) stddev-sans-first:0.93 09:11:01 INFO - PROCESS | 1971 | Values: 184.0 184.0 182.0 182.0 184.0 183.0 184.0 183.0 182.0 182.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#3] audio-fft Cycles:10 Average:65.10 Median:58.50 stddev:12.22 (20.9%) stddev-sans-first:11.11 09:11:01 INFO - PROCESS | 1971 | Values: 83.0 58.0 58.0 90.0 59.0 58.0 58.0 58.0 72.0 57.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#4] audio-oscillator Cycles:10 Average:89.70 Median:88.00 stddev:13.21 (15.0%) stddev-sans-first:13.94 09:11:01 INFO - PROCESS | 1971 | Values: 86.0 92.0 72.0 92.0 90.0 100.0 115.0 97.0 70.0 83.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#5] imaging-gaussian-blur Cycles:10 Average:87.40 Median:88.50 stddev:1.58 (1.8%) stddev-sans-first:1.56 09:11:01 INFO - PROCESS | 1971 | Values: 89.0 87.0 88.0 86.0 89.0 85.0 89.0 86.0 89.0 86.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#6] imaging-darkroom Cycles:10 Average:101.20 Median:93.50 stddev:16.51 (17.7%) stddev-sans-first:13.23 09:11:01 INFO - PROCESS | 1971 | Values: 132.0 133.0 94.0 93.0 92.0 93.0 94.0 93.0 94.0 94.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#7] imaging-desaturate Cycles:10 Average:85.70 Median:86.50 stddev:1.49 (1.7%) stddev-sans-first:1.58 09:11:01 INFO - PROCESS | 1971 | Values: 86.0 88.0 87.0 84.0 87.0 84.0 85.0 85.0 84.0 87.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#8] json-parse-financial Cycles:10 Average:66.40 Median:67.50 stddev:14.94 (22.1%) stddev-sans-first:15.45 09:11:01 INFO - PROCESS | 1971 | Values: 57.0 55.0 55.0 55.0 53.0 55.0 78.0 84.0 84.0 88.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#9] json-stringify-tinderbox Cycles:10 Average:71.10 Median:71.00 stddev:2.13 (3.0%) stddev-sans-first:2.24 09:11:01 INFO - PROCESS | 1971 | Values: 72.0 70.0 68.0 76.0 72.0 69.0 71.0 71.0 71.0 71.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#10] stanford-crypto-aes Cycles:10 Average:83.90 Median:84.00 stddev:1.20 (1.4%) stddev-sans-first:1.22 09:11:01 INFO - PROCESS | 1971 | Values: 83.0 83.0 85.0 86.0 83.0 83.0 83.0 83.0 85.0 85.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#11] stanford-crypto-ccm Cycles:10 Average:149.60 Median:144.50 stddev:20.24 (14.0%) stddev-sans-first:21.32 09:11:01 INFO - PROCESS | 1971 | Values: 143.0 140.0 146.0 144.0 138.0 140.0 133.0 145.0 202.0 165.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:180.90 Median:169.00 stddev:21.39 (12.7%) stddev-sans-first:22.18 09:11:01 INFO - PROCESS | 1971 | Values: 168.0 194.0 168.0 168.0 220.0 217.0 168.0 168.0 169.0 169.0 09:11:01 INFO - PROCESS | 1971 | 09:11:01 INFO - PROCESS | 1971 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:65.80 Median:66.00 stddev:1.23 (1.9%) stddev-sans-first:1.30 09:11:01 INFO - PROCESS | 1971 | Values: 66.0 67.0 68.0 66.0 66.0 65.0 64.0 66.0 66.0 64.0 09:11:01 INFO - PROCESS | 1971 | -------- Summary: end -------- 09:11:01 INFO - PROCESS | 1971 | 09:11:02 INFO - TEST-INFO | 1971: exit 0 09:11:02 INFO - TEST-OK | kraken | took 59637ms 09:11:02 INFO - SUITE-END | took 1177s 09:11:02 INFO - Completed test suite (00:19:37) 09:11:02 INFO - javascript benchmark 09:11:02 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.636050034496, 3322.2691007978237, 3361.5629968104145, 3363.3945889739853, 3412.726981841453, 5737.741801618927, 5762.510186634141, 5774.307083150028, 5786.768529258829, 5822.883542329151, 5731.856703582413, 5809.04150815115, 5812.041013232812, 5819.9418005819925, 5838.649681019142, 5205.947940520594, 5243.842684719445, 5249.947500525003, 5252.395974462928, 5301.284326615915, 5119.436861945183, 5124.975004999015, 5139.845804625848, 5312.2828418163635, 5313.628046036779, 5213.791448342062, 5239.318888544484, 5240.60695447841, 5241.685498870061, 5243.842684719464, 5237.502437268454, 5241.8165364212455, 5241.9213711794355, 5247.0, 5249.553787928012, 5096.923546146811, 5189.688618682853, 5193.480651934814, 5194.0, 5248.448912864133, 4805.75971201442, 5800.738966746507, 5801.854953626151, 5808.506276966463, 5811.418858114197, 6171.6297022178815, 6178.38216178383, 6206.410391012847, 6222.253329600431, 6237.7816776413065, 6219.844503887393, 6231.688415579249, 6247.812565623039, 6249.500039996835, 6252.906206406908, 5880.852978675567, 5894.764209431618, 5896.174535565025, 5922.733476993546, 5949.702514874239, 6166.352532984062, 6202.0, 6318.052292156168, 6331.556791024676, 6349.682515874188, 6134.4978165938865, 6138.449706860586, 6141.263048434127, 6151.5386346024225, 6223.533235007392, 6315.810525684236, 6319.304876463585, 6323.20959880015, 6330.905036424458, 6332.0818481320875, 6294.716737746812, 6301.338359472281, 6305.684715764193, 6306.49548036156, 6311.337309582428, 6026.367231440723, 6131.682521312785, 6143.723532440961, 6173.660448675366, 6295.874082518324, 4911.238757992517, 4993.27597498368, 5000.199968005102, 5001.7499125043605, 5041.621878359138, 4427.180971520214, 4434.11317736454, 4446.5331140230455, 4457.732536047847, 4468.151051300308, 5663.433656634386, 5686.0333743263745, 5814.447627475383, 5837.795677151279, 5877.882442351214, 6263.091851681481, 6265.561410701298, 6304.747810087637, 6326.335734747877, 6342.270638876496, 5752.3097228332035, 5872.559558033079, 5896.616719913192, 5898.498627616615, 5899.380565040695, 5493.478119578714, 5497.945020549823, 5509.779608815603, 5516.889662206733, 5525.0, 6260.62436253808, 6265.843353916189, 6272.560920735505, 6290.961991271389, 6295.905561416491, 5692.544596432358, 5929.466348028698, 6266.154069200599, 6275.748970041147, 6319.052142178526, 6235.127082208403, 6281.277653069773, 6281.371862813865, 6297.338779428277, 6362.300146983735, 6148.907766383419, 6166.475849552839, 6272.40412160844, 6276.0, 6277.403646653561, 6163.7842675506145, 6210.720517576629, 6233.657148856856, 6251.562390632612, 6348.809535713936, 6192.504599632109, 6205.03821907605, 6214.25428948529, 6214.471769899428, 6215.875682486416, 6015.488683461955, 6173.259208894961, 6182.969085154546, 6202.317745048132, 6209.192804935329, 4899.779509922133, 5877.265341832271, 5885.117232415172, 6040.244969378828, 6059.0608455689435, 6113.749968914014, 6160.075988601746, 6183.690815459119, 6204.193454850841, 6209.627422354673, 6014.0678194879865, 6034.2155519782145, 6039.969800150971, 6048.3649216833355, 6086.421789929949], "value": 5733.145390530421, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [425.2982578745071, 480.31555034076456, 520.3911423634347, 526.6655673647233, 536.4179864846642, 38180.61819381805, 40733.59266407335, 40762.59237407625, 40772.79613601928, 41081.0, 36784.63215367845, 40988.795056024755, 41560.37659435111, 41663.0, 41816.79091604545, 8400.0, 8679.869801952977, 9044.54777261136, 9255.722328330126, 9266.166045055943, 11084.61203857865, 11377.544898204062, 11397.373144477051, 11402.885971140266, 11551.075913926865, 9650.75873103171, 11285.435728213597, 11361.375124368156, 11412.372319522423, 11432.0, 8417.242448179662, 8617.181367770052, 11156.832647510293, 11358.60244891429, 11412.828807567894, 9631.951840240825, 11214.102871770321, 11219.551217951272, 11258.718532036683, 11310.038646715038, 39719.60280397188, 40056.59801906919, 40093.59906400928, 40121.79939100315, 40192.0, 3965.9603403966166, 4004.5795191504762, 4044.6562042226446, 4091.1817636472824, 4107.383892416132, 26846.0, 27558.448831023466, 27611.03361382342, 27840.582391264146, 29223.561646575105, 3192.058342788883, 3223.7421006319437, 3244.30247496787, 3278.0821370016433, 3313.188268874117, 410179.0, 412828.0, 414000.0, 415136.0, 417178.0, 57142.42857571371, 57270.42729572734, 57435.425645743846, 57522.0, 57640.71179644075, 59582.70208648929, 59639.701801490715, 59658.70170649206, 59787.0, 59838.0, 15722.606934826721, 15793.21033948298, 16003.919980400024, 16005.919970400073, 16062.036277823368, 11264.098872090213, 11573.76852462946, 11656.592019279455, 12342.75314493705, 12371.0, 17669.381571645186, 18055.368062117763, 18116.90941545284, 18154.455366339032, 18270.99509526989, 6723.428508576826, 7019.911913653392, 7821.569813660303, 7931.524406601117, 8021.679132834622, 855.4054931822324, 903.814717982812, 907.8138981508774, 909.5679552212646, 930.8882934047957, 24430.511389772106, 30426.39147217043, 30667.07998760041, 31336.529952050736, 31344.686553134634, 920.7799665443314, 936.864154697565, 1021.305512251676, 1073.983890241647, 1124.8706398764248, 14523.564293071226, 14544.41822327095, 14677.339519721425, 14868.405263789544, 14878.925605371904, 576089.0, 582438.0, 583771.0, 585946.0, 586346.0, 51633.225501617504, 51986.74006630018, 52016.739916300176, 52095.21857172145, 52237.21644175341, 1395.6231817409243, 1415.9575212743634, 1446.3925151436213, 1448.7319845828556, 1450.4343306110836, 23569.0, 25230.747692523575, 26771.598426023236, 27563.173104806887, 28110.01614943549, 43043.9239019027, 46609.533904661876, 46663.0, 46664.0, 46678.53321466878, 432325.0, 433364.0, 435300.0, 436391.0, 437602.0, 24674.13640522573, 25033.87483062573, 25686.357841053377, 33121.83439082789, 33933.830330848185, 37029.629703702616, 37467.87596372113, 43232.35151472667, 43663.781681091394, 46979.0, 35441.29117417689, 35751.82124089467, 36144.457833132, 36154.8192259037, 36615.90152295435, 36490.087747806516, 36814.63185368112, 37255.0, 37423.06442338855, 37585.24829503449, 35202.823985879906, 35419.29161416706, 35779.821100894325, 35959.64040359668, 36149.0, 31075.53386699156, 31721.5241771369, 31845.044648660576, 31936.26807809009, 32103.518447223778, 22324.0, 24279.271621851374, 24387.611635873724, 24468.755312446647, 24708.876455618327], "value": 19356.927313581225, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [330.618136052859, 385.8013123241531, 399.46539208720657, 408.9959100408995, 420.09469593027035, 19465.0, 21783.346499605, 21820.236291729772, 22114.44713882154, 22136.893155342248, 20485.38543843687, 21163.365099047016, 21413.571728565457, 21957.890210548925, 22477.775222247772, 5065.924011139827, 5250.737463126848, 5307.2835167252315, 5342.1452567589195, 5364.168553874156, 3926.1166237596453, 5223.0, 5314.707691076989, 5320.281761962144, 5321.281626980366, 4134.586541345871, 6005.3394126646035, 6047.3650266722025, 6102.11519329697, 6103.05402662588, 5896.262967129109, 6004.18943442634, 6009.09863520471, 6070.484008859252, 6108.114323423101, 5888.322842873082, 5904.0258357370985, 5910.290765108171, 5911.881762364728, 5914.0, 10685.41230232337, 13374.732505349839, 22955.96698148587, 24598.0, 25449.618255726178, 2511.7990560755097, 2532.480841427503, 2628.1852625686097, 2629.8290611110215, 2681.8256813307266, 10117.392956422638, 10124.341917775395, 10157.593696252141, 10623.309484883457, 10637.840432393521, 1549.2331296008547, 1790.9641807163914, 1829.6432195721839, 1837.8162183781649, 1848.3992702371784, 122287.38856305662, 128387.0, 132192.01711974328, 136132.59668508268, 136791.0, 11904.880951190551, 12013.939930300292, 12018.579349722893, 12056.276623402624, 12215.0, 15543.922280388526, 15830.52508424749, 15930.522084337488, 15951.60120996961, 15979.201039947957, 8768.693095741619, 8863.290936725047, 8882.689105881263, 8972.596233169523, 8991.415557988841, 7164.82087947795, 8730.912690873138, 8734.0, 8744.825103497895, 8795.956020219857, 4672.08894265615, 4796.8081276748535, 4806.4712881583, 4834.516548345137, 4877.439094504178, 1978.3174804692358, 2013.3959812056326, 2016.2237538547472, 2021.8382529397613, 2031.756189257269, 805.7784109369853, 814.1410811593707, 913.8492148795374, 922.8661844032578, 1009.1321463541349, 22036.559268814854, 22321.55356892853, 22398.10407583711, 22459.989300481517, 22534.887325563268, 898.4564338575199, 1036.6164519127972, 1038.3925403638805, 1058.1746237934424, 1069.7272195590074, 11384.316940983568, 11425.257358271852, 11566.942165289289, 11583.189176757545, 11747.118966077578, 735672.0, 737644.0, 743344.0, 748032.0, 762917.0, 124850.0, 149280.0, 152018.0, 155047.0, 157732.63401048994, 947.1570302430843, 994.7015895231547, 999.8750156230471, 1012.0891197921782, 1024.1755386913605, 18693.15880785395, 19302.4209273722, 19672.90163549173, 19677.21291148338, 19747.210111595374, 59824.70087649534, 60822.69588652028, 62111.0, 62294.0, 62787.37212627998, 85616.0, 86986.56506717426, 88342.11657883339, 88632.0, 91712.0, 8068.11250762404, 8150.0, 8253.876191857007, 8425.747227583182, 8671.826563468569, 12848.550300739429, 12905.935470322589, 13036.934815325863, 13120.278384688934, 13401.86598134006, 10157.288989770645, 10362.585496580052, 11399.772004560029, 11412.600558980395, 11514.94242528782, 10955.287906286067, 11053.0, 11067.446627668745, 11371.48828302745, 11529.884701153216, 11401.543938242377, 11416.885831141582, 11420.82868756986, 11591.6522504325, 11799.0, 6797.898031529432, 7391.445641577011, 7591.240875912365, 7677.0, 7823.569703666353, 10134.0, 10901.509432075418, 11537.423128843691, 11588.188826782045, 11975.0], "value": 9842.322413311755, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1323.9007074469416, 1449.3767679897646, 1471.021770522602, 1474.1155306815917, 1476.3946781819457, 8071.233232842877, 8383.91616083839, 8423.789405264855, 8439.156084391565, 8440.0, 7414.443916706242, 7678.193789652089, 7689.192634773352, 8058.11360750317, 8240.175982401757, 2314.571804216221, 2351.259353303704, 2352.8941197646063, 2353.211674089182, 2361.9763802362013, 2344.1795371619883, 2361.197192954395, 2379.1791831817995, 2380.1788383007834, 2381.1189859751917, 2069.509952833957, 2145.1741079684357, 2258.9209377671787, 2337.520808234308, 2349.9765002350014, 2327.0342807734855, 2344.5779759643256, 2345.5895218336723, 2346.4368551547504, 2348.2250857217077, 2107.8722883257537, 2194.418479103039, 2347.2019513365367, 2349.3774149850306, 2384.6542251373453, 8379.994400671898, 8445.95777021111, 8481.406301558893, 8489.872651910226, 8492.405531612789, 5055.545000949932, 5213.817516386946, 5216.087184742692, 5223.869403264911, 5235.031519168928, 3018.864151113183, 3373.4602463605706, 3519.8064106474135, 3578.3380074686265, 3605.837737301828, 1174.2426135142784, 1180.6753142885689, 1196.216478206776, 1214.3321173354707, 1256.5036810459817, 10378.221633377527, 10993.395363255018, 11014.614488492944, 11019.008289254005, 11043.613473528469, 4421.29259318514, 4514.751688657156, 4529.071540334224, 4582.908341833211, 4620.1221767864, 8609.397342186112, 8613.61238744258, 8633.481991080555, 8751.956240218886, 8810.823783524422, 5161.38063432383, 5169.870753231124, 5170.8965820684125, 5173.741312934339, 5182.170852663556, 4598.057398233354, 4702.9764851176205, 4736.810527578928, 4843.515648435199, 4860.8298709545, 3657.6708096271464, 3724.1248306647917, 3752.549694036733, 3786.469894214812, 3797.2595343908224, 1154.7401834587151, 1158.3108050709982, 1174.9823752643717, 1177.6525924852017, 1182.7575347053833, 921.3044151665583, 924.847400178963, 934.3179478980246, 951.8667386565886, 1000.3897622450299, 5524.530414914777, 5621.718914054362, 5662.745176467069, 5687.146927960756, 5726.455986681259, 510.7956817273046, 543.6574957776576, 578.3378032153245, 580.9360970293264, 586.3901542395946, 3714.331420344364, 3747.512823332949, 3759.191773768653, 3881.4954055972544, 3951.328274193394, 9562.235021198287, 9898.60405583783, 9917.65288214909, 10042.69871903844, 10058.497075146213, 9913.008699130029, 9986.001399859955, 10073.093421592092, 10082.647107351208, 10086.041826026516, 6594.472442204612, 7747.767566973, 7752.341051010727, 7862.528248305006, 7875.212478752079, 5353.54494867925, 5518.448155184449, 5625.493705566519, 5633.183188437735, 5725.456081672234, 9058.230050445785, 9075.364724469224, 9081.273498120003, 9214.585343659417, 9290.58192381358, 7477.700322314582, 7994.760157195294, 9390.154886060287, 9527.857082143635, 9540.284478664267, 7215.60314182725, 7290.088738907637, 7325.340719335285, 7391.186969433251, 7406.185319614946, 6785.762498312535, 7807.492512986638, 7899.447038707235, 7984.640691169027, 7997.880031799411, 7214.2785721427435, 7224.13310402734, 7251.419886408969, 7279.4540409468445, 7425.9628701856145, 7013.894791578028, 7071.328223818729, 7095.29047095307, 7123.074000379917, 7128.322809333105, 6390.201224846895, 6403.167588213689, 6441.13044738973, 6444.484441244783, 6557.508186885907, 5013.523715247045, 5015.247712842956, 5042.218456139304, 5072.315237442898, 5081.974590127025, 4482.506924238396, 4947.975260123676, 4984.5575667923185, 5135.691858488353, 5280.1815718563685], "value": 4345.947301384405, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.8956007977647, 360.6267513123917, 364.6626870145115, 369.3388833987163, 372.2647770652959, 5246.711430871305, 5257.605679574028, 5258.0, 5260.316158899343, 5302.734863256831, 4371.803268852901, 5031.371078615173, 5156.613254005946, 5218.7651555679995, 5241.5020573045595, 3176.5235214717864, 3210.855511501988, 3353.882614108507, 3435.4110121131384, 3796.126890815114, 3687.8709245176424, 4018.6985976051765, 4039.5354534228645, 4044.170944956277, 4046.231216068952, 3361.722545432732, 3364.9495257571157, 4027.758334499925, 4042.7265411395315, 4069.2065047315787, 3380.7464440166964, 3709.684676802475, 3743.925121497582, 4072.8981775455704, 4084.7549147050977, 3393.592768867727, 3393.911992215516, 3448.689617934398, 3594.5327107476123, 3604.5854726706502, 4366.279563871957, 4782.234842425195, 5047.3438453001245, 5068.873278168038, 5088.338515992898, 4241.597048280409, 4635.930461043087, 4639.698419602715, 4642.860714178546, 4686.7656617169005, 4449.154660614473, 4929.679570827885, 5434.793471851626, 5483.8080667176855, 5502.312210973629, 3355.597328320617, 3660.0117968148716, 3791.33651610967, 3792.469054332396, 3794.829232684536, 4761.59526440253, 5500.614956953015, 5524.640898341595, 5677.858053548653, 5801.332846722638, 3118.0645806258485, 3780.1477276135047, 3788.6590206881515, 3798.6011468795377, 3810.904727381838, 3844.4041173618134, 3865.8646947356706, 3866.4288248314556, 3876.4185372194397, 3876.8643097491454, 4322.956770432318, 4450.863585478988, 4459.7958551190995, 4468.262736648481, 4470.8435204767675, 4069.8575549856205, 5090.872728181751, 5282.943411317752, 5468.808591699271, 5486.1770734389365, 1097.8133717268083, 1190.4404929683035, 1208.1361826294242, 1229.8968763414662, 1245.9688507787196, 803.6182813163817, 805.1063319715112, 813.4956327077132, 849.2738708404225, 857.6998050682212, 4223.873283801491, 4576.832907608605, 4604.562566556172, 4747.762611869462, 4885.975570122127, 4970.751462426936, 5185.818496352609, 5331.626786124934, 5390.191471279261, 5450.710701505377, 4158.584141585818, 4500.594946454835, 4522.140793249272, 4846.684965477232, 4850.320955066293, 2534.467761770012, 2795.8741856616093, 2807.5367564352064, 2819.379736457976, 2819.6193513875774, 4870.905510630463, 5042.470540593258, 5304.2839216706025, 5584.86037849057, 5605.663660180402, 5034.841986343088, 5054.848354549369, 5076.0, 5217.139172036572, 5249.658772179796, 5829.766809327664, 5857.736401861926, 5865.120231965239, 5868.501177399883, 5917.201177840936, 5455.8363249102595, 5625.746841392229, 5666.34836993751, 5713.285839270091, 5748.568857335633, 5411.458854114714, 5475.5072043516275, 5502.862428439161, 5587.860303492445, 5592.384837667934, 5151.150060240168, 5525.364583072837, 5659.801906933238, 5866.624017273011, 5878.706064696663, 3553.307105114478, 3670.0, 3726.6646001859963, 3756.9436458452597, 3757.5345615210636, 5171.268289515052, 5645.745941432563, 5671.943280567307, 5679.318481782213, 5699.9145012825675, 4771.570558649738, 5241.895162096813, 5384.623076384615, 5507.449255074461, 5518.862028449321, 4752.2158843790385, 4789.9042019160115, 5358.079846483598, 5480.342358916955, 5495.752691128989, 4552.954470455253, 5289.7355132244, 5397.568194544348, 5429.511343979061, 5444.618876678753, 5260.552853007384, 5479.972600136974, 5516.641418307797, 5527.806526771544, 5532.873926231666, 4465.1962646723905, 5328.907573947409, 5403.675779453245, 5425.294711687456, 5427.968685949815], "value": 3961.4950746451946, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.4934621092127, 718.8346680263543, 767.3324207939094, 820.2330820682662, 843.4644001059329, 14403.639909002268, 15261.93166478347, 15264.847351526481, 15293.0, 15305.005174663638, 12074.215176013553, 14059.718805623881, 14093.859061409383, 14240.860731141509, 14254.429822807075, 693.4972145194744, 716.4805516000883, 717.2146499582948, 718.1848601836926, 860.8937515292849, 879.8548239540468, 883.8409086364452, 887.5074333744769, 889.4618755652834, 891.2424439226638, 919.6551293265026, 949.5299826585849, 949.8195342884829, 951.8001219743862, 953.9523023848815, 937.878075850142, 939.1125386509751, 940.0458534587399, 940.7836197674574, 944.6646440513588, 535.6705626039997, 553.8144721518296, 554.4677109974389, 558.6759679385947, 707.9221285658573, 12032.81950770739, 14441.49454769078, 14974.850251497563, 14991.700165996619, 15352.923235383863, 14154.079984800956, 14349.28253587327, 14352.210628415432, 14355.354009069513, 14378.28108594566, 11050.392228427434, 11774.88225117755, 11804.763904721944, 11964.222325548812, 12157.635270941886, 3680.8159592020293, 3685.5208822852856, 3759.172981943968, 3825.7704537727827, 3909.4135879618, 99247.0, 101002.4949875246, 101213.0, 101910.0, 102397.0, 10918.781624367468, 11108.16688748347, 11149.944250278697, 11153.330800152016, 11218.831717524243, 22410.215642452436, 22706.772932270796, 23002.19492317761, 23006.309810705705, 23022.65466018011, 7823.29590336861, 7924.405669574805, 7945.841083178304, 7961.283484486424, 8032.919670803217, 4265.87202383929, 4336.82652693895, 4348.586884245991, 4372.6939114261695, 4372.737635741865, 3333.100062982981, 3410.2838403935452, 3434.4676575130893, 3465.047112044208, 3465.6361082086523, 649.8635286589869, 650.1515522243424, 658.9011648252705, 665.012456502094, 665.1519312876121, 678.2742465561802, 694.2536772969079, 710.4671496377716, 729.6060127531074, 749.5577609210592, 8443.071262161156, 10107.91366906472, 10292.536835842255, 10328.690139295833, 10422.739431514121, 696.7665831946355, 703.9225685174627, 719.5718547464251, 725.989110163348, 726.1757904778115, 4640.234361330343, 4925.270725813646, 4984.27727979441, 5002.52476014778, 5012.774425150877, 82825.58587207025, 104938.0, 105822.35444113959, 106955.46522267339, 106986.46506767572, 86928.0, 87441.0, 88133.67799483013, 88332.5583372079, 93791.0620893796, 66605.667886642, 79127.0, 80805.99999999882, 82373.58813205895, 83999.5800020996, 9207.953960230156, 9646.083622055894, 9692.46691431978, 9696.6121355145, 9740.853887191555, 39038.02404939899, 52436.73781631068, 53414.73292633512, 53693.463065368844, 55124.72437637947, 69508.87210075458, 70223.0, 71243.93134103097, 72313.55372892618, 72608.63695681488, 11503.367314797431, 11749.76500470003, 11937.880621193677, 11996.520139194334, 12006.879931200576, 12577.30824804608, 12683.87316126827, 12812.743745125232, 12986.805197921849, 13370.799438008633, 11872.091337622338, 12019.098567607289, 12136.939315303367, 12566.434510446868, 12703.0, 11793.882061179278, 11980.0, 12393.690157746129, 12631.684207894876, 12722.173058751152, 11595.710107247387, 11955.462004209658, 12071.939640301742, 12163.81754273669, 12494.500219991098, 10034.69895903124, 10212.131968782736, 10797.0, 11118.221724479208, 11575.826362604736, 10268.58925642966, 10831.234256927215, 11012.0, 11017.338959662446, 11028.172887033665], "value": 7400.80144919625, "unit": "score"}], "name": "dromaeo_css", "value": 7198.902818978472, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 114.0, 112.0, 112.0, 112.0, 113.0, 112.0, 114.0, 113.0], "value": 112.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 113.0, 109.0, 145.0, 96.0, 115.0, 136.0, 142.0, 127.0, 110.0], "value": 123.8, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 182.0, 182.0, 184.0, 183.0, 184.0, 183.0, 182.0, 182.0], "value": 183.0, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 58.0, 90.0, 59.0, 58.0, 58.0, 58.0, 72.0, 57.0], "value": 65.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 92.0, 72.0, 92.0, 90.0, 100.0, 115.0, 97.0, 70.0, 83.0], "value": 89.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 88.0, 86.0, 89.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 87.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 133.0, 94.0, 93.0, 92.0, 93.0, 94.0, 93.0, 94.0, 94.0], "value": 101.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 87.0, 84.0, 87.0, 84.0, 85.0, 85.0, 84.0, 87.0], "value": 85.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 55.0, 55.0, 55.0, 53.0, 55.0, 78.0, 84.0, 84.0, 88.0], "value": 66.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 70.0, 68.0, 76.0, 72.0, 69.0, 71.0, 71.0, 71.0, 71.0], "value": 71.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 85.0, 86.0, 83.0, 83.0, 83.0, 83.0, 85.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 140.0, 146.0, 144.0, 138.0, 140.0, 133.0, 145.0, 202.0, 165.0], "value": 149.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 194.0, 168.0, 168.0, 220.0, 217.0, 168.0, 168.0, 169.0, 169.0], "value": 180.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 67.0, 68.0, 66.0, 66.0, 65.0, 64.0, 66.0, 66.0, 64.0], "value": 65.8, "unit": "score"}], "name": "kraken", "value": 1466.5, "alertThreshold": 2.0}]} 09:11:02 INFO - Return code: 0 09:11:02 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:11:02 ERROR - # TBPL SUCCESS # 09:11:02 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:11:02 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:11:02 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:11:02 INFO - 2017-02-07 09:11:02.726 screenresolution[1975:12236] starting screenresolution argv=screenresolution get 09:11:02 INFO - 2017-02-07 09:11:02.728 screenresolution[1975:12236] Display 0: 1600x1200x32@60 09:11:02 INFO - 2017-02-07 09:11:02.735 screenresolution[1976:12239] starting screenresolution argv=screenresolution list 09:11:02 INFO - Available Modes on Display 0 09:11:02 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:11:02 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:11:02 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:11:02 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:11:02 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:11:02 INFO - Intel Iris: 09:11:02 INFO - Chipset Model: Intel Iris 09:11:02 INFO - Type: GPU 09:11:02 INFO - Bus: Built-In 09:11:02 INFO - VRAM (Dynamic, Max): 1536 MB 09:11:02 INFO - Vendor: Intel (0x8086) 09:11:02 INFO - Device ID: 0x0a2e 09:11:02 INFO - Revision ID: 0x0009 09:11:02 INFO - Displays: 09:11:02 INFO - Display: 09:11:02 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:11:02 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:11:02 INFO - Display Serial Number: 1600x1200 60 09:11:02 INFO - Main Display: Yes 09:11:02 INFO - Mirror: Off 09:11:02 INFO - Online: Yes 09:11:02 INFO - Rotation: Supported 09:11:02 INFO - Return code: 0 09:11:02 INFO - Running post-action listener: _resource_record_post_action 09:11:02 INFO - [mozharness: 2017-02-07 17:11:02.979116Z] Finished run-tests step (success) 09:11:02 INFO - Running post-run listener: _resource_record_post_run 09:11:03 INFO - Total resource usage - Wall time: 1198s; CPU: 54.0%; Read bytes: 294912; Write bytes: 505239552; Read time: 7; Write time: 1654 09:11:03 INFO - TinderboxPrint: CPU usage
53.5% 09:11:03 INFO - TinderboxPrint: I/O read bytes / time
294,912 / 7 09:11:03 INFO - TinderboxPrint: I/O write bytes / time
505,239,552 / 1,654 09:11:03 INFO - TinderboxPrint: CPU idle
2,228.1 (46.5%) 09:11:03 INFO - TinderboxPrint: CPU system
958.2 (20.0%) 09:11:03 INFO - TinderboxPrint: CPU user
1,607.3 (33.5%) 09:11:03 INFO - TinderboxPrint: Swap in / out
207,949,824 / 0 09:11:03 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 203039232; Write bytes: 201364992; Read time: 15539; Write time: 440 09:11:03 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 294912; Write bytes: 293057024; Read time: 7; Write time: 1175 09:11:03 INFO - Running post-run listener: _upload_blobber_files 09:11:03 INFO - Blob upload gear active. 09:11:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:11:03 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:11:03 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:11:03 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:11:03 INFO - (blobuploader) - INFO - Open directory for files ... 09:11:03 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 09:11:03 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:03 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:04 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 09:11:04 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:04 INFO - (blobuploader) - INFO - Done attempting. 09:11:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 09:11:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 09:11:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:06 INFO - (blobuploader) - INFO - Done attempting. 09:11:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:11:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:11:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:07 INFO - (blobuploader) - INFO - Done attempting. 09:11:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:11:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:11:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:11:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:11:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:11:10 INFO - (blobuploader) - INFO - Done attempting. 09:11:10 INFO - (blobuploader) - INFO - Iteration through files over. 09:11:10 INFO - Return code: 0 09:11:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:11:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:11:10 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c53c47eb23507571916a583de98d6c5a7f82943a5ee5f39fc1252dcaa4ca3290f44281fcdf6bbf354258e24fed1ae449ff0cd75052d66cf80da37f4162bb98aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77e77de8a57249b812cfe2e3c3dedaeee7d35f440405475374f62fba300533acb85cd9db0e54e12345dc89b331865d08dcb560fbb18b734d2842497acff7f915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80bd8d203777e2f38bc7eeb0005f3e54a7774a24f92e7dd65c84c6c9431bbd1df8c8043346d519a5d0c5b995965f4b6c121b519394c8e45cd2082f7613f00a93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2cad034d2ca0facd7d418e02b8588bcdec2abc6cd1007afcda243dfc9329c59572d84194f8a7cb004f4de8496bc633b795de93ed7d4bc88cc132bbd6acf6b5fa"} 09:11:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:11:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:11:10 INFO - Contents: 09:11:10 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/c53c47eb23507571916a583de98d6c5a7f82943a5ee5f39fc1252dcaa4ca3290f44281fcdf6bbf354258e24fed1ae449ff0cd75052d66cf80da37f4162bb98aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77e77de8a57249b812cfe2e3c3dedaeee7d35f440405475374f62fba300533acb85cd9db0e54e12345dc89b331865d08dcb560fbb18b734d2842497acff7f915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80bd8d203777e2f38bc7eeb0005f3e54a7774a24f92e7dd65c84c6c9431bbd1df8c8043346d519a5d0c5b995965f4b6c121b519394c8e45cd2082f7613f00a93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2cad034d2ca0facd7d418e02b8588bcdec2abc6cd1007afcda243dfc9329c59572d84194f8a7cb004f4de8496bc633b795de93ed7d4bc88cc132bbd6acf6b5fa"} 09:11:10 INFO - Running post-run listener: copy_logs_to_upload_dir 09:11:10 INFO - Copying logs to upload dir... 09:11:10 INFO - mkdir: /builds/slave/test/build/upload/logs 09:11:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.816445 ========= master_lag: 4.17 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 5 secs) (at 2017-02-07 09:11:14.316664) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 09:11:14.321646) ========= 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.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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/c53c47eb23507571916a583de98d6c5a7f82943a5ee5f39fc1252dcaa4ca3290f44281fcdf6bbf354258e24fed1ae449ff0cd75052d66cf80da37f4162bb98aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77e77de8a57249b812cfe2e3c3dedaeee7d35f440405475374f62fba300533acb85cd9db0e54e12345dc89b331865d08dcb560fbb18b734d2842497acff7f915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80bd8d203777e2f38bc7eeb0005f3e54a7774a24f92e7dd65c84c6c9431bbd1df8c8043346d519a5d0c5b995965f4b6c121b519394c8e45cd2082f7613f00a93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2cad034d2ca0facd7d418e02b8588bcdec2abc6cd1007afcda243dfc9329c59572d84194f8a7cb004f4de8496bc633b795de93ed7d4bc88cc132bbd6acf6b5fa"} build_url:https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/artifacts/public/build/firefox-53.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011064 build_url: 'https://queue.taskcluster.net/v1/task/P9CfnH7VQT6opU9s2TV56Q/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/c53c47eb23507571916a583de98d6c5a7f82943a5ee5f39fc1252dcaa4ca3290f44281fcdf6bbf354258e24fed1ae449ff0cd75052d66cf80da37f4162bb98aa", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/77e77de8a57249b812cfe2e3c3dedaeee7d35f440405475374f62fba300533acb85cd9db0e54e12345dc89b331865d08dcb560fbb18b734d2842497acff7f915", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/80bd8d203777e2f38bc7eeb0005f3e54a7774a24f92e7dd65c84c6c9431bbd1df8c8043346d519a5d0c5b995965f4b6c121b519394c8e45cd2082f7613f00a93", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/2cad034d2ca0facd7d418e02b8588bcdec2abc6cd1007afcda243dfc9329c59572d84194f8a7cb004f4de8496bc633b795de93ed7d4bc88cc132bbd6acf6b5fa"}' ========= master_lag: 0.04 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-07 09:11:14.368006) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 09:11:14.368345) ========= 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.LSSNS8QAVB/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.9xR20QrT6K/Listeners TMPDIR=/var/folders/r9/65qs5nfd3vb9c3mfq0_swnf400000w/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.005232 ========= master_lag: 1.60 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2017-02-07 09:11:15.976298) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 09:11:15.976638) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-07 09:11:15.981781) ========= ========= Total master_lag: 5.89 =========