builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0318 starttime: 1471889335.97 results: success (0) buildid: 20160822083513 builduid: 47b3f49f150e4740a2e1d8b6b3fa5923 revision: 81c452fd0a01dae174f8981907467791d06f6af7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:55.972161) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:55.972647) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:55.972925) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:55.997241) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:55.997513) ========= 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.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 --2016-08-22 11:08:56-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 57.6M=0s 2016-08-22 11:08:56 (57.6 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.131324 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:56.151572) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:56.151883) ========= 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.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.026406 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:56.199976) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:56.200287) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 81c452fd0a01dae174f8981907467791d06f6af7 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 81c452fd0a01dae174f8981907467791d06f6af7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 2016-08-22 11:08:56,277 truncating revision to first 12 chars 2016-08-22 11:08:56,277 Setting DEBUG logging. 2016-08-22 11:08:56,277 attempt 1/10 2016-08-22 11:08:56,278 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/81c452fd0a01?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-22 11:08:56,935 unpacking tar archive at: fx-team-81c452fd0a01/testing/mozharness/ program finished with exit code 0 elapsedTime=0.941518 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:57.163528) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:57.163896) ========= script_repo_revision: 81c452fd0a01dae174f8981907467791d06f6af7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:57.164274) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:57.164552) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-22 11:08:57.182715) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-22 11:08:57.182980) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:08:57 INFO - MultiFileLogger online at 20160822 11:08:57 in /builds/slave/test 11:08:57 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 11:08:57 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:08:57 INFO - {'append_to_log': False, 11:08:57 INFO - 'base_work_dir': '/builds/slave/test', 11:08:57 INFO - 'blob_upload_branch': 'Fx-Team', 11:08:57 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:08:57 INFO - 'branch': 'Fx-Team', 11:08:57 INFO - 'buildbot_json_path': 'buildprops.json', 11:08:57 INFO - 'config_files': ('talos/mac_config.py',), 11:08:57 INFO - 'default_actions': ('clobber', 11:08:57 INFO - 'read-buildbot-config', 11:08:57 INFO - 'download-and-extract', 11:08:57 INFO - 'populate-webroot', 11:08:57 INFO - 'create-virtualenv', 11:08:57 INFO - 'install', 11:08:57 INFO - 'run-tests'), 11:08:57 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:08:57 INFO - 'download_minidump_stackwalk': True, 11:08:57 INFO - 'download_symbols': 'ondemand', 11:08:57 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:08:57 INFO - 'tooltool.py': '/tools/tooltool.py', 11:08:57 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:08:57 INFO - '/tools/misc-python/virtualenv.py')}, 11:08:57 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:08:57 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:08:57 INFO - 'installer_path': 'installer.exe', 11:08:57 INFO - 'log_level': 'info', 11:08:57 INFO - 'log_name': 'talos', 11:08:57 INFO - 'log_to_console': True, 11:08:57 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:08:57 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:08:57 INFO - 'opt_config_files': (), 11:08:57 INFO - 'pip_index': False, 11:08:57 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:08:57 INFO - 'cmd': ('bash', 11:08:57 INFO - '-c', 11:08:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:08:57 INFO - 'enabled': True, 11:08:57 INFO - 'halt_on_failure': False, 11:08:57 INFO - 'name': 'check_screen_resolution'},), 11:08:57 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:08:57 INFO - 'cmd': ('bash', 11:08:57 INFO - '-c', 11:08:57 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:08:57 INFO - 'enabled': True, 11:08:57 INFO - 'halt_on_failure': False, 11:08:57 INFO - 'name': 'check_screen_resolution'},), 11:08:57 INFO - 'run_cmd_checks_enabled': True, 11:08:57 INFO - 'sps_profile': False, 11:08:57 INFO - 'sps_profile_interval': 0, 11:08:57 INFO - 'suite': 'dromaeojs', 11:08:57 INFO - 'system_bits': '32', 11:08:57 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:08:57 INFO - 'title': 't-yosemite-r7-0318', 11:08:57 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:08:57 INFO - 'use_talos_json': True, 11:08:57 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:08:57 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:08:57 INFO - 'work_dir': 'build'} 11:08:57 INFO - [mozharness: 2016-08-22 18:08:57.370960Z] Running clobber step. 11:08:57 INFO - Running pre-action listener: _resource_record_pre_action 11:08:57 INFO - Running main action method: clobber 11:08:57 INFO - rmtree: /builds/slave/test/build 11:08:57 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:08:59 INFO - Running post-action listener: _resource_record_post_action 11:08:59 INFO - [mozharness: 2016-08-22 18:08:59.388056Z] Finished clobber step (success) 11:08:59 INFO - [mozharness: 2016-08-22 18:08:59.388135Z] Running read-buildbot-config step. 11:08:59 INFO - Running pre-action listener: _resource_record_pre_action 11:08:59 INFO - Running main action method: read_buildbot_config 11:08:59 INFO - Using buildbot properties: 11:08:59 INFO - { 11:08:59 INFO - "project": "", 11:08:59 INFO - "product": "firefox", 11:08:59 INFO - "script_repo_revision": "production", 11:08:59 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 11:08:59 INFO - "repository": "", 11:08:59 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 11:08:59 INFO - "buildid": "20160822083513", 11:08:59 INFO - "pgo_build": "False", 11:08:59 INFO - "basedir": "/builds/slave/test", 11:08:59 INFO - "buildnumber": 923, 11:08:59 INFO - "slavename": "t-yosemite-r7-0318", 11:08:59 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 11:08:59 INFO - "platform": "yosemite_r7", 11:08:59 INFO - "scheduler": "tests-fx-team-macosx64-talos", 11:08:59 INFO - "branch": "fx-team", 11:08:59 INFO - "repo_path": "integration/fx-team", 11:08:59 INFO - "revision": "81c452fd0a01dae174f8981907467791d06f6af7", 11:08:59 INFO - "stage_platform": "macosx64", 11:08:59 INFO - "builduid": "47b3f49f150e4740a2e1d8b6b3fa5923", 11:08:59 INFO - "slavebuilddir": "test" 11:08:59 INFO - } 11:08:59 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:08:59 INFO - Running post-action listener: _resource_record_post_action 11:08:59 INFO - [mozharness: 2016-08-22 18:08:59.393821Z] Finished read-buildbot-config step (success) 11:08:59 INFO - [mozharness: 2016-08-22 18:08:59.393979Z] Running download-and-extract step. 11:08:59 INFO - Running pre-action listener: _resource_record_pre_action 11:08:59 INFO - Running main action method: download_and_extract 11:08:59 INFO - mkdir: /builds/slave/test/build/tests 11:08:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:08:59 INFO - trying https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:08:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:08:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 11:09:00 INFO - Downloaded 1199 bytes. 11:09:00 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:09:00 INFO - Using the following test package requirements: 11:09:00 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:09:00 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:09:00 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:09:00 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'jsshell-mac.zip'], 11:09:00 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:09:00 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:09:00 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:09:00 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:09:00 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:09:00 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:09:00 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:09:00 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:09:00 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')]} 11:09:00 INFO - trying https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:09:00 INFO - Downloading https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:09:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:09:01 INFO - Downloaded 16352490 bytes. 11:09:01 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:09:08 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 11:09:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:08 INFO - trying https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:09:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:09:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 11:09:09 INFO - Downloaded 16352490 bytes. 11:09:09 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:09:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:15 INFO - trying https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:09:15 INFO - Downloading https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:09:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 11:09:17 INFO - Downloaded 14413981 bytes. 11:09:17 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:09:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:17 INFO - trying https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:09:17 INFO - Downloading https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:09:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 11:09:20 INFO - Downloaded 111476077 bytes. 11:09:20 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:09:20 INFO - mkdir: /builds/slave/test/properties 11:09:20 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:09:20 INFO - Writing to file /builds/slave/test/properties/build_url 11:09:20 INFO - Contents: 11:09:20 INFO - build_url:https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:09:21 INFO - Running post-action listener: _resource_record_post_action 11:09:21 INFO - Running post-action listener: set_extra_try_arguments 11:09:21 INFO - [mozharness: 2016-08-22 18:09:21.227986Z] Finished download-and-extract step (success) 11:09:21 INFO - [mozharness: 2016-08-22 18:09:21.228106Z] Running populate-webroot step. 11:09:21 INFO - Running pre-action listener: _resource_record_pre_action 11:09:21 INFO - Running main action method: populate_webroot 11:09:21 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:09:21 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:09:21 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:09:21 INFO - '--tppagecycles', 11:09:21 INFO - '7'], 11:09:21 INFO - 'tests': ['tsvgm']}}, 11:09:21 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:09:21 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:09:21 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:09:21 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:09:21 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:21 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:09:21 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['damp', 'tps']}, 11:09:21 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'tests': ['damp', 'tps']}, 11:09:21 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['dromaeo_dom']}, 11:09:21 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:09:21 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['basic_compositor_video']}, 11:09:21 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:09:21 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'other-e10s': {'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['a11yr', 11:09:21 INFO - 'ts_paint', 11:09:21 INFO - 'tpaint', 11:09:21 INFO - 'sessionrestore', 11:09:21 INFO - 'sessionrestore_no_auto_restore', 11:09:21 INFO - 'tabpaint']}, 11:09:21 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['tsvgx', 11:09:21 INFO - 'tsvgr_opacity', 11:09:21 INFO - 'tart', 11:09:21 INFO - 'tscrollx', 11:09:21 INFO - 'cart']}, 11:09:21 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:09:21 INFO - 'tsvgr_opacity', 11:09:21 INFO - 'tart', 11:09:21 INFO - 'tscrollx', 11:09:21 INFO - 'cart']}, 11:09:21 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'talos_options': ['--disable-e10s'], 11:09:21 INFO - 'tests': ['tp5o']}, 11:09:21 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'tests': ['tp5o']}, 11:09:21 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'talos_options': ['--disable-e10s', 11:09:21 INFO - '--xperf_path', 11:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:21 INFO - 'tests': ['tp5n']}, 11:09:21 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:09:21 INFO - 'talos_options': ['--xperf_path', 11:09:21 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:09:21 INFO - 'tests': ['tp5n']}}, 11:09:21 INFO - 'talos.zip': {'path': '', 11:09:21 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:09:21 INFO - Running post-action listener: _resource_record_post_action 11:09:21 INFO - [mozharness: 2016-08-22 18:09:21.246979Z] Finished populate-webroot step (success) 11:09:21 INFO - [mozharness: 2016-08-22 18:09:21.247119Z] Running create-virtualenv step. 11:09:21 INFO - Running pre-action listener: _resource_record_pre_action 11:09:21 INFO - Running main action method: create_virtualenv 11:09:21 INFO - Creating virtualenv /builds/slave/test/build/venv 11:09:21 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 11:09:21 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:09:21 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:09:21 INFO - Using real prefix '/tools/python27' 11:09:21 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:09:22 INFO - Installing distribute.............................................................................................................................................................................................done. 11:09:25 INFO - Installing pip.................done. 11:09:25 INFO - Return code: 0 11:09:25 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:25 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 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:25 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 11:09:25 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 11:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:25 INFO - 'HOME': '/Users/cltbld', 11:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:25 INFO - 'LOGNAME': 'cltbld', 11:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:25 INFO - 'MOZ_NO_REMOTE': '1', 11:09:25 INFO - 'NO_EM_RESTART': '1', 11:09:25 INFO - 'PAGER': '/bin/cat', 11:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:25 INFO - 'PWD': '/builds/slave/test', 11:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:25 INFO - 'SHELL': '/bin/bash', 11:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:25 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:25 INFO - 'USER': 'cltbld', 11:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:25 INFO - 'XPC_FLAGS': '0x0', 11:09:25 INFO - 'XPC_SERVICE_NAME': '0', 11:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:25 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 11:09:25 INFO - Cleaning up... 11:09:25 INFO - Return code: 0 11:09:25 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:25 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 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:25 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 11:09:25 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 11:09:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:25 INFO - 'HOME': '/Users/cltbld', 11:09:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:25 INFO - 'LOGNAME': 'cltbld', 11:09:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:25 INFO - 'MOZ_NO_REMOTE': '1', 11:09:25 INFO - 'NO_EM_RESTART': '1', 11:09:25 INFO - 'PAGER': '/bin/cat', 11:09:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:25 INFO - 'PWD': '/builds/slave/test', 11:09:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:25 INFO - 'SHELL': '/bin/bash', 11:09:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:25 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:25 INFO - 'USER': 'cltbld', 11:09:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:25 INFO - 'XPC_FLAGS': '0x0', 11:09:25 INFO - 'XPC_SERVICE_NAME': '0', 11:09:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:25 INFO - Downloading/unpacking psutil>=3.1.1 11:09:25 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 11:09:25 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 11:09:25 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 11:09:25 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 11:09:25 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 11:09:25 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 11:09:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:09:28 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:28 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:28 INFO - Installing collected packages: psutil 11:09:28 INFO - Running setup.py install for psutil 11:09:28 INFO - building 'psutil._psutil_osx' extension 11:09:28 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 11:09:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 11:09:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 11:09:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 11:09:29 INFO - building 'psutil._psutil_posix' extension 11:09:29 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 11:09:29 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:09:29 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:09:29 INFO - ^ 11:09:29 INFO - 1 warning generated. 11:09:29 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 11:09:29 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:09:29 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:09:29 INFO - Successfully installed psutil 11:09:29 INFO - Cleaning up... 11:09:29 INFO - Return code: 0 11:09:29 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:29 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:29 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 11:09:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 11:09:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:29 INFO - 'HOME': '/Users/cltbld', 11:09:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:29 INFO - 'LOGNAME': 'cltbld', 11:09:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:29 INFO - 'MOZ_NO_REMOTE': '1', 11:09:29 INFO - 'NO_EM_RESTART': '1', 11:09:29 INFO - 'PAGER': '/bin/cat', 11:09:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:29 INFO - 'PWD': '/builds/slave/test', 11:09:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:29 INFO - 'SHELL': '/bin/bash', 11:09:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:29 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:29 INFO - 'USER': 'cltbld', 11:09:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:29 INFO - 'XPC_FLAGS': '0x0', 11:09:29 INFO - 'XPC_SERVICE_NAME': '0', 11:09:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:29 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:09:29 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:29 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:29 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:29 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:30 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 11:09:30 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 11:09:32 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:09:32 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:09:32 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:09:32 INFO - Installing collected packages: mozsystemmonitor 11:09:32 INFO - Running setup.py install for mozsystemmonitor 11:09:32 INFO - Successfully installed mozsystemmonitor 11:09:32 INFO - Cleaning up... 11:09:32 INFO - Return code: 0 11:09:32 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 11:09:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 11:09:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:32 INFO - 'HOME': '/Users/cltbld', 11:09:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:32 INFO - 'LOGNAME': 'cltbld', 11:09:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:32 INFO - 'MOZ_NO_REMOTE': '1', 11:09:32 INFO - 'NO_EM_RESTART': '1', 11:09:32 INFO - 'PAGER': '/bin/cat', 11:09:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:32 INFO - 'PWD': '/builds/slave/test', 11:09:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:32 INFO - 'SHELL': '/bin/bash', 11:09:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:32 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:32 INFO - 'USER': 'cltbld', 11:09:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:32 INFO - 'XPC_FLAGS': '0x0', 11:09:32 INFO - 'XPC_SERVICE_NAME': '0', 11:09:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:33 INFO - Downloading/unpacking jsonschema==2.5.1 11:09:33 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 11:09:33 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 11:09:33 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 11:09:33 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 11:09:33 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 11:09:33 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 11:09:35 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:09:35 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09: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 11:09:35 INFO - Downloading functools32-3.2.3-2.tar.gz 11:09:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:09:36 INFO - warning: no files found matching '*.txt' 11:09:36 INFO - no previously-included directories found matching 'build' 11:09:36 INFO - no previously-included directories found matching 'dist' 11:09:36 INFO - no previously-included directories found matching '.git*' 11:09:36 INFO - Installing collected packages: jsonschema, functools32 11:09:36 INFO - Running setup.py install for functools32 11:09:36 INFO - warning: no files found matching '*.txt' 11:09:36 INFO - no previously-included directories found matching 'build' 11:09:36 INFO - no previously-included directories found matching 'dist' 11:09:36 INFO - no previously-included directories found matching '.git*' 11:09:36 INFO - Successfully installed jsonschema functools32 11:09:36 INFO - Cleaning up... 11:09:36 INFO - Return code: 0 11:09:36 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 11:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 11:09:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:36 INFO - 'HOME': '/Users/cltbld', 11:09:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:36 INFO - 'LOGNAME': 'cltbld', 11:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:36 INFO - 'MOZ_NO_REMOTE': '1', 11:09:36 INFO - 'NO_EM_RESTART': '1', 11:09:36 INFO - 'PAGER': '/bin/cat', 11:09:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:36 INFO - 'PWD': '/builds/slave/test', 11:09:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:36 INFO - 'SHELL': '/bin/bash', 11:09:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:36 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:36 INFO - 'USER': 'cltbld', 11:09:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:36 INFO - 'XPC_FLAGS': '0x0', 11:09:36 INFO - 'XPC_SERVICE_NAME': '0', 11:09:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:36 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:09:36 INFO - Cleaning up... 11:09:36 INFO - Return code: 0 11:09:36 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 11:09:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 11:09:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:36 INFO - 'HOME': '/Users/cltbld', 11:09:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:36 INFO - 'LOGNAME': 'cltbld', 11:09:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:36 INFO - 'MOZ_NO_REMOTE': '1', 11:09:36 INFO - 'NO_EM_RESTART': '1', 11:09:36 INFO - 'PAGER': '/bin/cat', 11:09:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:36 INFO - 'PWD': '/builds/slave/test', 11:09:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:36 INFO - 'SHELL': '/bin/bash', 11:09:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:36 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:36 INFO - 'USER': 'cltbld', 11:09:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:36 INFO - 'XPC_FLAGS': '0x0', 11:09:36 INFO - 'XPC_SERVICE_NAME': '0', 11:09:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:36 INFO - Downloading/unpacking blobuploader==1.2.4 11:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:39 INFO - Downloading blobuploader-1.2.4.tar.gz 11:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:09:39 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:09:39 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:40 INFO - Downloading docopt-0.6.1.tar.gz 11:09:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:09:40 INFO - Installing collected packages: blobuploader, requests, docopt 11:09:40 INFO - Running setup.py install for blobuploader 11:09:40 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:09:40 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:09:40 INFO - Running setup.py install for requests 11:09:41 INFO - Running setup.py install for docopt 11:09:41 INFO - Successfully installed blobuploader requests docopt 11:09:41 INFO - Cleaning up... 11:09:41 INFO - Return code: 0 11:09:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:09:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:09:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:41 INFO - 'HOME': '/Users/cltbld', 11:09:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:41 INFO - 'LOGNAME': 'cltbld', 11:09:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:41 INFO - 'MOZ_NO_REMOTE': '1', 11:09:41 INFO - 'NO_EM_RESTART': '1', 11:09:41 INFO - 'PAGER': '/bin/cat', 11:09:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:41 INFO - 'PWD': '/builds/slave/test', 11:09:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:41 INFO - 'SHELL': '/bin/bash', 11:09:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:41 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:41 INFO - 'USER': 'cltbld', 11:09:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:41 INFO - 'XPC_FLAGS': '0x0', 11:09:41 INFO - 'XPC_SERVICE_NAME': '0', 11:09:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-zQFr90-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-e3iiC3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:41 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-h8rbRD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Tp4t6N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-MYHMRk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-m0UOSf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-WvCW61-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-SkE1QW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-vNnmZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-O0jxdl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-eUqAog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-3Xatum-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:42 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-dhbKSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:43 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-yO_33z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:43 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-L5sJU8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:43 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ejSeTm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:43 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-eiFpkT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:43 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:09:43 INFO - Running setup.py install for manifestparser 11:09:43 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:09:43 INFO - Running setup.py install for mozcrash 11:09:43 INFO - Running setup.py install for mozdebug 11:09:43 INFO - Running setup.py install for mozdevice 11:09:44 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Running setup.py install for mozfile 11:09:44 INFO - Running setup.py install for mozhttpd 11:09:44 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Running setup.py install for mozinfo 11:09:44 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Running setup.py install for mozInstall 11:09:44 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Running setup.py install for mozleak 11:09:44 INFO - Running setup.py install for mozlog 11:09:44 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:09:44 INFO - Running setup.py install for moznetwork 11:09:45 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:09:45 INFO - Running setup.py install for mozprocess 11:09:45 INFO - Running setup.py install for mozprofile 11:09:45 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:09:45 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:09:45 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:09:45 INFO - Running setup.py install for mozrunner 11:09:45 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:09:45 INFO - Running setup.py install for mozscreenshot 11:09:45 INFO - Running setup.py install for moztest 11:09:45 INFO - Running setup.py install for mozversion 11:09:46 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:09:46 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:09:46 INFO - Cleaning up... 11:09:46 INFO - Return code: 0 11:09:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09: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')]} 11:09:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09: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')]} 11:09:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 11:09:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:09:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:46 INFO - 'HOME': '/Users/cltbld', 11:09:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:46 INFO - 'LOGNAME': 'cltbld', 11:09:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:46 INFO - 'MOZ_NO_REMOTE': '1', 11:09:46 INFO - 'NO_EM_RESTART': '1', 11:09:46 INFO - 'PAGER': '/bin/cat', 11:09:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:46 INFO - 'PWD': '/builds/slave/test', 11:09:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:46 INFO - 'SHELL': '/bin/bash', 11:09:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:46 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:46 INFO - 'USER': 'cltbld', 11:09:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:46 INFO - 'XPC_FLAGS': '0x0', 11:09:46 INFO - 'XPC_SERVICE_NAME': '0', 11:09:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-PSgPnj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-rQfhqQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 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)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-UCVYJ7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:09:46 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)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-C1SCPj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:09:46 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 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)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-fEtTsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:09:46 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)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-O7hXmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:09:46 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)) 11:09:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:09:46 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-otmkU3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-Q3eWDB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_Zw1Qm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-y3F9e4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 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)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-3XhBfy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-WZ8nbK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 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)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-_XLJLj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:09:47 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)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-QGkHd7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:09:47 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 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)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ZZkZmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:09:47 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)) 11:09:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:09:47 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-ajdifj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 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)) 11:09:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:09:48 INFO - Running setup.py (path:/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/pip-3jGFpt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:09:48 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 11:09:48 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:09:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:09:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:09:50 INFO - Downloading blessings-1.6.tar.gz 11:09:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:09:50 INFO - Installing collected packages: blessings 11:09:50 INFO - Running setup.py install for blessings 11:09:50 INFO - Successfully installed blessings 11:09:50 INFO - Cleaning up... 11:09:50 INFO - Return code: 0 11:09:50 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:09:50 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:51 INFO - Reading from file tmpfile_stdout 11:09:51 INFO - Current package versions: 11:09:51 INFO - blessings == 1.6 11:09:51 INFO - blobuploader == 1.2.4 11:09:51 INFO - docopt == 0.6.1 11:09:51 INFO - functools32 == 3.2.3-2 11:09:51 INFO - jsonschema == 2.5.1 11:09:51 INFO - manifestparser == 1.1 11:09:51 INFO - mozInstall == 1.12 11:09:51 INFO - mozcrash == 0.17 11:09:51 INFO - mozdebug == 0.1 11:09:51 INFO - mozdevice == 0.48 11:09:51 INFO - mozfile == 1.2 11:09:51 INFO - mozhttpd == 0.7 11:09:51 INFO - mozinfo == 0.9 11:09:51 INFO - mozleak == 0.1 11:09:51 INFO - mozlog == 3.3 11:09:51 INFO - moznetwork == 0.27 11:09:51 INFO - mozprocess == 0.23 11:09:51 INFO - mozprofile == 0.28 11:09:51 INFO - mozrunner == 6.12 11:09:51 INFO - mozscreenshot == 0.1 11:09:51 INFO - mozsystemmonitor == 0.3 11:09:51 INFO - moztest == 0.7 11:09:51 INFO - mozversion == 1.4 11:09:51 INFO - psutil == 3.1.1 11:09:51 INFO - requests == 1.2.3 11:09:51 INFO - wsgiref == 0.1.2 11:09:51 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 11:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 11:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:51 INFO - 'HOME': '/Users/cltbld', 11:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:51 INFO - 'LOGNAME': 'cltbld', 11:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:51 INFO - 'MOZ_NO_REMOTE': '1', 11:09:51 INFO - 'NO_EM_RESTART': '1', 11:09:51 INFO - 'PAGER': '/bin/cat', 11:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:51 INFO - 'PWD': '/builds/slave/test', 11:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:51 INFO - 'SHELL': '/bin/bash', 11:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:51 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:51 INFO - 'USER': 'cltbld', 11:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:51 INFO - 'XPC_FLAGS': '0x0', 11:09:51 INFO - 'XPC_SERVICE_NAME': '0', 11:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 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)) 11:09:51 INFO - Cleaning up... 11:09:51 INFO - Return code: 0 11:09:51 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:09:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:09:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:09:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:09:51 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 0x1044ffa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104594618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f87e958e150>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, '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.qRo2SbPcC4/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.y4FD9mQBed/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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 11:09:51 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 11:09:51 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 11:09:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:09:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:09:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:09:51 INFO - 'HOME': '/Users/cltbld', 11:09:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:09:51 INFO - 'LOGNAME': 'cltbld', 11:09:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:09:51 INFO - 'MOZ_NO_REMOTE': '1', 11:09:51 INFO - 'NO_EM_RESTART': '1', 11:09:51 INFO - 'PAGER': '/bin/cat', 11:09:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:09:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:09:51 INFO - 'PWD': '/builds/slave/test', 11:09:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:09:51 INFO - 'SHELL': '/bin/bash', 11:09:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:09:51 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:09:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:09:51 INFO - 'USER': 'cltbld', 11:09:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:09:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:09:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:09:51 INFO - 'XPC_FLAGS': '0x0', 11:09:51 INFO - 'XPC_SERVICE_NAME': '0', 11:09:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:09:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:09:51 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:09:51 INFO - Cleaning up... 11:09:51 INFO - Return code: 0 11:09:51 INFO - Running post-action listener: _resource_record_post_action 11:09:51 INFO - Running post-action listener: _start_resource_monitoring 11:09:51 INFO - Starting resource monitoring. 11:09:51 INFO - [mozharness: 2016-08-22 18:09:51.818132Z] Finished create-virtualenv step (success) 11:09:51 INFO - [mozharness: 2016-08-22 18:09:51.819018Z] Running install step. 11:09:51 INFO - Running pre-action listener: _resource_record_pre_action 11:09:51 INFO - Running main action method: install 11:09:51 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:09:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:09:52 INFO - Reading from file tmpfile_stdout 11:09:52 INFO - Detecting whether we're running mozinstall >=1.0... 11:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:09:52 INFO - Reading from file tmpfile_stdout 11:09:52 INFO - Output received: 11:09:52 INFO - Usage: mozinstall [options] installer 11:09:52 INFO - Options: 11:09:52 INFO - -h, --help show this help message and exit 11:09:52 INFO - -d DEST, --destination=DEST 11:09:52 INFO - Directory to install application into. [default: 11:09:52 INFO - "/builds/slave/test"] 11:09:52 INFO - --app=APP Application being installed. [default: firefox] 11:09:52 INFO - mkdir: /builds/slave/test/build/application 11:09:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 11:09:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 11:10:26 INFO - Reading from file tmpfile_stdout 11:10:26 INFO - Output received: 11:10:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:10:26 INFO - Running post-action listener: _resource_record_post_action 11:10:26 INFO - [mozharness: 2016-08-22 18:10:26.350369Z] Finished install step (success) 11:10:26 INFO - [mozharness: 2016-08-22 18:10:26.350530Z] Running run-tests step. 11:10:26 INFO - Running pre-action listener: _resource_record_pre_action 11:10:26 INFO - Running main action method: run_tests 11:10:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:10:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:10:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:10:26 INFO - 2016-08-22 11:10:26.365 screenresolution[1634:7399] starting screenresolution argv=screenresolution get 11:10:26 INFO - 2016-08-22 11:10:26.367 screenresolution[1634:7399] Display 0: 1600x1200x32@60 11:10:26 INFO - 2016-08-22 11:10:26.373 screenresolution[1635:7401] starting screenresolution argv=screenresolution list 11:10:26 INFO - Available Modes on Display 0 11:10:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:10:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:10:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:10:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:10:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:10:26 INFO - Intel Iris: 11:10:26 INFO - Chipset Model: Intel Iris 11:10:26 INFO - Type: GPU 11:10:26 INFO - Bus: Built-In 11:10:26 INFO - VRAM (Dynamic, Max): 1536 MB 11:10:26 INFO - Vendor: Intel (0x8086) 11:10:26 INFO - Device ID: 0x0a2e 11:10:26 INFO - Revision ID: 0x0009 11:10:26 INFO - Displays: 11:10:26 INFO - Display: 11:10:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:10:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:10:26 INFO - Display Serial Number: 1600x1200 60 11:10:26 INFO - Main Display: Yes 11:10:26 INFO - Mirror: Off 11:10:26 INFO - Online: Yes 11:10:26 INFO - Rotation: Supported 11:10:26 INFO - Return code: 0 11:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:10:26 INFO - Python 2.7.3 11:10:26 INFO - Return code: 0 11:10:26 INFO - grabbing minidump binary from tooltool 11:10:26 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')]} 11:10:26 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 0x104463d30>, '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 0x1041c8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10445bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:10:26 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 11:10:26 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 11:10:26 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 11:10:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:10:26 INFO - Return code: 0 11:10:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:10:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:10:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:10:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:10:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:10:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 11:10:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0318 --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 11:10:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.qRo2SbPcC4/Render', 11:10:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:10:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:10:26 INFO - 'HOME': '/Users/cltbld', 11:10:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:10:26 INFO - 'LOGNAME': 'cltbld', 11:10:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:10:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:10:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:10:26 INFO - 'MOZ_NO_REMOTE': '1', 11:10:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:10:26 INFO - 'NO_EM_RESTART': '1', 11:10:26 INFO - 'PAGER': '/bin/cat', 11:10:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:10:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:10:26 INFO - 'PWD': '/builds/slave/test', 11:10:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:10:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:10:26 INFO - 'SHELL': '/bin/bash', 11:10:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.y4FD9mQBed/Listeners', 11:10:26 INFO - 'TMPDIR': '/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/', 11:10:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:10:26 INFO - 'USER': 'cltbld', 11:10:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:10:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:10:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:10:26 INFO - 'XPC_FLAGS': '0x0', 11:10:26 INFO - 'XPC_SERVICE_NAME': '0', 11:10:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:10:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0318', '--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 11:10:26 INFO - mozversion application_buildid: 20160822083513 11:10:26 INFO - mozversion application_changeset: 81c452fd0a01dae174f8981907467791d06f6af7 11:10:26 INFO - mozversion application_display_name: Nightly 11:10:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:10:26 INFO - mozversion application_name: Firefox 11:10:26 INFO - mozversion application_remotingname: firefox 11:10:26 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 11:10:26 INFO - mozversion application_vendor: Mozilla 11:10:26 INFO - mozversion application_version: 51.0a1 11:10:26 INFO - mozversion platform_buildid: 20160822083513 11:10:26 INFO - mozversion platform_changeset: 81c452fd0a01dae174f8981907467791d06f6af7 11:10:26 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 11:10:26 INFO - mozversion platform_version: 51.0a1 11:10:26 INFO - using testdate: 1471889426 11:10:26 INFO - actual date: 1471889426 11:10:26 INFO - starting webserver on 'localhost:49267' 11:10:26 INFO - SUITE-START | Running 2 tests 11:10:26 INFO - TEST-START | dromaeo_css 11:10:26 INFO - operating with platform_type : mac_ 11:10:26 INFO - Initialising browser for dromaeo_css test... 11:10:26 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpFArn6t/profile http://localhost:49267/getInfo.html) 11:10:29 INFO - PROCESS | 1645 | __metrics Screen width/height:1600/1200 11:10:29 INFO - PROCESS | 1645 | colorDepth:24 11:10:29 INFO - PROCESS | 1645 | Browser inner width/height: 1024/689 11:10:29 INFO - PROCESS | 1645 | __metrics 11:10:29 INFO - PROCESS | 1645 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:10:29 INFO - PROCESS | 1645 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:10:30 INFO - TEST-INFO | 1645: exit 0 11:10:30 INFO - Browser initialized. 11:10:30 INFO - Running cycle 1/1 for dromaeo_css test... 11:10:30 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpFArn6t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:10:42 INFO - PROCESS | 1647 | RSS: Main: 228556800 11:10:42 INFO - PROCESS | 1647 | 11:11:33 INFO - PROCESS | 1647 | 1471889493417 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 11:13:45 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 11:13:46 INFO - PROCESS | 1647 | RSS: Main: 321335296 11:13:46 INFO - PROCESS | 1647 | 11:16:49 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 11:16:50 INFO - PROCESS | 1647 | RSS: Main: 372961280 11:16:50 INFO - PROCESS | 1647 | 11:19:52 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 11:19:53 INFO - PROCESS | 1647 | RSS: Main: 361492480 11:19:53 INFO - PROCESS | 1647 | 11:22:55 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 11:22:56 INFO - PROCESS | 1647 | RSS: Main: 338157568 11:22:56 INFO - PROCESS | 1647 | 11:25:59 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 11:26:00 INFO - PROCESS | 1647 | RSS: Main: 330665984 11:26:00 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 11:29:03 INFO - PROCESS | 1647 | RSS: Main: 563163136 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | __start_tp_report 11:29:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 11:29:03 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 11:29:03 INFO - PROCESS | 1647 | |i|pagename|runs| 11:29:03 INFO - PROCESS | 1647 | |0;dojo.html;2836.9432611347775;3401.1667141550324;3409.198838273006;3418.572678415198;3446.5691788526433;5682.204491371212;5769.990251705955;5790.536757059435;5889.587728858976;5896.557758168138;5766.769329226826;5786.623869448483;5825.533957283428;5858.355580886099;5914.940850591493;5231.73841307935;5235.65968212065;5298.1521907454635;5329.147336426173;5342.839714808542;5396.568274538027;5401.459854014606;5408.675479471224;5414.593905457087;5419.83740487786;5189.610779191576;5198.532132108128;5217.973910130425;5258.290130832365;5332.866678333034;5329.040772660919;5333;5336.439673834269;5345.786168553253;5352.437994010612;5237.371515418136;5271.710055946922;5290.470952904717;5292.814751483679;5337.359516857963;5593.021221286259;5710.400407957146;5809.157672137559;5811.4479124483105;5856.619319744203;6277.403646653606;6278.843028924268;6288.402601752826;6294.874102517924;6301.338359472281;6241.687915604201;6290.056491526262;6296.748130074791;6306.621602703853;6316.778912738032;5793.073108302651;5863;5956.255468066492;5960.731767070492;6015.157877897098;6443.774467893695;6449.5485316027425;6450.322716114741;6459.870802583922;6466.935330646634;6176.166217560662;6203.7828675997025;6232.283287421914;6243.406876346739;6250.937490625127;6151.66165860873;6183;6233.968830155911;6259.843503912439;6300.338464461165;6238.095476155932;6261.874762504815;6267.780627678009;6269.592476489013;6271.749130034838;6244.000959846403;6252.74989000444;6278.905816412757;6283.151774510474;6288.622682639057;4880.975595122072;4907.901841963141;4937.8271760488215;5010.974945125251;5071.746412679425;3808.847646094125;4467.30756732707;4510.233260345749;4542.273236282179;4561.384213131251;5904;5953.077273022688;5953.553483488756;5958.255218097738;5962.552808539377;6270.529710271748;6275.560710750295;6286.402791734778;6286.622802631765;6300.653464059429;5653.830385088454;5782.739776710058;5782.7686892523825;5828.475437210586;5841.269841269841;5438.45615438453;5480.424555421703;5496.7526461309335;5543.861403464865;5545.140503222007;6452.064450654722;6468.708908099053;6473.514486413444;6473.838154046187;6498.382653647801;6198.938010620016;6217.937820621917;6257.5932564383165;6263.874722505616;6265.248170219603;6296.370362963667;6339.2392912850755;6392.584482008655;6398.488120950406;6463;6257.342978987323;6279.434850863444;6289.811305660837;6290.056491526308;6293.842653933505;5993.730282137401;6196.039613859859;6242.500599952084;6253.187085678833;6266.436020758154;6010.7295171716505;6095.3904609538695;6113.113598528277;6120.908186377297;6233.470155036691;6197.5351848610635;6212.47193988516;6216.595921265103;6228.377162283736;6233;6044.727987240497;6067;6077.453029227214;6080.452759251689;6085.391460853879;5982.252218472691;6075.483583895419;6125.234345706786;6146.078088286793;6226.875462490815;6012.5886701968575;6049.727762250621;6063.605865618721;6067.908981365372;6082.057281121433 11:29:03 INFO - PROCESS | 1647 | |1;ext.html;469.18830423367575;502.18896482181265;502.97736601852915;519.9185693756988;533.3093643731368;30597.541036884435;39708.80145599272;40763.388549171716;40814.97962550934;43138.137237255316;42233.78883105588;42521.36217956725;42648;42706;42735;9758.85361719573;9943.403395796275;9947.253955953296;9947.502624868765;9955.352902061379;9773.364731292444;9881.258905582074;9883;9897.950510247474;9915.702528924145;9848.261380396463;9899.257555683316;9915.504224788769;9945.850812237823;9951;9826.55780489876;9903.702888913343;9908.900910990942;9910.405375677483;9916.305858589902;9820.459874706889;9887.060729230712;9893;9902.653407130716;9904.504774761304;33755;40394.59605403938;40608;40683.77948661545;40879.38680919789;3653.7442379033478;4094.447249621293;4099.098198396348;4125.793710314472;4255.680823938217;31045.22386940322;31536.285308005023;31827.522587161213;31944.361112777846;32070.35859282801;3222.1622378181605;3326.567546219;3350.9497357539412;3359.160209947513;3432.828358582061;509083;510072.44963775313;511348;511604.513616343;521478;58020;58172.12741808876;58263.83472330614;58296.70851645715;58366.7081664589;61467;61506.76986460246;61562.0765688515;61606.69196653988;61612.38387616067;16473.83526164747;16617.41839035652;16621.335146594;16627.25177366997;16675.999440033636;12806.80789788154;12812.93593532045;12824.23054616726;12830.358482076046;12854.55009074678;12822.743545129046;16111.919440402722;16301;16339.918300408423;16493.752593711102;5007.924881126786;5199.376074871039;5984.9128240994805;6040.909386359208;6065.181200537959;863.8185980944072;890.6971629645951;926.0646746785795;947.7772723409993;1058.0688993685662;25023.873925673026;29726.40547189044;30237.395252094833;31065.8446707765;31844.203894902814;999.3654029691053;1056.1814593689799;1062.8033813744328;1082.9891701083045;1113.521185890075;14743.77884331736;15192.848071519144;15233.695326093417;15579.298931548108;16254;628405;630781;634498;635512;635844;58508.70745646244;58700.4129958695;58710;58730.82538349209;58895.70552147298;1229.5450683247077;1247.0709321555498;1284.1460428814732;1356.4099616666979;1395.0443945897093;24057.249657681456;30205.546916796706;30238.244043899078;30594.694053059186;30860.382792344477;49022.264666029325;49627;49702.751486242334;49703.502964969884;49707.751461242464;521274;523398;523524;523625;524496;25826.61260081138;26065.73934260709;26253.949842006106;26505.60241596339;26704.06535771239;46696.766516167205;46869;47458.05083898234;47529.524704753894;47913.56259312226;37638.81180594189;38549.03627409225;38747.03132421712;39548.604513954495;39964.20071598494;36904.81547592245;39308.410373844985;39482;39873.80063099666;39974.60025399825;31873.043808685776;32123.03630891077;32247.516287256178;32254.83872580622;32382.676173237967;33310.333793324484;33768.09574065577;34318;34512.30975380528;34545.790897318475;19992;22042.559148816614;23527.176548821393;24442.87778561096;25237.74762252354 11:29:03 INFO - PROCESS | 1647 | |2;jquery.html;313.54692469381746;344.67600455571744;362.717080677072;372.49340896380926;399.3689969847641;15611.687766244668;17011.82988170118;20692.586148277063;20803.583928321423;20965.790342096574;20151.992400379997;20656.173753049898;20745.792542074574;21333.466663333424;21559.892200538976;5871.677057761822;5936.881262374761;6061.545384096188;6207.937920620781;6209.348018458065;4215.725977811432;6025.789097381593;6132.417420345082;6133.2026836511195;6142.416470435302;6425.967870160643;6710.83222919426;6717.294684058177;6768.357006084439;6832.077669514627;6577.440917521993;6597.5711578747705;6750.189977202743;6754.628495432749;6762.661866906684;5811.428143488363;5906;5907.084401917709;5910.704464776744;5916.319623243339;25362;25394.746052539424;25490.87254563734;25589.360265993313;25743.356416089562;2118.8622739522034;2379.119725701484;2866.5270230411957;3151.5272709093592;3259.771815972883;10215.540300686487;10411.271211015232;10841.945790271076;12893.775091366306;15628.296726647328;1710.332425884765;1856.7400563921062;1868.4861663042773;1883.8022007689133;1918.5011896906765;121645;125424.45755424476;126644;126695;127790;11135.331880087058;11217.32696038224;11338;11360.715982100513;11515.366654834064;14380.640483987983;14405.19935008124;14409.919256055839;14416;14416.42334306637;8420;8570.571471426403;8580.871286930702;8611.698590549426;8736.344774141964;8547.871781923277;8571.742847714579;8648;8724;8761.912380876238;3716.591174970751;4260.4248426462655;4450.910981780346;4462.709923854939;4555.840545580889;1743.7210046392515;1909.4748944040223;1911.5221194701326;1911.6176764646848;1914.1386376130797;840;850.9149085091441;900.7254734550669;904.674317245791;923.5936188077225;17809.940547440146;21855.065257924176;21870.739837802623;21948.15051529918;21995.780042199694;874.7927859553776;934.3319526538558;941.6892425499568;1020.5101551255439;1033.1734612310122;8990.955045224731;10898.346099234072;11384.260023098472;11455;11489.138314626269;604649;605243;613788;631214;631862;85310;106465.46767266114;135202.591896325;136439;140703;989.4607438945648;1006.2754816532085;1049.522467277387;1049.895010498944;1050.207093644309;18997.335093271668;19848.70226946568;19857.801421985594;19872.403827885188;20081.79918200799;55557;55590.444095560146;56860.57848553819;57338;57454;71121.53223911415;73098.90351644836;78008;83342.58328708318;86928.5653571728;7804.6878124874365;7919.2872641462545;8009.239122283374;8243.175682431709;10118.59525618967;11965.880341196476;12767.106302558732;13238.735225295248;13248.337583120998;13259.86740132625;7594.784834426686;10081.445520496443;11614.88385116138;11670.88329116732;11793.705157371134;7871.291583757489;7878.212178782076;7998.360131189607;8023.318017968538;8136.877946830684;6783.21992970795;7650.273224043707;7825.843483130419;7958.164392738679;8054.355651547745;9794;9997.850032249376;10257.692269231935;11627.88372116268;12010;9656.776615286386;11347.276653603241;11365.977062064454;11684.532618695157;11695.005924496512 11:29:03 INFO - PROCESS | 1647 | |3;mootools.html;1489.836118027017;1528.22060749018;1686.6542358816444;1854.4529363837667;1859.2377125378598;8449.831003379937;8955.05971872953;8985.640574377017;8987.775305617362;8992.505412202334;7869.370450363957;8064.395170362217;8438.32493400528;8619;8737.737867863958;2363.5036642305136;2399.0523743121457;2403.6034054381007;2406.061635961976;2407.3981504623844;2331.1025255276754;2367.206985659806;2367.4199821043867;2374.6675465434855;2380.5119950410212;2357.964630530543;2367.3844800351944;2377.892994815229;2392.6530653055297;2398.95202095958;2042.428120126352;2342.332435255944;2353.1646265575732;2358.6697862299206;2375.881205939696;2335.8948847301913;2353.1058197884695;2358.893849776747;2358.9646155307682;2359.2214569192124;8815.074417186232;8834.293256539462;8940.195382415613;8940.955295223483;8956.328275379372;5222.764975576109;5395.78416863325;5398.460153984609;5400.027994960906;5403.378611459693;3080.2761351082477;3607.3867442534834;3607.4047782115917;3672.926541469182;3690.4833323334756;1083.4745148602922;1093.7593729379523;1093.8031154392206;1105.7346236903165;1119.3395896421068;11796.646100616996;11907.226030308002;11924.70188245292;11933.641990740205;11967.64097077089;4523.88690282739;4655.627549796008;4808.543188397097;4830.468648448667;4841.661083724176;8563.529005904611;9998.650047248311;10009.349392289623;10020.549075291628;10024.448655323882;5364.409914909357;5374.758135883894;5379.462053794589;5379.865503362369;5383.892322153535;4078.469798926121;4412.735235885857;4539.523350048264;4548.340490628841;4942.481039490874;3320.93358132836;3944.2703099926607;3950.525936887592;4010.057636455431;4018.537868145142;1286.6526037969695;1289.2006955687345;1294.9611511654666;1300.959232613905;1304.928228947417;846.7290467050608;856.3705676327894;881.2509367037967;931.6739141300491;980.8083178937625;5913.586048976616;6018.458338749533;6103.511719062553;6108.450239478469;6212.316645169028;954.2080073538946;1008.9596416143272;1015.081351377005;1020.5407566595062;1040.4173662748885;3504.789712617251;3825.885223443301;3932.3315036443873;3980.7014473914574;4044.2518134145275;10541.262111652264;10848.349099054081;10943.616973405893;10945.671629851116;10956.945215273872;10875.945620272007;10910.290831095954;10912.399818009926;10915.126789856793;10924.453777311102;7770.689172433153;8133.633986470623;8169.060558035783;8321.04308004575;8325.83348333042;5953.613015154001;6079.878402432015;6089.421504957022;6145.201123853871;6221.4089661483895;10034.548445319831;10197.54111064989;10223.335483193569;10266.024727650862;10268.435236061789;9439.150476456876;10072.194224462171;10266.178705703674;10327.586896524055;10447.477626118512;6195.597286176384;7045.718171273092;7508.812279692833;7612.1246056704185;7773.92226077732;8340;8347.998240211213;8376.49741015541;8437.662493500191;8475.74572762818;6611.702473388613;7611.809704757426;7618.085829700472;7679.963204967258;7694.769156925301;7424.406047516078;7440.888386674313;7443.925560744323;7503.287187717158;7676.040494938133;6953.304669533006;6967.512274140761;7032.1912980007955;7034.613096279753;7034.929650703428;5046.4196617388;5076.18780995039;5096.337476128079;5162.5869930406225;5248.264974830953;5052;5134.358205224347;5171.276021356938;5198.168293073241;5395.541378982831 11:29:03 INFO - PROCESS | 1647 | |4;prototype.html;280.7080636138416;289.1830578615412;304.69682665747575;306.50958466453665;306.97390721788645;4525.864224073275;4549.658775591827;5244.082285600024;5279.48913972208;5333.786648534059;4543.681942264043;4887.853364399065;4932.802687892489;5130.820421285256;5273.920891186625;3463.7402194835363;4040.2525532776385;4041.1513582147636;4045.1707399983006;4091.6317531422164;3709.3879509880835;3728.7576307540057;3836.405357169643;3860.729748917563;4073.3279008963486;2908.5637154426795;3343.8996830095134;3784.678302344304;4045.130296986147;4055.71609987301;3369.679880411369;3985.3822657488136;4013.8595149169637;4027.013381721497;4032.495938007749;3506.5266189064405;3514.7715398499017;3517.505060349367;3534.7879127252445;3558.5907620623702;4852.684575502581;5075;5098.362704661918;5103.770330335144;5153.6907785532985;4496.830823985754;4585.403897493338;4595.862124136248;4608.953910460886;4654.883627909296;4794.089123066607;5485.314335708036;5493.285872836505;5512.972435137799;5588.2455868458055;3850.7497012694084;3858.6527212550723;3869.7678139311734;3898.4152377143655;3927.8625248116464;5122.462141475092;5136.43499215086;5494.2857428534435;5537.556995440355;5905.616134951258;3312.287858110518;3876.9224615507533;3936.6850651947775;3989.3018721723583;3999.340108882002;3810.3903375459795;4019.2162528307285;4028.395740638869;4032.919341613151;4032.9395059074136;4189.162167566499;4278.272693642089;4530.501644819067;4561.950751327147;4576.427946506687;4957.429895561984;5650.463205995424;5669.6314739541795;5677.347105082886;5682.261306030189;1182.5041322726759;1312.3044786263297;1318.8878945289566;1326.290434617475;1330.2683524061727;674.8460133172331;737.1854101218184;784.9646765895549;853.6116067189414;858.52780970466;4340.414044104069;4516.774468337974;4857.465678775332;5197.506236907488;5219.530242278213;5475.123980163155;5647.29408823897;5703.857403564944;5719.169865642954;5841.860837136803;5074.2895994560795;5187.429382767892;5284.101702710548;5309.796434479647;5310.893782124336;2452.9264122076365;2769.5430254007865;2798.656644810462;3008.849557522115;3010.9698903011126;5230.424653288135;5716.512927114457;5953.374895635982;5955.880882382415;5980.401959803985;4712.065425369855;5609.298837645294;5847.3275573308765;5900.721976567041;5904.232449781501;4745.667803253739;5288.963748956757;5444.8094316698725;5748.223035848945;5867.090600956773;4798.5441383068555;5026.899462010666;5693.572982026448;5751.153179176167;5753.798617048383;5120.692758434506;5207;5369.946300537101;5666.526703057152;5691.26013618227;4823.927641085456;5250.842474725764;5699.829005129853;5796.840631873558;5925.466707996301;3817.942730858984;3824.647841577354;3826.8660596878976;3839.673627741673;3873.6126387361037;5158.871028224325;5441.727913604224;5486.341639003345;5618.410066942912;5718.714064296685;4680.297955306732;4955.72743499111;5261.89476210466;5459.590530710292;5495.450454954473;4453.866384008485;5435.537979271806;5526.485449120721;5582.609217354746;5634.576857167268;5141.791678955517;5411.458854114557;5437.211604317431;5440.809571664972;5447.809326673548;4861.1979023460735;5220.269162317202;5353.2869481765465;5484.369297530835;5507.834764957058;4385.079133381898;5356.116240820221;5389.353277606712;5428.348598168245;5433.646812957145 11:29:03 INFO - PROCESS | 1647 | |5;yui.html;706.4560288577794;719.8812195987663;721.8700633885899;753.5968256982513;785.5836406704442;12994.805077923827;15638;16206.675866482678;16246.51260462185;16254.43109491168;14599.708005839877;14768.261586920638;14781.556553303391;14832.925835370836;14891.627709307246;655.6426747422643;681.0159319782915;681.9761308354208;682.2733788515241;803.7829785957773;672.4653900149368;676.3980057748607;814.4868732698392;837.9120192379804;843.696269343036;880.1242763450376;886.8625363068702;889.2308153447254;893.2139717048988;897.8518544440192;887.9511626860552;891.5453118909338;891.857302831547;892.3619611977467;892.7589550821307;513.4762542206965;659.2780904909117;659.6240143118376;659.7361055577808;659.8251463362213;14072.92963535186;14590.70818583633;14651.267436628126;14680.706385872223;14702.044367116103;14439;14872.62818429537;14874.033187842755;15047.818066380423;15088.54734357971;11994.88005119955;12070.818937715942;12102.334371609559;12124.333161676104;12219.511219551207;3820.6943444524372;3835.17543728097;3876.321643712339;3908.2769687607597;4015.859444919414;125815;127361;127729.36135319357;129962;130353.6964630351;11462.828057579143;11467.426628668532;11473.311601303949;11560.94219528897;12059;23308.184213552446;23339.066437342655;23349.533009339717;23357.7664223359;23396.64905026426;7991.800204994805;8134.593270336578;8230.913407472199;8287.709930152536;8370.656216259456;4415.094905544356;4547.567981041796;4573.5426457354;4722.268048452495;4745.240761478216;3103.8826022631833;3639.6724294813594;3687.9078023049638;3759.5300587426573;3764.8494060237285;668.5854770042507;679.2629996453804;679.7179170644226;690.8341997920463;697.3061803505544;591.7514643849594;602.4607975861579;683.4190937702913;697.100740045346;698.7309885694044;9671.70984870455;9671.93608703042;9713.562889669982;9767;9799.608015679292;633.4077637409038;642.2357394700292;661.454300202335;678.9015592739025;684.8561802021532;4872.880297070786;5171.689698618095;5259.081209928527;5280.709560974184;5366.892662146735;103972.96027039785;106018;123468;154560;172266;110449.34325985117;110987;113054;135907;139110;72895.90656140161;79225.20774792293;108623.45688271508;110819;113046.99999999836;7471.514351567131;9811.754706132404;10135.746606334606;10148.492575371054;10155.593776248867;59749;61417.385826142956;61820.69089654523;61855;62650.37349626445;65732.34267657454;74212.62893685713;76828;93763;94527;13209.339533023118;13513.256770877697;13628.250446225156;13818.65453363674;13889.861101388857;15121.157377685578;15148.697026059197;15179.043835042201;15713.214339283219;15928.044317340997;14067.788983165465;14441.566752997427;14512;14756.63108422298;14990;13855;14139.858601414266;14582.197979110826;14650.780238296222;14734.77897831512;13757.79363309571;13776.380062896993;13929.860701393261;14119.364628591533;14312.57062288133;11382.203245772716;11452.713682158013;11519.136064795006;11577.536898523964;11811.350375729073;9929.3545919515;10647.467626618793;10882.945585272022;11739.530418783153;11760.647180584596 11:29:03 INFO - PROCESS | 1647 | __end_tp_report 11:29:03 INFO - PROCESS | 1647 | __start_cc_report 11:29:03 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,1381 11:29:03 INFO - PROCESS | 1647 | __end_cc_report 11:29:03 INFO - PROCESS | 1647 | __startTimestamp1471890543706__endTimestamp 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | ------- Summary: start ------- 11:29:03 INFO - PROCESS | 1647 | Number of tests: 6 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:5823.24 Median:6067.00 stddev:660.24 (10.9%) stddev-sans-first:619.31 11:29:03 INFO - PROCESS | 1647 | Values: 2836.9 3401.2 3409.2 3418.6 3446.6 5682.2 5770.0 5790.5 5889.6 5896.6 5766.8 5786.6 5825.5 5858.4 5914.9 5231.7 5235.7 5298.2 5329.1 5342.8 5396.6 5401.5 5408.7 5414.6 5419.8 5189.6 5198.5 5218.0 5258.3 5332.9 5329.0 5333.0 5336.4 5345.8 5352.4 5237.4 5271.7 5290.5 5292.8 5337.4 5593.0 5710.4 5809.2 5811.4 5856.6 6277.4 6278.8 6288.4 6294.9 6301.3 6241.7 6290.1 6296.7 6306.6 6316.8 5793.1 5863.0 5956.3 5960.7 6015.2 6443.8 6449.5 6450.3 6459.9 6466.9 6176.2 6203.8 6232.3 6243.4 6250.9 6151.7 6183.0 6234.0 6259.8 6300.3 6238.1 6261.9 6267.8 6269.6 6271.7 6244.0 6252.7 6278.9 6283.2 6288.6 4881.0 4907.9 4937.8 5011.0 5071.7 3808.8 4467.3 4510.2 4542.3 4561.4 5904.0 5953.1 5953.6 5958.3 5962.6 6270.5 6275.6 6286.4 6286.6 6300.7 5653.8 5782.7 5782.8 5828.5 5841.3 5438.5 5480.4 5496.8 5543.9 5545.1 6452.1 6468.7 6473.5 6473.8 6498.4 6198.9 6217.9 6257.6 6263.9 6265.2 6296.4 6339.2 6392.6 6398.5 6463.0 6257.3 6279.4 6289.8 6290.1 6293.8 5993.7 6196.0 6242.5 6253.2 6266.4 6010.7 6095.4 6113.1 6120.9 6233.5 6197.5 6212.5 6216.6 6228.4 6233.0 6044.7 6067.0 6077.5 6080.5 6085.4 5982.3 6075.5 6125.2 6146.1 6226.9 6012.6 6049.7 6063.6 6067.9 6082.1 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:68776.66 Median:4096.77 stddev:149302.86 (3644.4%) stddev-sans-first:149633.61 11:29:03 INFO - PROCESS | 1647 | Values: 469.2 502.2 503.0 519.9 533.3 30597.5 39708.8 40763.4 40815.0 43138.1 42233.8 42521.4 42648.0 42706.0 42735.0 9758.9 9943.4 9947.3 9947.5 9955.4 9773.4 9881.3 9883.0 9898.0 9915.7 9848.3 9899.3 9915.5 9945.9 9951.0 9826.6 9903.7 9908.9 9910.4 9916.3 9820.5 9887.1 9893.0 9902.7 9904.5 33755.0 40394.6 40608.0 40683.8 40879.4 3653.7 4094.4 4099.1 4125.8 4255.7 31045.2 31536.3 31827.5 31944.4 32070.4 3222.2 3326.6 3350.9 3359.2 3432.8 509083.0 510072.4 511348.0 511604.5 521478.0 58020.0 58172.1 58263.8 58296.7 58366.7 61467.0 61506.8 61562.1 61606.7 61612.4 16473.8 16617.4 16621.3 16627.3 16676.0 12806.8 12812.9 12824.2 12830.4 12854.6 12822.7 16111.9 16301.0 16339.9 16493.8 5007.9 5199.4 5984.9 6040.9 6065.2 863.8 890.7 926.1 947.8 1058.1 25023.9 29726.4 30237.4 31065.8 31844.2 999.4 1056.2 1062.8 1083.0 1113.5 14743.8 15192.8 15233.7 15579.3 16254.0 628405.0 630781.0 634498.0 635512.0 635844.0 58508.7 58700.4 58710.0 58730.8 58895.7 1229.5 1247.1 1284.1 1356.4 1395.0 24057.2 30205.5 30238.2 30594.7 30860.4 49022.3 49627.0 49702.8 49703.5 49707.8 521274.0 523398.0 523524.0 523625.0 524496.0 25826.6 26065.7 26253.9 26505.6 26704.1 46696.8 46869.0 47458.1 47529.5 47913.6 37638.8 38549.0 38747.0 39548.6 39964.2 36904.8 39308.4 39482.0 39873.8 39974.6 31873.0 32123.0 32247.5 32254.8 32382.7 33310.3 33768.1 34318.0 34512.3 34545.8 19992.0 22042.6 23527.2 24442.9 25237.7 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:35704.71 Median:3205.65 stddev:102974.00 (3212.3%) stddev-sans-first:103228.58 11:29:03 INFO - PROCESS | 1647 | Values: 313.5 344.7 362.7 372.5 399.4 15611.7 17011.8 20692.6 20803.6 20965.8 20152.0 20656.2 20745.8 21333.5 21559.9 5871.7 5936.9 6061.5 6207.9 6209.3 4215.7 6025.8 6132.4 6133.2 6142.4 6426.0 6710.8 6717.3 6768.4 6832.1 6577.4 6597.6 6750.2 6754.6 6762.7 5811.4 5906.0 5907.1 5910.7 5916.3 25362.0 25394.7 25490.9 25589.4 25743.4 2118.9 2379.1 2866.5 3151.5 3259.8 10215.5 10411.3 10841.9 12893.8 15628.3 1710.3 1856.7 1868.5 1883.8 1918.5 121645.0 125424.5 126644.0 126695.0 127790.0 11135.3 11217.3 11338.0 11360.7 11515.4 14380.6 14405.2 14409.9 14416.0 14416.4 8420.0 8570.6 8580.9 8611.7 8736.3 8547.9 8571.7 8648.0 8724.0 8761.9 3716.6 4260.4 4450.9 4462.7 4555.8 1743.7 1909.5 1911.5 1911.6 1914.1 840.0 850.9 900.7 904.7 923.6 17809.9 21855.1 21870.7 21948.2 21995.8 874.8 934.3 941.7 1020.5 1033.2 8991.0 10898.3 11384.3 11455.0 11489.1 604649.0 605243.0 613788.0 631214.0 631862.0 85310.0 106465.5 135202.6 136439.0 140703.0 989.5 1006.3 1049.5 1049.9 1050.2 18997.3 19848.7 19857.8 19872.4 20081.8 55557.0 55590.4 56860.6 57338.0 57454.0 71121.5 73098.9 78008.0 83342.6 86928.6 7804.7 7919.3 8009.2 8243.2 10118.6 11965.9 12767.1 13238.7 13248.3 13259.9 7594.8 10081.4 11614.9 11670.9 11793.7 7871.3 7878.2 7998.4 8023.3 8136.9 6783.2 7650.3 7825.8 7958.2 8054.4 9794.0 9997.9 10257.7 11627.9 12010.0 9656.8 11347.3 11366.0 11684.5 11695.0 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:5749.86 Median:4601.98 stddev:3227.52 (70.1%) stddev-sans-first:3220.70 11:29:03 INFO - PROCESS | 1647 | Values: 1489.8 1528.2 1686.7 1854.5 1859.2 8449.8 8955.1 8985.6 8987.8 8992.5 7869.4 8064.4 8438.3 8619.0 8737.7 2363.5 2399.1 2403.6 2406.1 2407.4 2331.1 2367.2 2367.4 2374.7 2380.5 2358.0 2367.4 2377.9 2392.7 2399.0 2042.4 2342.3 2353.2 2358.7 2375.9 2335.9 2353.1 2358.9 2359.0 2359.2 8815.1 8834.3 8940.2 8941.0 8956.3 5222.8 5395.8 5398.5 5400.0 5403.4 3080.3 3607.4 3607.4 3672.9 3690.5 1083.5 1093.8 1093.8 1105.7 1119.3 11796.6 11907.2 11924.7 11933.6 11967.6 4523.9 4655.6 4808.5 4830.5 4841.7 8563.5 9998.7 10009.3 10020.5 10024.4 5364.4 5374.8 5379.5 5379.9 5383.9 4078.5 4412.7 4539.5 4548.3 4942.5 3320.9 3944.3 3950.5 4010.1 4018.5 1286.7 1289.2 1295.0 1301.0 1304.9 846.7 856.4 881.3 931.7 980.8 5913.6 6018.5 6103.5 6108.5 6212.3 954.2 1009.0 1015.1 1020.5 1040.4 3504.8 3825.9 3932.3 3980.7 4044.3 10541.3 10848.3 10943.6 10945.7 10956.9 10875.9 10910.3 10912.4 10915.1 10924.5 7770.7 8133.6 8169.1 8321.0 8325.8 5953.6 6079.9 6089.4 6145.2 6221.4 10034.5 10197.5 10223.3 10266.0 10268.4 9439.2 10072.2 10266.2 10327.6 10447.5 6195.6 7045.7 7508.8 7612.1 7773.9 8340.0 8348.0 8376.5 8437.7 8475.7 6611.7 7611.8 7618.1 7680.0 7694.8 7424.4 7440.9 7443.9 7503.3 7676.0 6953.3 6967.5 7032.2 7034.6 7034.9 5046.4 5076.2 5096.3 5162.6 5248.3 5052.0 5134.4 5171.3 5198.2 5395.5 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4459.84 Median:5050.59 stddev:1370.21 (27.1%) stddev-sans-first:1337.67 11:29:03 INFO - PROCESS | 1647 | Values: 280.7 289.2 304.7 306.5 307.0 4525.9 4549.7 5244.1 5279.5 5333.8 4543.7 4887.9 4932.8 5130.8 5273.9 3463.7 4040.3 4041.2 4045.2 4091.6 3709.4 3728.8 3836.4 3860.7 4073.3 2908.6 3343.9 3784.7 4045.1 4055.7 3369.7 3985.4 4013.9 4027.0 4032.5 3506.5 3514.8 3517.5 3534.8 3558.6 4852.7 5075.0 5098.4 5103.8 5153.7 4496.8 4585.4 4595.9 4609.0 4654.9 4794.1 5485.3 5493.3 5513.0 5588.2 3850.7 3858.7 3869.8 3898.4 3927.9 5122.5 5136.4 5494.3 5537.6 5905.6 3312.3 3876.9 3936.7 3989.3 3999.3 3810.4 4019.2 4028.4 4032.9 4032.9 4189.2 4278.3 4530.5 4562.0 4576.4 4957.4 5650.5 5669.6 5677.3 5682.3 1182.5 1312.3 1318.9 1326.3 1330.3 674.8 737.2 785.0 853.6 858.5 4340.4 4516.8 4857.5 5197.5 5219.5 5475.1 5647.3 5703.9 5719.2 5841.9 5074.3 5187.4 5284.1 5309.8 5310.9 2452.9 2769.5 2798.7 3008.8 3011.0 5230.4 5716.5 5953.4 5955.9 5980.4 4712.1 5609.3 5847.3 5900.7 5904.2 4745.7 5289.0 5444.8 5748.2 5867.1 4798.5 5026.9 5693.6 5751.2 5753.8 5120.7 5207.0 5369.9 5666.5 5691.3 4823.9 5250.8 5699.8 5796.8 5925.5 3817.9 3824.6 3826.9 3839.7 3873.6 5158.9 5441.7 5486.3 5618.4 5718.7 4680.3 4955.7 5261.9 5459.6 5495.5 4453.9 5435.5 5526.5 5582.6 5634.6 5141.8 5411.5 5437.2 5440.8 5447.8 4861.2 5220.3 5353.3 5484.4 5507.8 4385.1 5356.1 5389.4 5428.3 5433.6 11:29:03 INFO - PROCESS | 1647 | 11:29:03 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:24283.29 Median:3371.78 stddev:38085.20 (1129.5%) stddev-sans-first:38150.90 11:29:03 INFO - PROCESS | 1647 | Values: 706.5 719.9 721.9 753.6 785.6 12994.8 15638.0 16206.7 16246.5 16254.4 14599.7 14768.3 14781.6 14832.9 14891.6 655.6 681.0 682.0 682.3 803.8 672.5 676.4 814.5 837.9 843.7 880.1 886.9 889.2 893.2 897.9 888.0 891.5 891.9 892.4 892.8 513.5 659.3 659.6 659.7 659.8 14072.9 14590.7 14651.3 14680.7 14702.0 14439.0 14872.6 14874.0 15047.8 15088.5 11994.9 12070.8 12102.3 12124.3 12219.5 3820.7 3835.2 3876.3 3908.3 4015.9 125815.0 127361.0 127729.4 129962.0 130353.7 11462.8 11467.4 11473.3 11560.9 12059.0 23308.2 23339.1 23349.5 23357.8 23396.6 7991.8 8134.6 8230.9 8287.7 8370.7 4415.1 4547.6 4573.5 4722.3 4745.2 3103.9 3639.7 3687.9 3759.5 3764.8 668.6 679.3 679.7 690.8 697.3 591.8 602.5 683.4 697.1 698.7 9671.7 9671.9 9713.6 9767.0 9799.6 633.4 642.2 661.5 678.9 684.9 4872.9 5171.7 5259.1 5280.7 5366.9 103973.0 106018.0 123468.0 154560.0 172266.0 110449.3 110987.0 113054.0 135907.0 139110.0 72895.9 79225.2 108623.5 110819.0 113047.0 7471.5 9811.8 10135.7 10148.5 10155.6 59749.0 61417.4 61820.7 61855.0 62650.4 65732.3 74212.6 76828.0 93763.0 94527.0 13209.3 13513.3 13628.3 13818.7 13889.9 15121.2 15148.7 15179.0 15713.2 15928.0 14067.8 14441.6 14512.0 14756.6 14990.0 13855.0 14139.9 14582.2 14650.8 14734.8 13757.8 13776.4 13929.9 14119.4 14312.6 11382.2 11452.7 11519.1 11577.5 11811.4 9929.4 10647.5 10882.9 11739.5 11760.6 11:29:03 INFO - PROCESS | 1647 | -------- Summary: end -------- 11:29:03 INFO - PROCESS | 1647 | 11:29:04 INFO - TEST-INFO | 1647: exit 0 11:29:04 INFO - TEST-OK | dromaeo_css | took 1117373ms 11:29:04 INFO - TEST-START | kraken 11:29:04 INFO - operating with platform_type : mac_ 11:29:04 INFO - Initialising browser for kraken test... 11:29:04 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpx4uoHz/profile http://localhost:49267/getInfo.html) 11:29:06 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:29:06 INFO - PROCESS | 1656 | colorDepth:24 11:29:06 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:29:06 INFO - PROCESS | 1656 | __metrics 11:29:06 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:29:06 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:29:07 INFO - TEST-INFO | 1656: exit 0 11:29:07 INFO - Browser initialized. 11:29:07 INFO - Running cycle 1/1 for kraken test... 11:29:07 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/T/tmpx4uoHz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:29:19 INFO - PROCESS | 1657 | RSS: Main: 228847616 11:29:19 INFO - PROCESS | 1657 | 11:29:22 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 11:29:23 INFO - PROCESS | 1657 | RSS: Main: 293605376 11:29:23 INFO - PROCESS | 1657 | 11:29:25 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 11:29:26 INFO - PROCESS | 1657 | RSS: Main: 295583744 11:29:26 INFO - PROCESS | 1657 | 11:29:29 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 11:29:30 INFO - PROCESS | 1657 | RSS: Main: 381308928 11:29:30 INFO - PROCESS | 1657 | 11:29:32 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 11:29:33 INFO - PROCESS | 1657 | RSS: Main: 281640960 11:29:33 INFO - PROCESS | 1657 | 11:29:35 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 11:29:35 INFO - PROCESS | 1657 | RSS: Main: 285810688 11:29:35 INFO - PROCESS | 1657 | 11:29:38 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 11:29:39 INFO - PROCESS | 1657 | RSS: Main: 322580480 11:29:39 INFO - PROCESS | 1657 | 11:29:42 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 11:29:42 INFO - PROCESS | 1657 | RSS: Main: 325160960 11:29:42 INFO - PROCESS | 1657 | 11:29:45 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 11:29:46 INFO - PROCESS | 1657 | RSS: Main: 326471680 11:29:46 INFO - PROCESS | 1657 | 11:29:48 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 11:29:48 INFO - PROCESS | 1657 | RSS: Main: 301326336 11:29:48 INFO - PROCESS | 1657 | 11:29:50 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 11:29:51 INFO - PROCESS | 1657 | RSS: Main: 304783360 11:29:51 INFO - PROCESS | 1657 | 11:29:53 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 11:29:54 INFO - PROCESS | 1657 | RSS: Main: 307658752 11:29:54 INFO - PROCESS | 1657 | 11:29:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 11:29:57 INFO - PROCESS | 1657 | RSS: Main: 316141568 11:29:57 INFO - PROCESS | 1657 | 11:30:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 11:30:01 INFO - PROCESS | 1657 | RSS: Main: 318787584 11:30:01 INFO - PROCESS | 1657 | 11:30:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 11:30:03 INFO - PROCESS | 1657 | RSS: Main: 362160128 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | __start_tp_report 11:30:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 11:30:03 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 11:30:03 INFO - PROCESS | 1657 | |i|pagename|runs| 11:30:03 INFO - PROCESS | 1657 | |0;ai-astar;97;112;107;113;114;112;115;113;113;113 11:30:03 INFO - PROCESS | 1657 | |1;audio-beat-detection;146;115;128;162;107;133;126;106;160;107 11:30:03 INFO - PROCESS | 1657 | |2;audio-dft;184;183;182;181;181;183;174;182;184;185 11:30:03 INFO - PROCESS | 1657 | |3;audio-fft;78;76;75;149;59;74;86;97;87;47 11:30:03 INFO - PROCESS | 1657 | |4;audio-oscillator;84;87;133;71;95;99;145;74;69;120 11:30:03 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;86;90;87;89;86;90;87;91;86 11:30:03 INFO - PROCESS | 1657 | |6;imaging-darkroom;95;93;109;150;103;93;94;95;94;94 11:30:03 INFO - PROCESS | 1657 | |7;imaging-desaturate;85;86;87;86;83;80;115;140;122;92 11:30:03 INFO - PROCESS | 1657 | |8;json-parse-financial;55;54;55;56;54;55;54;59;77;81 11:30:03 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;65;65;67;73;75;80;73;69;68;71 11:30:03 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;85;82;85;84;86;87;86;86;86;86 11:30:03 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;137;139;132;138;138;139;136;158;221;135 11:30:03 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;170;166;168;166;165;166;168;167;168;165 11:30:03 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;68;68;68;75;83;84;86;86;69;64 11:30:03 INFO - PROCESS | 1657 | __end_tp_report 11:30:03 INFO - PROCESS | 1657 | __start_cc_report 11:30:03 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2074 11:30:03 INFO - PROCESS | 1657 | __end_cc_report 11:30:03 INFO - PROCESS | 1657 | __startTimestamp1471890603240__endTimestamp 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | ------- Summary: start ------- 11:30:03 INFO - PROCESS | 1657 | Number of tests: 14 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:110.90 Median:113.00 stddev:5.32 (4.7%) stddev-sans-first:2.24 11:30:03 INFO - PROCESS | 1657 | Values: 97.0 112.0 107.0 113.0 114.0 112.0 115.0 113.0 113.0 113.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:129.00 Median:130.50 stddev:21.23 (16.3%) stddev-sans-first:21.61 11:30:03 INFO - PROCESS | 1657 | Values: 146.0 115.0 128.0 162.0 107.0 133.0 126.0 106.0 160.0 107.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:181.90 Median:183.00 stddev:3.07 (1.7%) stddev-sans-first:3.16 11:30:03 INFO - PROCESS | 1657 | Values: 184.0 183.0 182.0 181.0 181.0 183.0 174.0 182.0 184.0 185.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:82.80 Median:77.00 stddev:27.22 (35.3%) stddev-sans-first:28.81 11:30:03 INFO - PROCESS | 1657 | Values: 78.0 76.0 75.0 149.0 59.0 74.0 86.0 97.0 87.0 47.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:97.70 Median:79.00 stddev:26.65 (33.7%) stddev-sans-first:27.80 11:30:03 INFO - PROCESS | 1657 | Values: 84.0 87.0 133.0 71.0 95.0 99.0 145.0 74.0 69.0 120.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:88.10 Median:89.00 stddev:1.91 (2.1%) stddev-sans-first:2.00 11:30:03 INFO - PROCESS | 1657 | Values: 89.0 86.0 90.0 87.0 89.0 86.0 90.0 87.0 91.0 86.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:102.00 Median:94.00 stddev:17.66 (18.8%) stddev-sans-first:18.55 11:30:03 INFO - PROCESS | 1657 | Values: 95.0 93.0 109.0 150.0 103.0 93.0 94.0 95.0 94.0 94.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:97.60 Median:85.50 stddev:20.52 (24.0%) stddev-sans-first:21.25 11:30:03 INFO - PROCESS | 1657 | Values: 85.0 86.0 87.0 86.0 83.0 80.0 115.0 140.0 122.0 92.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:60.00 Median:55.50 stddev:10.17 (18.3%) stddev-sans-first:10.62 11:30:03 INFO - PROCESS | 1657 | Values: 55.0 54.0 55.0 56.0 54.0 55.0 54.0 59.0 77.0 81.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:70.60 Median:72.00 stddev:4.77 (6.6%) stddev-sans-first:4.60 11:30:03 INFO - PROCESS | 1657 | Values: 65.0 65.0 67.0 73.0 75.0 80.0 73.0 69.0 68.0 71.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:86.00 stddev:1.42 (1.6%) stddev-sans-first:1.50 11:30:03 INFO - PROCESS | 1657 | Values: 85.0 82.0 85.0 84.0 86.0 87.0 86.0 86.0 86.0 86.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:147.30 Median:138.50 stddev:26.82 (19.4%) stddev-sans-first:28.19 11:30:03 INFO - PROCESS | 1657 | Values: 137.0 139.0 132.0 138.0 138.0 139.0 136.0 158.0 221.0 135.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.90 Median:167.50 stddev:1.60 (1.0%) stddev-sans-first:1.24 11:30:03 INFO - PROCESS | 1657 | Values: 170.0 166.0 168.0 166.0 165.0 166.0 168.0 167.0 168.0 165.0 11:30:03 INFO - PROCESS | 1657 | 11:30:03 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.10 Median:79.00 stddev:8.76 (11.1%) stddev-sans-first:8.91 11:30:03 INFO - PROCESS | 1657 | Values: 68.0 68.0 68.0 75.0 83.0 84.0 86.0 86.0 69.0 64.0 11:30:03 INFO - PROCESS | 1657 | -------- Summary: end -------- 11:30:03 INFO - PROCESS | 1657 | 11:30:04 INFO - TEST-INFO | 1657: exit 0 11:30:04 INFO - TEST-OK | kraken | took 59773ms 11:30:04 INFO - SUITE-END | took 1177s 11:30:04 INFO - Completed test suite (00:19:37) 11:30:04 INFO - javascript benchmark 11:30:04 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2836.9432611347775, 3401.1667141550324, 3409.198838273006, 3418.572678415198, 3446.5691788526433, 5682.204491371212, 5769.990251705955, 5790.536757059435, 5889.587728858976, 5896.557758168138, 5766.769329226826, 5786.623869448483, 5825.533957283428, 5858.355580886099, 5914.940850591493, 5231.73841307935, 5235.65968212065, 5298.1521907454635, 5329.147336426173, 5342.839714808542, 5396.568274538027, 5401.459854014606, 5408.675479471224, 5414.593905457087, 5419.83740487786, 5189.610779191576, 5198.532132108128, 5217.973910130425, 5258.290130832365, 5332.866678333034, 5329.040772660919, 5333.0, 5336.439673834269, 5345.786168553253, 5352.437994010612, 5237.371515418136, 5271.710055946922, 5290.470952904717, 5292.814751483679, 5337.359516857963, 5593.021221286259, 5710.400407957146, 5809.157672137559, 5811.4479124483105, 5856.619319744203, 6277.403646653606, 6278.843028924268, 6288.402601752826, 6294.874102517924, 6301.338359472281, 6241.687915604201, 6290.056491526262, 6296.748130074791, 6306.621602703853, 6316.778912738032, 5793.073108302651, 5863.0, 5956.255468066492, 5960.731767070492, 6015.157877897098, 6443.774467893695, 6449.5485316027425, 6450.322716114741, 6459.870802583922, 6466.935330646634, 6176.166217560662, 6203.7828675997025, 6232.283287421914, 6243.406876346739, 6250.937490625127, 6151.66165860873, 6183.0, 6233.968830155911, 6259.843503912439, 6300.338464461165, 6238.095476155932, 6261.874762504815, 6267.780627678009, 6269.592476489013, 6271.749130034838, 6244.000959846403, 6252.74989000444, 6278.905816412757, 6283.151774510474, 6288.622682639057, 4880.975595122072, 4907.901841963141, 4937.8271760488215, 5010.974945125251, 5071.746412679425, 3808.847646094125, 4467.30756732707, 4510.233260345749, 4542.273236282179, 4561.384213131251, 5904.0, 5953.077273022688, 5953.553483488756, 5958.255218097738, 5962.552808539377, 6270.529710271748, 6275.560710750295, 6286.402791734778, 6286.622802631765, 6300.653464059429, 5653.830385088454, 5782.739776710058, 5782.7686892523825, 5828.475437210586, 5841.269841269841, 5438.45615438453, 5480.424555421703, 5496.7526461309335, 5543.861403464865, 5545.140503222007, 6452.064450654722, 6468.708908099053, 6473.514486413444, 6473.838154046187, 6498.382653647801, 6198.938010620016, 6217.937820621917, 6257.5932564383165, 6263.874722505616, 6265.248170219603, 6296.370362963667, 6339.2392912850755, 6392.584482008655, 6398.488120950406, 6463.0, 6257.342978987323, 6279.434850863444, 6289.811305660837, 6290.056491526308, 6293.842653933505, 5993.730282137401, 6196.039613859859, 6242.500599952084, 6253.187085678833, 6266.436020758154, 6010.7295171716505, 6095.3904609538695, 6113.113598528277, 6120.908186377297, 6233.470155036691, 6197.5351848610635, 6212.47193988516, 6216.595921265103, 6228.377162283736, 6233.0, 6044.727987240497, 6067.0, 6077.453029227214, 6080.452759251689, 6085.391460853879, 5982.252218472691, 6075.483583895419, 6125.234345706786, 6146.078088286793, 6226.875462490815, 6012.5886701968575, 6049.727762250621, 6063.605865618721, 6067.908981365372, 6082.057281121433], "value": 5778.512265227737, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [469.18830423367575, 502.18896482181265, 502.97736601852915, 519.9185693756988, 533.3093643731368, 30597.541036884435, 39708.80145599272, 40763.388549171716, 40814.97962550934, 43138.137237255316, 42233.78883105588, 42521.36217956725, 42648.0, 42706.0, 42735.0, 9758.85361719573, 9943.403395796275, 9947.253955953296, 9947.502624868765, 9955.352902061379, 9773.364731292444, 9881.258905582074, 9883.0, 9897.950510247474, 9915.702528924145, 9848.261380396463, 9899.257555683316, 9915.504224788769, 9945.850812237823, 9951.0, 9826.55780489876, 9903.702888913343, 9908.900910990942, 9910.405375677483, 9916.305858589902, 9820.459874706889, 9887.060729230712, 9893.0, 9902.653407130716, 9904.504774761304, 33755.0, 40394.59605403938, 40608.0, 40683.77948661545, 40879.38680919789, 3653.7442379033478, 4094.447249621293, 4099.098198396348, 4125.793710314472, 4255.680823938217, 31045.22386940322, 31536.285308005023, 31827.522587161213, 31944.361112777846, 32070.35859282801, 3222.1622378181605, 3326.567546219, 3350.9497357539412, 3359.160209947513, 3432.828358582061, 509083.0, 510072.44963775313, 511348.0, 511604.513616343, 521478.0, 58020.0, 58172.12741808876, 58263.83472330614, 58296.70851645715, 58366.7081664589, 61467.0, 61506.76986460246, 61562.0765688515, 61606.69196653988, 61612.38387616067, 16473.83526164747, 16617.41839035652, 16621.335146594, 16627.25177366997, 16675.999440033636, 12806.80789788154, 12812.93593532045, 12824.23054616726, 12830.358482076046, 12854.55009074678, 12822.743545129046, 16111.919440402722, 16301.0, 16339.918300408423, 16493.752593711102, 5007.924881126786, 5199.376074871039, 5984.9128240994805, 6040.909386359208, 6065.181200537959, 863.8185980944072, 890.6971629645951, 926.0646746785795, 947.7772723409993, 1058.0688993685662, 25023.873925673026, 29726.40547189044, 30237.395252094833, 31065.8446707765, 31844.203894902814, 999.3654029691053, 1056.1814593689799, 1062.8033813744328, 1082.9891701083045, 1113.521185890075, 14743.77884331736, 15192.848071519144, 15233.695326093417, 15579.298931548108, 16254.0, 628405.0, 630781.0, 634498.0, 635512.0, 635844.0, 58508.70745646244, 58700.4129958695, 58710.0, 58730.82538349209, 58895.70552147298, 1229.5450683247077, 1247.0709321555498, 1284.1460428814732, 1356.4099616666979, 1395.0443945897093, 24057.249657681456, 30205.546916796706, 30238.244043899078, 30594.694053059186, 30860.382792344477, 49022.264666029325, 49627.0, 49702.751486242334, 49703.502964969884, 49707.751461242464, 521274.0, 523398.0, 523524.0, 523625.0, 524496.0, 25826.61260081138, 26065.73934260709, 26253.949842006106, 26505.60241596339, 26704.06535771239, 46696.766516167205, 46869.0, 47458.05083898234, 47529.524704753894, 47913.56259312226, 37638.81180594189, 38549.03627409225, 38747.03132421712, 39548.604513954495, 39964.20071598494, 36904.81547592245, 39308.410373844985, 39482.0, 39873.80063099666, 39974.60025399825, 31873.043808685776, 32123.03630891077, 32247.516287256178, 32254.83872580622, 32382.676173237967, 33310.333793324484, 33768.09574065577, 34318.0, 34512.30975380528, 34545.790897318475, 19992.0, 22042.559148816614, 23527.176548821393, 24442.87778561096, 25237.74762252354], "value": 19657.491898227432, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.54692469381746, 344.67600455571744, 362.717080677072, 372.49340896380926, 399.3689969847641, 15611.687766244668, 17011.82988170118, 20692.586148277063, 20803.583928321423, 20965.790342096574, 20151.992400379997, 20656.173753049898, 20745.792542074574, 21333.466663333424, 21559.892200538976, 5871.677057761822, 5936.881262374761, 6061.545384096188, 6207.937920620781, 6209.348018458065, 4215.725977811432, 6025.789097381593, 6132.417420345082, 6133.2026836511195, 6142.416470435302, 6425.967870160643, 6710.83222919426, 6717.294684058177, 6768.357006084439, 6832.077669514627, 6577.440917521993, 6597.5711578747705, 6750.189977202743, 6754.628495432749, 6762.661866906684, 5811.428143488363, 5906.0, 5907.084401917709, 5910.704464776744, 5916.319623243339, 25362.0, 25394.746052539424, 25490.87254563734, 25589.360265993313, 25743.356416089562, 2118.8622739522034, 2379.119725701484, 2866.5270230411957, 3151.5272709093592, 3259.771815972883, 10215.540300686487, 10411.271211015232, 10841.945790271076, 12893.775091366306, 15628.296726647328, 1710.332425884765, 1856.7400563921062, 1868.4861663042773, 1883.8022007689133, 1918.5011896906765, 121645.0, 125424.45755424476, 126644.0, 126695.0, 127790.0, 11135.331880087058, 11217.32696038224, 11338.0, 11360.715982100513, 11515.366654834064, 14380.640483987983, 14405.19935008124, 14409.919256055839, 14416.0, 14416.42334306637, 8420.0, 8570.571471426403, 8580.871286930702, 8611.698590549426, 8736.344774141964, 8547.871781923277, 8571.742847714579, 8648.0, 8724.0, 8761.912380876238, 3716.591174970751, 4260.4248426462655, 4450.910981780346, 4462.709923854939, 4555.840545580889, 1743.7210046392515, 1909.4748944040223, 1911.5221194701326, 1911.6176764646848, 1914.1386376130797, 840.0, 850.9149085091441, 900.7254734550669, 904.674317245791, 923.5936188077225, 17809.940547440146, 21855.065257924176, 21870.739837802623, 21948.15051529918, 21995.780042199694, 874.7927859553776, 934.3319526538558, 941.6892425499568, 1020.5101551255439, 1033.1734612310122, 8990.955045224731, 10898.346099234072, 11384.260023098472, 11455.0, 11489.138314626269, 604649.0, 605243.0, 613788.0, 631214.0, 631862.0, 85310.0, 106465.46767266114, 135202.591896325, 136439.0, 140703.0, 989.4607438945648, 1006.2754816532085, 1049.522467277387, 1049.895010498944, 1050.207093644309, 18997.335093271668, 19848.70226946568, 19857.801421985594, 19872.403827885188, 20081.79918200799, 55557.0, 55590.444095560146, 56860.57848553819, 57338.0, 57454.0, 71121.53223911415, 73098.90351644836, 78008.0, 83342.58328708318, 86928.5653571728, 7804.6878124874365, 7919.2872641462545, 8009.239122283374, 8243.175682431709, 10118.59525618967, 11965.880341196476, 12767.106302558732, 13238.735225295248, 13248.337583120998, 13259.86740132625, 7594.784834426686, 10081.445520496443, 11614.88385116138, 11670.88329116732, 11793.705157371134, 7871.291583757489, 7878.212178782076, 7998.360131189607, 8023.318017968538, 8136.877946830684, 6783.21992970795, 7650.273224043707, 7825.843483130419, 7958.164392738679, 8054.355651547745, 9794.0, 9997.850032249376, 10257.692269231935, 11627.88372116268, 12010.0, 9656.776615286386, 11347.276653603241, 11365.977062064454, 11684.532618695157, 11695.005924496512], "value": 9646.838689191469, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1489.836118027017, 1528.22060749018, 1686.6542358816444, 1854.4529363837667, 1859.2377125378598, 8449.831003379937, 8955.05971872953, 8985.640574377017, 8987.775305617362, 8992.505412202334, 7869.370450363957, 8064.395170362217, 8438.32493400528, 8619.0, 8737.737867863958, 2363.5036642305136, 2399.0523743121457, 2403.6034054381007, 2406.061635961976, 2407.3981504623844, 2331.1025255276754, 2367.206985659806, 2367.4199821043867, 2374.6675465434855, 2380.5119950410212, 2357.964630530543, 2367.3844800351944, 2377.892994815229, 2392.6530653055297, 2398.95202095958, 2042.428120126352, 2342.332435255944, 2353.1646265575732, 2358.6697862299206, 2375.881205939696, 2335.8948847301913, 2353.1058197884695, 2358.893849776747, 2358.9646155307682, 2359.2214569192124, 8815.074417186232, 8834.293256539462, 8940.195382415613, 8940.955295223483, 8956.328275379372, 5222.764975576109, 5395.78416863325, 5398.460153984609, 5400.027994960906, 5403.378611459693, 3080.2761351082477, 3607.3867442534834, 3607.4047782115917, 3672.926541469182, 3690.4833323334756, 1083.4745148602922, 1093.7593729379523, 1093.8031154392206, 1105.7346236903165, 1119.3395896421068, 11796.646100616996, 11907.226030308002, 11924.70188245292, 11933.641990740205, 11967.64097077089, 4523.88690282739, 4655.627549796008, 4808.543188397097, 4830.468648448667, 4841.661083724176, 8563.529005904611, 9998.650047248311, 10009.349392289623, 10020.549075291628, 10024.448655323882, 5364.409914909357, 5374.758135883894, 5379.462053794589, 5379.865503362369, 5383.892322153535, 4078.469798926121, 4412.735235885857, 4539.523350048264, 4548.340490628841, 4942.481039490874, 3320.93358132836, 3944.2703099926607, 3950.525936887592, 4010.057636455431, 4018.537868145142, 1286.6526037969695, 1289.2006955687345, 1294.9611511654666, 1300.959232613905, 1304.928228947417, 846.7290467050608, 856.3705676327894, 881.2509367037967, 931.6739141300491, 980.8083178937625, 5913.586048976616, 6018.458338749533, 6103.511719062553, 6108.450239478469, 6212.316645169028, 954.2080073538946, 1008.9596416143272, 1015.081351377005, 1020.5407566595062, 1040.4173662748885, 3504.789712617251, 3825.885223443301, 3932.3315036443873, 3980.7014473914574, 4044.2518134145275, 10541.262111652264, 10848.349099054081, 10943.616973405893, 10945.671629851116, 10956.945215273872, 10875.945620272007, 10910.290831095954, 10912.399818009926, 10915.126789856793, 10924.453777311102, 7770.689172433153, 8133.633986470623, 8169.060558035783, 8321.04308004575, 8325.83348333042, 5953.613015154001, 6079.878402432015, 6089.421504957022, 6145.201123853871, 6221.4089661483895, 10034.548445319831, 10197.54111064989, 10223.335483193569, 10266.024727650862, 10268.435236061789, 9439.150476456876, 10072.194224462171, 10266.178705703674, 10327.586896524055, 10447.477626118512, 6195.597286176384, 7045.718171273092, 7508.812279692833, 7612.1246056704185, 7773.92226077732, 8340.0, 8347.998240211213, 8376.49741015541, 8437.662493500191, 8475.74572762818, 6611.702473388613, 7611.809704757426, 7618.085829700472, 7679.963204967258, 7694.769156925301, 7424.406047516078, 7440.888386674313, 7443.925560744323, 7503.287187717158, 7676.040494938133, 6953.304669533006, 6967.512274140761, 7032.1912980007955, 7034.613096279753, 7034.929650703428, 5046.4196617388, 5076.18780995039, 5096.337476128079, 5162.5869930406225, 5248.264974830953, 5052.0, 5134.358205224347, 5171.276021356938, 5198.168293073241, 5395.541378982831], "value": 4628.7140518598835, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [280.7080636138416, 289.1830578615412, 304.69682665747575, 306.50958466453665, 306.97390721788645, 4525.864224073275, 4549.658775591827, 5244.082285600024, 5279.48913972208, 5333.786648534059, 4543.681942264043, 4887.853364399065, 4932.802687892489, 5130.820421285256, 5273.920891186625, 3463.7402194835363, 4040.2525532776385, 4041.1513582147636, 4045.1707399983006, 4091.6317531422164, 3709.3879509880835, 3728.7576307540057, 3836.405357169643, 3860.729748917563, 4073.3279008963486, 2908.5637154426795, 3343.8996830095134, 3784.678302344304, 4045.130296986147, 4055.71609987301, 3369.679880411369, 3985.3822657488136, 4013.8595149169637, 4027.013381721497, 4032.495938007749, 3506.5266189064405, 3514.7715398499017, 3517.505060349367, 3534.7879127252445, 3558.5907620623702, 4852.684575502581, 5075.0, 5098.362704661918, 5103.770330335144, 5153.6907785532985, 4496.830823985754, 4585.403897493338, 4595.862124136248, 4608.953910460886, 4654.883627909296, 4794.089123066607, 5485.314335708036, 5493.285872836505, 5512.972435137799, 5588.2455868458055, 3850.7497012694084, 3858.6527212550723, 3869.7678139311734, 3898.4152377143655, 3927.8625248116464, 5122.462141475092, 5136.43499215086, 5494.2857428534435, 5537.556995440355, 5905.616134951258, 3312.287858110518, 3876.9224615507533, 3936.6850651947775, 3989.3018721723583, 3999.340108882002, 3810.3903375459795, 4019.2162528307285, 4028.395740638869, 4032.919341613151, 4032.9395059074136, 4189.162167566499, 4278.272693642089, 4530.501644819067, 4561.950751327147, 4576.427946506687, 4957.429895561984, 5650.463205995424, 5669.6314739541795, 5677.347105082886, 5682.261306030189, 1182.5041322726759, 1312.3044786263297, 1318.8878945289566, 1326.290434617475, 1330.2683524061727, 674.8460133172331, 737.1854101218184, 784.9646765895549, 853.6116067189414, 858.52780970466, 4340.414044104069, 4516.774468337974, 4857.465678775332, 5197.506236907488, 5219.530242278213, 5475.123980163155, 5647.29408823897, 5703.857403564944, 5719.169865642954, 5841.860837136803, 5074.2895994560795, 5187.429382767892, 5284.101702710548, 5309.796434479647, 5310.893782124336, 2452.9264122076365, 2769.5430254007865, 2798.656644810462, 3008.849557522115, 3010.9698903011126, 5230.424653288135, 5716.512927114457, 5953.374895635982, 5955.880882382415, 5980.401959803985, 4712.065425369855, 5609.298837645294, 5847.3275573308765, 5900.721976567041, 5904.232449781501, 4745.667803253739, 5288.963748956757, 5444.8094316698725, 5748.223035848945, 5867.090600956773, 4798.5441383068555, 5026.899462010666, 5693.572982026448, 5751.153179176167, 5753.798617048383, 5120.692758434506, 5207.0, 5369.946300537101, 5666.526703057152, 5691.26013618227, 4823.927641085456, 5250.842474725764, 5699.829005129853, 5796.840631873558, 5925.466707996301, 3817.942730858984, 3824.647841577354, 3826.8660596878976, 3839.673627741673, 3873.6126387361037, 5158.871028224325, 5441.727913604224, 5486.341639003345, 5618.410066942912, 5718.714064296685, 4680.297955306732, 4955.72743499111, 5261.89476210466, 5459.590530710292, 5495.450454954473, 4453.866384008485, 5435.537979271806, 5526.485449120721, 5582.609217354746, 5634.576857167268, 5141.791678955517, 5411.458854114557, 5437.211604317431, 5440.809571664972, 5447.809326673548, 4861.1979023460735, 5220.269162317202, 5353.2869481765465, 5484.369297530835, 5507.834764957058, 4385.079133381898, 5356.116240820221, 5389.353277606712, 5428.348598168245, 5433.646812957145], "value": 4013.6433137482013, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [706.4560288577794, 719.8812195987663, 721.8700633885899, 753.5968256982513, 785.5836406704442, 12994.805077923827, 15638.0, 16206.675866482678, 16246.51260462185, 16254.43109491168, 14599.708005839877, 14768.261586920638, 14781.556553303391, 14832.925835370836, 14891.627709307246, 655.6426747422643, 681.0159319782915, 681.9761308354208, 682.2733788515241, 803.7829785957773, 672.4653900149368, 676.3980057748607, 814.4868732698392, 837.9120192379804, 843.696269343036, 880.1242763450376, 886.8625363068702, 889.2308153447254, 893.2139717048988, 897.8518544440192, 887.9511626860552, 891.5453118909338, 891.857302831547, 892.3619611977467, 892.7589550821307, 513.4762542206965, 659.2780904909117, 659.6240143118376, 659.7361055577808, 659.8251463362213, 14072.92963535186, 14590.70818583633, 14651.267436628126, 14680.706385872223, 14702.044367116103, 14439.0, 14872.62818429537, 14874.033187842755, 15047.818066380423, 15088.54734357971, 11994.88005119955, 12070.818937715942, 12102.334371609559, 12124.333161676104, 12219.511219551207, 3820.6943444524372, 3835.17543728097, 3876.321643712339, 3908.2769687607597, 4015.859444919414, 125815.0, 127361.0, 127729.36135319357, 129962.0, 130353.6964630351, 11462.828057579143, 11467.426628668532, 11473.311601303949, 11560.94219528897, 12059.0, 23308.184213552446, 23339.066437342655, 23349.533009339717, 23357.7664223359, 23396.64905026426, 7991.800204994805, 8134.593270336578, 8230.913407472199, 8287.709930152536, 8370.656216259456, 4415.094905544356, 4547.567981041796, 4573.5426457354, 4722.268048452495, 4745.240761478216, 3103.8826022631833, 3639.6724294813594, 3687.9078023049638, 3759.5300587426573, 3764.8494060237285, 668.5854770042507, 679.2629996453804, 679.7179170644226, 690.8341997920463, 697.3061803505544, 591.7514643849594, 602.4607975861579, 683.4190937702913, 697.100740045346, 698.7309885694044, 9671.70984870455, 9671.93608703042, 9713.562889669982, 9767.0, 9799.608015679292, 633.4077637409038, 642.2357394700292, 661.454300202335, 678.9015592739025, 684.8561802021532, 4872.880297070786, 5171.689698618095, 5259.081209928527, 5280.709560974184, 5366.892662146735, 103972.96027039785, 106018.0, 123468.0, 154560.0, 172266.0, 110449.34325985117, 110987.0, 113054.0, 135907.0, 139110.0, 72895.90656140161, 79225.20774792293, 108623.45688271508, 110819.0, 113046.99999999836, 7471.514351567131, 9811.754706132404, 10135.746606334606, 10148.492575371054, 10155.593776248867, 59749.0, 61417.385826142956, 61820.69089654523, 61855.0, 62650.37349626445, 65732.34267657454, 74212.62893685713, 76828.0, 93763.0, 94527.0, 13209.339533023118, 13513.256770877697, 13628.250446225156, 13818.65453363674, 13889.861101388857, 15121.157377685578, 15148.697026059197, 15179.043835042201, 15713.214339283219, 15928.044317340997, 14067.788983165465, 14441.566752997427, 14512.0, 14756.63108422298, 14990.0, 13855.0, 14139.858601414266, 14582.197979110826, 14650.780238296222, 14734.77897831512, 13757.79363309571, 13776.380062896993, 13929.860701393261, 14119.364628591533, 14312.57062288133, 11382.203245772716, 11452.713682158013, 11519.136064795006, 11577.536898523964, 11811.350375729073, 9929.3545919515, 10647.467626618793, 10882.945585272022, 11739.530418783153, 11760.647180584596], "value": 7881.508268266335, "unit": "score"}], "name": "dromaeo_css", "value": 7371.636664452606, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 112.0, 107.0, 113.0, 114.0, 112.0, 115.0, 113.0, 113.0, 113.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 115.0, 128.0, 162.0, 107.0, 133.0, 126.0, 106.0, 160.0, 107.0], "value": 129.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 182.0, 181.0, 181.0, 183.0, 174.0, 182.0, 184.0, 185.0], "value": 181.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 76.0, 75.0, 149.0, 59.0, 74.0, 86.0, 97.0, 87.0, 47.0], "value": 82.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 133.0, 71.0, 95.0, 99.0, 145.0, 74.0, 69.0, 120.0], "value": 97.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 90.0, 87.0, 89.0, 86.0, 90.0, 87.0, 91.0, 86.0], "value": 88.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 93.0, 109.0, 150.0, 103.0, 93.0, 94.0, 95.0, 94.0, 94.0], "value": 102.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 87.0, 86.0, 83.0, 80.0, 115.0, 140.0, 122.0, 92.0], "value": 97.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 54.0, 55.0, 56.0, 54.0, 55.0, 54.0, 59.0, 77.0, 81.0], "value": 60.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 67.0, 73.0, 75.0, 80.0, 73.0, 69.0, 68.0, 71.0], "value": 70.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 82.0, 85.0, 84.0, 86.0, 87.0, 86.0, 86.0, 86.0, 86.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 132.0, 138.0, 138.0, 139.0, 136.0, 158.0, 221.0, 135.0], "value": 147.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 166.0, 168.0, 166.0, 165.0, 166.0, 168.0, 167.0, 168.0, 165.0], "value": 166.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 75.0, 83.0, 84.0, 86.0, 86.0, 69.0, 64.0], "value": 75.1, "unit": "score"}], "name": "kraken", "value": 1495.2, "alertThreshold": 2.0}]} 11:30:04 INFO - Return code: 0 11:30:04 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 11:30:04 INFO - # TBPL SUCCESS # 11:30:04 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:30:04 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:30:04 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:30:04 INFO - 2016-08-22 11:30:04.551 screenresolution[1662:9713] starting screenresolution argv=screenresolution get 11:30:04 INFO - 2016-08-22 11:30:04.554 screenresolution[1662:9713] Display 0: 1600x1200x32@60 11:30:04 INFO - 2016-08-22 11:30:04.560 screenresolution[1663:9716] starting screenresolution argv=screenresolution list 11:30:04 INFO - Available Modes on Display 0 11:30:04 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:30:04 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:30:04 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:30:04 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:30:04 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:30:04 INFO - Intel Iris: 11:30:04 INFO - Chipset Model: Intel Iris 11:30:04 INFO - Type: GPU 11:30:04 INFO - Bus: Built-In 11:30:04 INFO - VRAM (Dynamic, Max): 1536 MB 11:30:04 INFO - Vendor: Intel (0x8086) 11:30:04 INFO - Device ID: 0x0a2e 11:30:04 INFO - Revision ID: 0x0009 11:30:04 INFO - Displays: 11:30:04 INFO - Display: 11:30:04 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:30:04 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:30:04 INFO - Display Serial Number: 1600x1200 60 11:30:04 INFO - Main Display: Yes 11:30:04 INFO - Mirror: Off 11:30:04 INFO - Online: Yes 11:30:04 INFO - Rotation: Supported 11:30:04 INFO - Return code: 0 11:30:04 INFO - Running post-action listener: _resource_record_post_action 11:30:04 INFO - [mozharness: 2016-08-22 18:30:04.795724Z] Finished run-tests step (success) 11:30:04 INFO - Running post-run listener: _resource_record_post_run 11:30:04 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 36992000; Write bytes: 703695360; Read time: 236; Write time: 1931 11:30:04 INFO - TinderboxPrint: CPU usage
53.4% 11:30:04 INFO - TinderboxPrint: I/O read bytes / time
36,992,000 / 236 11:30:04 INFO - TinderboxPrint: I/O write bytes / time
703,695,360 / 1,931 11:30:04 INFO - TinderboxPrint: CPU idle
2,258.1 (46.6%) 11:30:04 INFO - TinderboxPrint: CPU system
967.8 (20.0%) 11:30:04 INFO - TinderboxPrint: CPU user
1,623.9 (33.5%) 11:30:04 INFO - TinderboxPrint: Swap in / out
443,449,344 / 0 11:30:04 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397730304; Write bytes: 391583232; Read time: 29568; Write time: 762 11:30:04 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 34980864; Write bytes: 294159360; Read time: 218; Write time: 1122 11:30:05 INFO - Running post-run listener: _upload_blobber_files 11:30:05 INFO - Blob upload gear active. 11:30:05 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 11:30:05 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 11:30:05 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', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 11:30:05 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 Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 11:30:06 INFO - (blobuploader) - INFO - Open directory for files ... 11:30:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 11:30:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 11:30:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:08 INFO - (blobuploader) - INFO - Done attempting. 11:30:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 11:30:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 11:30:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:09 INFO - (blobuploader) - INFO - Done attempting. 11:30:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 11:30:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 11:30:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 11:30:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 11:30:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 11:30:13 INFO - (blobuploader) - INFO - Done attempting. 11:30:13 INFO - (blobuploader) - INFO - Iteration through files over. 11:30:13 INFO - Return code: 0 11:30:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 11:30:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 11:30:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c00a5eeae746437d68dbc2d52e976a1877e10e43ca795ff0a7d949cc2b057b584d35e438f8afec7bee61136befb737e6bed5034f56d5c6eb4203de6c50b27277", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/247d4d1814cff741b768ebc3ad16640c13da9b77f8f68c9f78df9e7d3af5fec2aecd43f7e83c2dcfe598164760ca2fe3cf20f86e773ffe56c9b2eda7c2d99d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be8374c6081dfc4cd8ec96ef836f5729dd8890e53c10a0c17241ad4f392e5e474a83cf00752d545351b82246d3037571a68586234fbbae4a962874e145b3385e"} 11:30:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 11:30:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 11:30:13 INFO - Contents: 11:30:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c00a5eeae746437d68dbc2d52e976a1877e10e43ca795ff0a7d949cc2b057b584d35e438f8afec7bee61136befb737e6bed5034f56d5c6eb4203de6c50b27277", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/247d4d1814cff741b768ebc3ad16640c13da9b77f8f68c9f78df9e7d3af5fec2aecd43f7e83c2dcfe598164760ca2fe3cf20f86e773ffe56c9b2eda7c2d99d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be8374c6081dfc4cd8ec96ef836f5729dd8890e53c10a0c17241ad4f392e5e474a83cf00752d545351b82246d3037571a68586234fbbae4a962874e145b3385e"} 11:30:13 INFO - Running post-run listener: copy_logs_to_upload_dir 11:30:13 INFO - Copying logs to upload dir... 11:30:13 INFO - mkdir: /builds/slave/test/build/upload/logs 11:30:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.060531 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-22 11:30:13.280300) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 11:30:13.285943) ========= 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.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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/Fx-Team/sha512/c00a5eeae746437d68dbc2d52e976a1877e10e43ca795ff0a7d949cc2b057b584d35e438f8afec7bee61136befb737e6bed5034f56d5c6eb4203de6c50b27277", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/247d4d1814cff741b768ebc3ad16640c13da9b77f8f68c9f78df9e7d3af5fec2aecd43f7e83c2dcfe598164760ca2fe3cf20f86e773ffe56c9b2eda7c2d99d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be8374c6081dfc4cd8ec96ef836f5729dd8890e53c10a0c17241ad4f392e5e474a83cf00752d545351b82246d3037571a68586234fbbae4a962874e145b3385e"} build_url:https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011136 build_url: 'https://queue.taskcluster.net/v1/task/Xza_Y9djTiKoJvrQTSkyyg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c00a5eeae746437d68dbc2d52e976a1877e10e43ca795ff0a7d949cc2b057b584d35e438f8afec7bee61136befb737e6bed5034f56d5c6eb4203de6c50b27277", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/247d4d1814cff741b768ebc3ad16640c13da9b77f8f68c9f78df9e7d3af5fec2aecd43f7e83c2dcfe598164760ca2fe3cf20f86e773ffe56c9b2eda7c2d99d92", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be8374c6081dfc4cd8ec96ef836f5729dd8890e53c10a0c17241ad4f392e5e474a83cf00752d545351b82246d3037571a68586234fbbae4a962874e145b3385e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-22 11:30:13.319167) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:30:13.319488) ========= 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.qRo2SbPcC4/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.y4FD9mQBed/Listeners TMPDIR=/var/folders/jq/hg7w4nn92rb7mz49hy3qd8vw00000w/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.005215 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-22 11:30:13.368899) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 11:30:13.369257) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-22 11:30:13.369599) ========= ========= Total master_lag: 0.17 =========