builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0056 starttime: 1471571205.77 results: success (0) buildid: 20160818163358 builduid: d6e5e62a7203429ca8850e6861aa6aa4 revision: abac53d4d2476c4ab1816844091989456b8993bf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:45.774102) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:45.774559) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:45.774843) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:45.868734) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:45.869145) ========= 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.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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-18 18:46:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 34.3M=0s 2016-08-18 18:46:46 (34.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.547910 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:46.439956) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:46.440308) ========= 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.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.021184 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:46.488716) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 18:46:46.489128) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev abac53d4d2476c4ab1816844091989456b8993bf --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 abac53d4d2476c4ab1816844091989456b8993bf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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-18 18:46:46,569 truncating revision to first 12 chars 2016-08-18 18:46:46,569 Setting DEBUG logging. 2016-08-18 18:46:46,569 attempt 1/10 2016-08-18 18:46:46,569 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/abac53d4d247?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-18 18:46:47,975 unpacking tar archive at: fx-team-abac53d4d247/testing/mozharness/ program finished with exit code 0 elapsedTime=1.679092 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-18 18:46:48.190238) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:48.190515) ========= script_repo_revision: abac53d4d2476c4ab1816844091989456b8993bf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:48.190900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:48.191191) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-18 18:46:48.209267) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 18:46:48.209552) ========= /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.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:46:48 INFO - MultiFileLogger online at 20160818 18:46:48 in /builds/slave/test 18:46:48 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 18:46:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:46:48 INFO - {'append_to_log': False, 18:46:48 INFO - 'base_work_dir': '/builds/slave/test', 18:46:48 INFO - 'blob_upload_branch': 'Fx-Team', 18:46:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:46:48 INFO - 'branch': 'Fx-Team', 18:46:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:46:48 INFO - 'config_files': ('talos/mac_config.py',), 18:46:48 INFO - 'default_actions': ('clobber', 18:46:48 INFO - 'read-buildbot-config', 18:46:48 INFO - 'download-and-extract', 18:46:48 INFO - 'populate-webroot', 18:46:48 INFO - 'create-virtualenv', 18:46:48 INFO - 'install', 18:46:48 INFO - 'run-tests'), 18:46:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:46:48 INFO - 'download_minidump_stackwalk': True, 18:46:48 INFO - 'download_symbols': 'ondemand', 18:46:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:46:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:46:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:46:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:46:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:46:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:46:48 INFO - 'installer_path': 'installer.exe', 18:46:48 INFO - 'log_level': 'info', 18:46:48 INFO - 'log_name': 'talos', 18:46:48 INFO - 'log_to_console': True, 18:46:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:46:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:46:48 INFO - 'opt_config_files': (), 18:46:48 INFO - 'pip_index': False, 18:46:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:48 INFO - 'cmd': ('bash', 18:46:48 INFO - '-c', 18:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:48 INFO - 'enabled': True, 18:46:48 INFO - 'halt_on_failure': False, 18:46:48 INFO - 'name': 'check_screen_resolution'},), 18:46:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:46:48 INFO - 'cmd': ('bash', 18:46:48 INFO - '-c', 18:46:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:46:48 INFO - 'enabled': True, 18:46:48 INFO - 'halt_on_failure': False, 18:46:48 INFO - 'name': 'check_screen_resolution'},), 18:46:48 INFO - 'run_cmd_checks_enabled': True, 18:46:48 INFO - 'sps_profile': False, 18:46:48 INFO - 'sps_profile_interval': 0, 18:46:48 INFO - 'suite': 'dromaeojs', 18:46:48 INFO - 'system_bits': '32', 18:46:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:46:48 INFO - 'title': 't-yosemite-r7-0056', 18:46:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:46:48 INFO - 'use_talos_json': True, 18:46:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:46:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:46:48 INFO - 'work_dir': 'build'} 18:46:48 INFO - [mozharness: 2016-08-19 01:46:48.408922Z] Running clobber step. 18:46:48 INFO - Running pre-action listener: _resource_record_pre_action 18:46:48 INFO - Running main action method: clobber 18:46:48 INFO - rmtree: /builds/slave/test/build 18:46:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:46:49 INFO - Running post-action listener: _resource_record_post_action 18:46:49 INFO - [mozharness: 2016-08-19 01:46:49.956093Z] Finished clobber step (success) 18:46:49 INFO - [mozharness: 2016-08-19 01:46:49.956307Z] Running read-buildbot-config step. 18:46:49 INFO - Running pre-action listener: _resource_record_pre_action 18:46:49 INFO - Running main action method: read_buildbot_config 18:46:49 INFO - Using buildbot properties: 18:46:49 INFO - { 18:46:49 INFO - "project": "", 18:46:49 INFO - "product": "firefox", 18:46:49 INFO - "script_repo_revision": "production", 18:46:49 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 18:46:49 INFO - "repository": "", 18:46:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 18:46:49 INFO - "buildid": "20160818163358", 18:46:49 INFO - "pgo_build": "False", 18:46:49 INFO - "basedir": "/builds/slave/test", 18:46:49 INFO - "buildnumber": 129, 18:46:49 INFO - "slavename": "t-yosemite-r7-0056", 18:46:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 18:46:49 INFO - "platform": "yosemite_r7", 18:46:49 INFO - "scheduler": "tests-fx-team-macosx64-talos", 18:46:49 INFO - "branch": "fx-team", 18:46:49 INFO - "repo_path": "integration/fx-team", 18:46:49 INFO - "revision": "abac53d4d2476c4ab1816844091989456b8993bf", 18:46:49 INFO - "stage_platform": "macosx64", 18:46:49 INFO - "builduid": "d6e5e62a7203429ca8850e6861aa6aa4", 18:46:49 INFO - "slavebuilddir": "test" 18:46:49 INFO - } 18:46:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 18:46:49 INFO - Running post-action listener: _resource_record_post_action 18:46:49 INFO - [mozharness: 2016-08-19 01:46:49.961881Z] Finished read-buildbot-config step (success) 18:46:49 INFO - [mozharness: 2016-08-19 01:46:49.962069Z] Running download-and-extract step. 18:46:49 INFO - Running pre-action listener: _resource_record_pre_action 18:46:49 INFO - Running main action method: download_and_extract 18:46:49 INFO - mkdir: /builds/slave/test/build/tests 18:46:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:46:49 INFO - trying https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 18:46:49 INFO - Downloading https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:46:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:46:51 INFO - Downloaded 1199 bytes. 18:46:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 18:46:51 INFO - Using the following test package requirements: 18:46:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:46:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 18:46:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 18:46:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'jsshell-mac.zip'], 18:46:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 18:46:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 18:46:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 18:46:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 18:46:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 18:46:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 18:46:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 18:46:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:46: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')]} 18:46:51 INFO - trying https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:46:51 INFO - Downloading https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:46:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:46:52 INFO - Downloaded 16339559 bytes. 18:46:52 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:46:59 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 18:46: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')]} 18:46:59 INFO - trying https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 18:46:59 INFO - Downloading https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:46:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:47:00 INFO - Downloaded 16339559 bytes. 18:47:00 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 18:47:07 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')]} 18:47:07 INFO - trying https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 18:47:07 INFO - Downloading https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:47:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:47:07 INFO - Downloaded 14413981 bytes. 18:47:07 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 18:47: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')]} 18:47:08 INFO - trying https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:08 INFO - Downloading https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 18:47:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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 18:47:11 INFO - Downloaded 111247322 bytes. 18:47:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:11 INFO - mkdir: /builds/slave/test/properties 18:47:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:47:11 INFO - Writing to file /builds/slave/test/properties/build_url 18:47:11 INFO - Contents: 18:47:11 INFO - build_url:https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 18:47:11 INFO - Running post-action listener: _resource_record_post_action 18:47:11 INFO - Running post-action listener: set_extra_try_arguments 18:47:11 INFO - [mozharness: 2016-08-19 01:47:11.777959Z] Finished download-and-extract step (success) 18:47:11 INFO - [mozharness: 2016-08-19 01:47:11.778114Z] Running populate-webroot step. 18:47:11 INFO - Running pre-action listener: _resource_record_pre_action 18:47:11 INFO - Running main action method: populate_webroot 18:47:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:47:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:47:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:47:11 INFO - '--tppagecycles', 18:47:11 INFO - '7'], 18:47:11 INFO - 'tests': ['tsvgm']}}, 18:47:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:47:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:47:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:47:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:47:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:47:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['damp', 'tps']}, 18:47:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'tests': ['damp', 'tps']}, 18:47:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['dromaeo_dom']}, 18:47:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:47:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['basic_compositor_video']}, 18:47:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 18:47:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'other-e10s': {'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['a11yr', 18:47:11 INFO - 'ts_paint', 18:47:11 INFO - 'tpaint', 18:47:11 INFO - 'sessionrestore', 18:47:11 INFO - 'sessionrestore_no_auto_restore', 18:47:11 INFO - 'tabpaint']}, 18:47:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['tsvgx', 18:47:11 INFO - 'tsvgr_opacity', 18:47:11 INFO - 'tart', 18:47:11 INFO - 'tscrollx', 18:47:11 INFO - 'cart']}, 18:47:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:47:11 INFO - 'tsvgr_opacity', 18:47:11 INFO - 'tart', 18:47:11 INFO - 'tscrollx', 18:47:11 INFO - 'cart']}, 18:47:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'talos_options': ['--disable-e10s'], 18:47:11 INFO - 'tests': ['tp5o']}, 18:47:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'tests': ['tp5o']}, 18:47:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'talos_options': ['--disable-e10s', 18:47:11 INFO - '--xperf_path', 18:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:11 INFO - 'tests': ['tp5n']}, 18:47:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 18:47:11 INFO - 'talos_options': ['--xperf_path', 18:47:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:47:11 INFO - 'tests': ['tp5n']}}, 18:47:11 INFO - 'talos.zip': {'path': '', 18:47:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:47:11 INFO - Running post-action listener: _resource_record_post_action 18:47:11 INFO - [mozharness: 2016-08-19 01:47:11.796988Z] Finished populate-webroot step (success) 18:47:11 INFO - [mozharness: 2016-08-19 01:47:11.797123Z] Running create-virtualenv step. 18:47:11 INFO - Running pre-action listener: _resource_record_pre_action 18:47:11 INFO - Running main action method: create_virtualenv 18:47:11 INFO - Creating virtualenv /builds/slave/test/build/venv 18:47:11 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 18:47:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:47:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:47:11 INFO - Using real prefix '/tools/python27' 18:47:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:47:12 INFO - Installing distribute.............................................................................................................................................................................................done. 18:47:15 INFO - Installing pip.................done. 18:47:15 INFO - Return code: 0 18:47:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 18:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 18:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:15 INFO - 'HOME': '/Users/cltbld', 18:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:15 INFO - 'LOGNAME': 'cltbld', 18:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:15 INFO - 'MOZ_NO_REMOTE': '1', 18:47:15 INFO - 'NO_EM_RESTART': '1', 18:47:15 INFO - 'PAGER': '/bin/cat', 18:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:15 INFO - 'PWD': '/builds/slave/test', 18:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:15 INFO - 'SHELL': '/bin/bash', 18:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:15 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:15 INFO - 'USER': 'cltbld', 18:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:15 INFO - 'XPC_FLAGS': '0x0', 18:47:15 INFO - 'XPC_SERVICE_NAME': '0', 18:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:15 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 18:47:15 INFO - Cleaning up... 18:47:15 INFO - Return code: 0 18:47:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 18:47:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 18:47:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:15 INFO - 'HOME': '/Users/cltbld', 18:47:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:15 INFO - 'LOGNAME': 'cltbld', 18:47:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:15 INFO - 'MOZ_NO_REMOTE': '1', 18:47:15 INFO - 'NO_EM_RESTART': '1', 18:47:15 INFO - 'PAGER': '/bin/cat', 18:47:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:15 INFO - 'PWD': '/builds/slave/test', 18:47:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:15 INFO - 'SHELL': '/bin/bash', 18:47:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:15 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:15 INFO - 'USER': 'cltbld', 18:47:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:15 INFO - 'XPC_FLAGS': '0x0', 18:47:15 INFO - 'XPC_SERVICE_NAME': '0', 18:47:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:16 INFO - Downloading/unpacking psutil>=3.1.1 18:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:16 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:16 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:47:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:19 INFO - Installing collected packages: psutil 18:47:19 INFO - Running setup.py install for psutil 18:47:19 INFO - building 'psutil._psutil_osx' extension 18:47:19 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 18:47:19 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 18:47:19 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 18:47:19 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 18:47:20 INFO - building 'psutil._psutil_posix' extension 18:47:20 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 18:47:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:47:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:47:20 INFO - ^ 18:47:20 INFO - 1 warning generated. 18:47:20 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 18:47:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:47:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:47:20 INFO - Successfully installed psutil 18:47:20 INFO - Cleaning up... 18:47:20 INFO - Return code: 0 18:47:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:47:20 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')]} 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 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')]} 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:20 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:20 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 18:47:20 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 18:47:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:20 INFO - 'HOME': '/Users/cltbld', 18:47:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:20 INFO - 'LOGNAME': 'cltbld', 18:47:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:20 INFO - 'MOZ_NO_REMOTE': '1', 18:47:20 INFO - 'NO_EM_RESTART': '1', 18:47:20 INFO - 'PAGER': '/bin/cat', 18:47:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:20 INFO - 'PWD': '/builds/slave/test', 18:47:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:20 INFO - 'SHELL': '/bin/bash', 18:47:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:20 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:20 INFO - 'USER': 'cltbld', 18:47:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:20 INFO - 'XPC_FLAGS': '0x0', 18:47:20 INFO - 'XPC_SERVICE_NAME': '0', 18:47:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:47:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:47:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:47:23 INFO - Installing collected packages: mozsystemmonitor 18:47:23 INFO - Running setup.py install for mozsystemmonitor 18:47:23 INFO - Successfully installed mozsystemmonitor 18:47:23 INFO - Cleaning up... 18:47:23 INFO - Return code: 0 18:47:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:47:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 18:47:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 18:47:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:23 INFO - 'HOME': '/Users/cltbld', 18:47:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:23 INFO - 'LOGNAME': 'cltbld', 18:47:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:23 INFO - 'MOZ_NO_REMOTE': '1', 18:47:23 INFO - 'NO_EM_RESTART': '1', 18:47:23 INFO - 'PAGER': '/bin/cat', 18:47:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:23 INFO - 'PWD': '/builds/slave/test', 18:47:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:23 INFO - 'SHELL': '/bin/bash', 18:47:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:23 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:23 INFO - 'USER': 'cltbld', 18:47:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:23 INFO - 'XPC_FLAGS': '0x0', 18:47:23 INFO - 'XPC_SERVICE_NAME': '0', 18:47:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:23 INFO - Downloading/unpacking jsonschema==2.5.1 18:47:23 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 18:47:23 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 18:47:23 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 18:47:23 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 18:47:23 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 18:47:23 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 18:47:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:47:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:47:26 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 18:47:26 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 18:47:26 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 18:47:26 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 18:47:26 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 18:47:26 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 18:47:26 INFO - Downloading functools32-3.2.3-2.tar.gz 18:47:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:47:26 INFO - warning: no files found matching '*.txt' 18:47:26 INFO - no previously-included directories found matching 'build' 18:47:26 INFO - no previously-included directories found matching 'dist' 18:47:26 INFO - no previously-included directories found matching '.git*' 18:47:26 INFO - Installing collected packages: jsonschema, functools32 18:47:26 INFO - Running setup.py install for functools32 18:47:26 INFO - warning: no files found matching '*.txt' 18:47:26 INFO - no previously-included directories found matching 'build' 18:47:26 INFO - no previously-included directories found matching 'dist' 18:47:26 INFO - no previously-included directories found matching '.git*' 18:47:26 INFO - Successfully installed jsonschema functools32 18:47:26 INFO - Cleaning up... 18:47:26 INFO - Return code: 0 18:47:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:26 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:26 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 18:47:26 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 18:47:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:26 INFO - 'HOME': '/Users/cltbld', 18:47:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:26 INFO - 'LOGNAME': 'cltbld', 18:47:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:26 INFO - 'MOZ_NO_REMOTE': '1', 18:47:26 INFO - 'NO_EM_RESTART': '1', 18:47:26 INFO - 'PAGER': '/bin/cat', 18:47:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:26 INFO - 'PWD': '/builds/slave/test', 18:47:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:26 INFO - 'SHELL': '/bin/bash', 18:47:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:26 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:26 INFO - 'USER': 'cltbld', 18:47:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:26 INFO - 'XPC_FLAGS': '0x0', 18:47:26 INFO - 'XPC_SERVICE_NAME': '0', 18:47:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:47:27 INFO - Cleaning up... 18:47:27 INFO - Return code: 0 18:47:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:47:27 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')]} 18:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:27 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')]} 18:47:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:27 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:27 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 18:47:27 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 18:47:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:27 INFO - 'HOME': '/Users/cltbld', 18:47:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:27 INFO - 'LOGNAME': 'cltbld', 18:47:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:27 INFO - 'MOZ_NO_REMOTE': '1', 18:47:27 INFO - 'NO_EM_RESTART': '1', 18:47:27 INFO - 'PAGER': '/bin/cat', 18:47:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:27 INFO - 'PWD': '/builds/slave/test', 18:47:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:27 INFO - 'SHELL': '/bin/bash', 18:47:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:27 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:27 INFO - 'USER': 'cltbld', 18:47:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:27 INFO - 'XPC_FLAGS': '0x0', 18:47:27 INFO - 'XPC_SERVICE_NAME': '0', 18:47:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:27 INFO - Downloading/unpacking blobuploader==1.2.4 18:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 18:47:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 18:47:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 18:47:29 INFO - Downloading blobuploader-1.2.4.tar.gz 18:47:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:47:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:47: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 18:47: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 18:47:30 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 18:47:30 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 18:47: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 18:47: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 18:47:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:47:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:47: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 18:47: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 18:47:30 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 18:47:30 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 18:47: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 18:47: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 18:47:31 INFO - Downloading docopt-0.6.1.tar.gz 18:47:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:47:31 INFO - Installing collected packages: blobuploader, requests, docopt 18:47:31 INFO - Running setup.py install for blobuploader 18:47:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:47:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:47:31 INFO - Running setup.py install for requests 18:47:31 INFO - Running setup.py install for docopt 18:47:31 INFO - Successfully installed blobuploader requests docopt 18:47:31 INFO - Cleaning up... 18:47:32 INFO - Return code: 0 18:47:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:32 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:32 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 18:47:32 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 18:47:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:32 INFO - 'HOME': '/Users/cltbld', 18:47:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:32 INFO - 'LOGNAME': 'cltbld', 18:47:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:32 INFO - 'MOZ_NO_REMOTE': '1', 18:47:32 INFO - 'NO_EM_RESTART': '1', 18:47:32 INFO - 'PAGER': '/bin/cat', 18:47:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:32 INFO - 'PWD': '/builds/slave/test', 18:47:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:32 INFO - 'SHELL': '/bin/bash', 18:47:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:32 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:32 INFO - 'USER': 'cltbld', 18:47:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:32 INFO - 'XPC_FLAGS': '0x0', 18:47:32 INFO - 'XPC_SERVICE_NAME': '0', 18:47:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-a_XNTa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-L2rj3V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VBeN3h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-UGPSp9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8peTgJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-AW6_iH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:32 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-zCkyuk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-4xPpd9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-981bLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-WOpwtu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-RBdkam-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-5mxgJl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-iW4ZHK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-YXrjD7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-RsLHLN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:33 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VwBbUB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:34 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-lEJ21Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:47:34 INFO - Running setup.py install for manifestparser 18:47:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:47:34 INFO - Running setup.py install for mozcrash 18:47:34 INFO - Running setup.py install for mozdebug 18:47:34 INFO - Running setup.py install for mozdevice 18:47:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 18:47:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:47:34 INFO - Running setup.py install for mozfile 18:47:34 INFO - Running setup.py install for mozhttpd 18:47:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Running setup.py install for mozinfo 18:47:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Running setup.py install for mozInstall 18:47:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Running setup.py install for mozleak 18:47:35 INFO - Running setup.py install for mozlog 18:47:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Running setup.py install for moznetwork 18:47:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:47:35 INFO - Running setup.py install for mozprocess 18:47:35 INFO - Running setup.py install for mozprofile 18:47:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:47:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:47:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:47:36 INFO - Running setup.py install for mozrunner 18:47:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:47:36 INFO - Running setup.py install for mozscreenshot 18:47:36 INFO - Running setup.py install for moztest 18:47:36 INFO - Running setup.py install for mozversion 18:47:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:47:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:47:36 INFO - Cleaning up... 18:47:36 INFO - Return code: 0 18:47:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:36 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:36 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 18:47:36 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 18:47:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:36 INFO - 'HOME': '/Users/cltbld', 18:47:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:36 INFO - 'LOGNAME': 'cltbld', 18:47:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:36 INFO - 'MOZ_NO_REMOTE': '1', 18:47:36 INFO - 'NO_EM_RESTART': '1', 18:47:36 INFO - 'PAGER': '/bin/cat', 18:47:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:36 INFO - 'PWD': '/builds/slave/test', 18:47:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:36 INFO - 'SHELL': '/bin/bash', 18:47:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:36 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:36 INFO - 'USER': 'cltbld', 18:47:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:36 INFO - 'XPC_FLAGS': '0x0', 18:47:36 INFO - 'XPC_SERVICE_NAME': '0', 18:47:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8_tkyp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-3DJAmf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-9_obzv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-CfWcBU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-jLHbI_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-AVqhv7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-VDfY68-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-6zpndy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-nCuL_D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:47:37 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)) 18:47:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:47:37 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-8Tgz3N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ud_FTT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-S1s4Xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-v2ZKXA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-pDSl9P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-qnpqo2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-ogrdJt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:47:38 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)) 18:47:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:47:38 INFO - Running setup.py (path:/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/pip-Q6vCUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:47:38 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)) 18:47:38 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)) 18:47:38 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)) 18:47:38 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)) 18:47:38 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)) 18:47:38 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)) 18:47:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:47:38 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 18:47:38 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 18:47:38 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 18:47:38 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 18:47:38 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 18:47:38 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 18:47:41 INFO - Downloading blessings-1.6.tar.gz 18:47:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:47:41 INFO - Installing collected packages: blessings 18:47:41 INFO - Running setup.py install for blessings 18:47:41 INFO - Successfully installed blessings 18:47:41 INFO - Cleaning up... 18:47:41 INFO - Return code: 0 18:47:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:47:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:41 INFO - Reading from file tmpfile_stdout 18:47:41 INFO - Current package versions: 18:47:41 INFO - blessings == 1.6 18:47:41 INFO - blobuploader == 1.2.4 18:47:41 INFO - docopt == 0.6.1 18:47:41 INFO - functools32 == 3.2.3-2 18:47:41 INFO - jsonschema == 2.5.1 18:47:41 INFO - manifestparser == 1.1 18:47:41 INFO - mozInstall == 1.12 18:47:41 INFO - mozcrash == 0.17 18:47:41 INFO - mozdebug == 0.1 18:47:41 INFO - mozdevice == 0.48 18:47:41 INFO - mozfile == 1.2 18:47:41 INFO - mozhttpd == 0.7 18:47:41 INFO - mozinfo == 0.9 18:47:41 INFO - mozleak == 0.1 18:47:41 INFO - mozlog == 3.3 18:47:41 INFO - moznetwork == 0.27 18:47:41 INFO - mozprocess == 0.23 18:47:41 INFO - mozprofile == 0.28 18:47:41 INFO - mozrunner == 6.12 18:47:41 INFO - mozscreenshot == 0.1 18:47:41 INFO - mozsystemmonitor == 0.3 18:47:41 INFO - moztest == 0.7 18:47:41 INFO - mozversion == 1.4 18:47:41 INFO - psutil == 3.1.1 18:47:41 INFO - requests == 1.2.3 18:47:41 INFO - wsgiref == 0.1.2 18:47:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:47: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')]} 18:47:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47: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')]} 18:47:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:41 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:41 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 18:47:41 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 18:47:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:41 INFO - 'HOME': '/Users/cltbld', 18:47:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:41 INFO - 'LOGNAME': 'cltbld', 18:47:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:41 INFO - 'MOZ_NO_REMOTE': '1', 18:47:41 INFO - 'NO_EM_RESTART': '1', 18:47:41 INFO - 'PAGER': '/bin/cat', 18:47:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:41 INFO - 'PWD': '/builds/slave/test', 18:47:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:41 INFO - 'SHELL': '/bin/bash', 18:47:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:41 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:41 INFO - 'USER': 'cltbld', 18:47:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:41 INFO - 'XPC_FLAGS': '0x0', 18:47:41 INFO - 'XPC_SERVICE_NAME': '0', 18:47:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 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)) 18:47:42 INFO - Cleaning up... 18:47:42 INFO - Return code: 0 18:47:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:47:42 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')]} 18:47:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:47:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:42 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')]} 18:47:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:47:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:47:42 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 0x10d2eca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d381618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff6e2579a80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, '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.cuJDHawgQn/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.MgwAtl2xFU/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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 18:47:42 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 18:47:42 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 18:47:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:47:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:47:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:47:42 INFO - 'HOME': '/Users/cltbld', 18:47:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:47:42 INFO - 'LOGNAME': 'cltbld', 18:47:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:47:42 INFO - 'MOZ_NO_REMOTE': '1', 18:47:42 INFO - 'NO_EM_RESTART': '1', 18:47:42 INFO - 'PAGER': '/bin/cat', 18:47:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:47:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:47:42 INFO - 'PWD': '/builds/slave/test', 18:47:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:47:42 INFO - 'SHELL': '/bin/bash', 18:47:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:47:42 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:47:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:47:42 INFO - 'USER': 'cltbld', 18:47:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:47:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:47:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:47:42 INFO - 'XPC_FLAGS': '0x0', 18:47:42 INFO - 'XPC_SERVICE_NAME': '0', 18:47:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:47:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:47:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:47:42 INFO - Cleaning up... 18:47:42 INFO - Return code: 0 18:47:42 INFO - Running post-action listener: _resource_record_post_action 18:47:42 INFO - Running post-action listener: _start_resource_monitoring 18:47:42 INFO - Starting resource monitoring. 18:47:42 INFO - [mozharness: 2016-08-19 01:47:42.457117Z] Finished create-virtualenv step (success) 18:47:42 INFO - [mozharness: 2016-08-19 01:47:42.458124Z] Running install step. 18:47:42 INFO - Running pre-action listener: _resource_record_pre_action 18:47:42 INFO - Running main action method: install 18:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:47:42 INFO - Reading from file tmpfile_stdout 18:47:42 INFO - Detecting whether we're running mozinstall >=1.0... 18:47:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:47:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:47:42 INFO - Reading from file tmpfile_stdout 18:47:42 INFO - Output received: 18:47:42 INFO - Usage: mozinstall [options] installer 18:47:42 INFO - Options: 18:47:42 INFO - -h, --help show this help message and exit 18:47:42 INFO - -d DEST, --destination=DEST 18:47:42 INFO - Directory to install application into. [default: 18:47:42 INFO - "/builds/slave/test"] 18:47:42 INFO - --app=APP Application being installed. [default: firefox] 18:47:42 INFO - mkdir: /builds/slave/test/build/application 18:47:42 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'] 18:47:42 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 18:48:17 INFO - Reading from file tmpfile_stdout 18:48:17 INFO - Output received: 18:48:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:48:17 INFO - Running post-action listener: _resource_record_post_action 18:48:17 INFO - [mozharness: 2016-08-19 01:48:17.159143Z] Finished install step (success) 18:48:17 INFO - [mozharness: 2016-08-19 01:48:17.159276Z] Running run-tests step. 18:48:17 INFO - Running pre-action listener: _resource_record_pre_action 18:48:17 INFO - Running main action method: run_tests 18:48:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:48:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:48:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:48:17 INFO - 2016-08-18 18:48:17.173 screenresolution[7219:178922] starting screenresolution argv=screenresolution get 18:48:17 INFO - 2016-08-18 18:48:17.176 screenresolution[7219:178922] Display 0: 1600x1200x32@60 18:48:17 INFO - 2016-08-18 18:48:17.182 screenresolution[7220:178924] starting screenresolution argv=screenresolution list 18:48:17 INFO - Available Modes on Display 0 18:48:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:48:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:48:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:48:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:48:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:48:17 INFO - Intel Iris: 18:48:17 INFO - Chipset Model: Intel Iris 18:48:17 INFO - Type: GPU 18:48:17 INFO - Bus: Built-In 18:48:17 INFO - VRAM (Dynamic, Max): 1536 MB 18:48:17 INFO - Vendor: Intel (0x8086) 18:48:17 INFO - Device ID: 0x0a2e 18:48:17 INFO - Revision ID: 0x0009 18:48:17 INFO - Displays: 18:48:17 INFO - Display: 18:48:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:48:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:48:17 INFO - Display Serial Number: 1600x1200 60 18:48:17 INFO - Main Display: Yes 18:48:17 INFO - Mirror: Off 18:48:17 INFO - Online: Yes 18:48:17 INFO - Rotation: Supported 18:48:17 INFO - Return code: 0 18:48:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:48:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:48:17 INFO - Python 2.7.3 18:48:17 INFO - Return code: 0 18:48:17 INFO - grabbing minidump binary from tooltool 18:48: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')]} 18:48:17 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 0x10d250d30>, '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 0x10cfb5db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d248b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:48:17 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 18:48:17 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 18:48:17 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 18:48:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:48:17 INFO - Return code: 0 18:48:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:48:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:48:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:48:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:48:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:48:17 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/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 18:48:17 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/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0056 --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 18:48:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.cuJDHawgQn/Render', 18:48:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:48:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:48:17 INFO - 'HOME': '/Users/cltbld', 18:48:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:48:17 INFO - 'LOGNAME': 'cltbld', 18:48:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:48:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:48:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:48:17 INFO - 'MOZ_NO_REMOTE': '1', 18:48:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:48:17 INFO - 'NO_EM_RESTART': '1', 18:48:17 INFO - 'PAGER': '/bin/cat', 18:48:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:48:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:48:17 INFO - 'PWD': '/builds/slave/test', 18:48:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:48:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:48:17 INFO - 'SHELL': '/bin/bash', 18:48:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.MgwAtl2xFU/Listeners', 18:48:17 INFO - 'TMPDIR': '/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/', 18:48:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:48:17 INFO - 'USER': 'cltbld', 18:48:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:48:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:48:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:48:17 INFO - 'XPC_FLAGS': '0x0', 18:48:17 INFO - 'XPC_SERVICE_NAME': '0', 18:48:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:48:17 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/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0056', '--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 18:48:17 INFO - mozversion application_buildid: 20160818163358 18:48:17 INFO - mozversion application_changeset: abac53d4d2476c4ab1816844091989456b8993bf 18:48:17 INFO - mozversion application_display_name: Nightly 18:48:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:48:17 INFO - mozversion application_name: Firefox 18:48:17 INFO - mozversion application_remotingname: firefox 18:48:17 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 18:48:17 INFO - mozversion application_vendor: Mozilla 18:48:17 INFO - mozversion application_version: 51.0a1 18:48:17 INFO - mozversion platform_buildid: 20160818163358 18:48:17 INFO - mozversion platform_changeset: abac53d4d2476c4ab1816844091989456b8993bf 18:48:17 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 18:48:17 INFO - mozversion platform_version: 51.0a1 18:48:17 INFO - using testdate: 1471571297 18:48:17 INFO - actual date: 1471571297 18:48:17 INFO - starting webserver on 'localhost:50433' 18:48:17 INFO - SUITE-START | Running 2 tests 18:48:17 INFO - TEST-START | dromaeo_css 18:48:17 INFO - operating with platform_type : mac_ 18:48:17 INFO - Initialising browser for dromaeo_css test... 18:48:17 INFO - TEST-INFO | started process 7230 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpc1r9b5/profile http://localhost:50433/getInfo.html) 18:48:20 INFO - PROCESS | 7230 | __metrics Screen width/height:1600/1200 18:48:20 INFO - PROCESS | 7230 | colorDepth:24 18:48:20 INFO - PROCESS | 7230 | Browser inner width/height: 1024/689 18:48:20 INFO - PROCESS | 7230 | __metrics 18:48:20 INFO - PROCESS | 7230 | 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" 18:48:20 INFO - PROCESS | 7230 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 18:48:21 INFO - TEST-INFO | 7230: exit 0 18:48:21 INFO - Browser initialized. 18:48:21 INFO - Running cycle 1/1 for dromaeo_css test... 18:48:21 INFO - TEST-INFO | started process 7232 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpc1r9b5/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:48:33 INFO - PROCESS | 7232 | RSS: Main: 228409344 18:48:33 INFO - PROCESS | 7232 | 18:49:24 INFO - PROCESS | 7232 | 1471571364820 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] 18:51:37 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50433/tests/dromaeo/cssquery-ext.html) 18:51:38 INFO - PROCESS | 7232 | RSS: Main: 374308864 18:51:38 INFO - PROCESS | 7232 | 18:54:41 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-ext.html (next: http://localhost:50433/tests/dromaeo/cssquery-jquery.html) 18:54:42 INFO - PROCESS | 7232 | RSS: Main: 372551680 18:54:42 INFO - PROCESS | 7232 | 18:57:44 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50433/tests/dromaeo/cssquery-mootools.html) 18:57:45 INFO - PROCESS | 7232 | RSS: Main: 336584704 18:57:45 INFO - PROCESS | 7232 | 19:00:47 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50433/tests/dromaeo/cssquery-prototype.html) 19:00:48 INFO - PROCESS | 7232 | RSS: Main: 366108672 19:00:48 INFO - PROCESS | 7232 | 19:03:51 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50433/tests/dromaeo/cssquery-yui.html) 19:03:52 INFO - PROCESS | 7232 | RSS: Main: 327303168 19:03:52 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | Cycle 1(1): loaded http://localhost:50433/tests/dromaeo/cssquery-yui.html (next: http://localhost:50433/tests/dromaeo/cssquery-dojo.html) 19:06:56 INFO - PROCESS | 7232 | RSS: Main: 485990400 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | __start_tp_report 19:06:56 INFO - PROCESS | 7232 | _x_x_mozilla_page_load 19:06:56 INFO - PROCESS | 7232 | _x_x_mozilla_page_load_details 19:06:56 INFO - PROCESS | 7232 | |i|pagename|runs| 19:06:56 INFO - PROCESS | 7232 | |0;dojo.html;3330.6169790474096;3335.5497007903928;3345.7360320009566;3358.2108204571937;3398.558187435633;5724;5779.595428320009;5828.358880523145;5930.525557955359;5978.9402105978925;5833.766649334032;5849.210356601858;5861.208736820538;5873.970630146854;5879.294484661847;4745.976270118645;5095.719735414551;5260.053190425722;5297.993381257549;5333.919991200135;5364.168553874156;5375.650582712111;5377;5378.865528361783;5388.191771234327;5261;5299.973500132494;5300.893982120355;5357.142857142858;5362.865928351784;5377.247185394048;5390.5418039466695;5400.486953739389;5407.297051383334;5419.674819510842;4270.658347332206;5133.25567792668;5260.736963151827;5275;5325.467453254682;5702.030654788696;5728.283964504437;5782.653040817522;5788.507976821975;5810.88378232433;6254.249490061176;6258.624482531062;6264.342244064399;6265.0915617235705;6273.592216505913;6145.661988590626;6186.381361863822;6250.281217659982;6265.592736472115;6316.873662526769;6033.7586496540525;6049.48579370754;6055.666938318347;6060.454559089615;6065.727042283108;6369.394907483782;6383.872322553616;6385.297617262097;6392.296847346788;6399.104125422445;6141.854248382324;6144.324124346318;6194.473469755031;6224.875502489925;6228.4705800007505;6253.1245625612455;6268.310485846553;6276.717547710364;6287.9056814147825;6293.37066293367;6156.846078848064;6200.007998720183;6228.750849966042;6230.626162430269;6241.18864547615;5293.20601909709;5851.473367396869;5883.02930016551;5892.469677729025;5961.821145365646;4817.8313759018265;4852.708837469763;4852.757362131879;4854.660173787801;5022.799088036438;4547.658925580595;4553;4564.064366804797;4564.383808185852;4579.656525760582;5887.20522729426;5891.351951285355;5894.32215295238;5897.351291357947;5960.6721630309885;6212.192414986023;6237.937620623827;6269.62382257066;6272.383605252613;6325.462335701517;5785.508231800344;5796.62321949072;5805.564582656318;5809.506191973645;5818.912716309259;5509.815389691155;5513.641613295204;5520.779168833202;5525.530329922001;5526.143447765603;5324.254604355471;6233.657148856856;6265.060240963892;6319.873602527831;6372.235331760218;6319.6208227506495;6328;6332.398422149889;6340.4293613575;6342.556021078481;6362.586431881913;6367.331430199948;6374.29882712892;6382.776602818879;6390.968045159931;6110.832160913912;6204.255489341308;6237.1891654084675;6279.434850863444;6293.465055470337;6284.465820405134;6327.746890124344;6334.714937827716;6339.270983836917;6344.651044192429;6196.876062478816;6202;6207.689615519115;6215.9378406217165;6224.9377506224355;5936.5844390892225;6024.66864322449;6114.633122012693;6177.351378105414;6210.875782484235;6027.4876635484725;6102.0651310565;6105.641896956745;6128.228138398679;6130.693465326626;6207.6275423474735;6222.284437289589;6229.50163986889;6238.344973777869;6245.313015568375;5897.498712609477;6009.54928380382;6076.179715738495;6088.665123418255;6096.603720758137 19:06:56 INFO - PROCESS | 7232 | |1;ext.html;453.8615722204726;461.4716150008243;475.9333693282939;514.8827045311673;520.2014907117575;31166.376672466537;40776.59223407769;41913.37129943053;42369.364459533055;44199;44085.33871991923;44305.77847110768;44445.55554444455;44450.77774611123;44583.77708111463;8213.42506024577;8849.469031858076;9882.654107106253;9884.159846413064;9887.950560247189;8789;12009.159358844885;12224.755504889941;12244.204126731778;12477.875221247763;12112.636620901387;12167.087468439859;12189.20770149942;12211.755764884698;12222.694432639166;12100.576479823209;12150.088743344284;12171.330576818227;12171.513139474411;12175.878241217562;11950.521979120826;12008.519659213622;12016.218945768496;12032.954397752403;12072.336021518813;33329.333413331595;40962;40997.2354561497;41017.58982410168;41124.177516449505;3371.831408429569;3437.226624009603;3440.724742020632;3442.6557344265625;3559.2525569630407;31133.532997004837;32055.679443205503;32116.518252226237;32152.6784732152;32264.193395165075;3242.319112986252;3346.0965539304493;3374.54443650109;3379.3241351729507;3412.692857642824;387123;387531;388514;388662;391868.0406597977;62070.68964655148;62225.68887155535;62494;62498.37501624926;62586.68706656437;62051;62289.68855155786;62843.37156628375;62879.37120628827;62884.74230515364;16137.59656008609;16333.183340833148;16334.75497867533;16348.836511634969;16354.591135221477;11516.251443656136;11681;12065.698357541132;12755.68110797219;12863.22820630765;14230.715385692436;16594.58513537171;17168.79818412724;17317.82682173162;17361.218745156497;6265.498760099181;7361.374283185882;7403.85192296151;7407.185209626937;7482.812929676802;879.9164079412445;935.7988032573029;1014.7868947520956;1042.801867645145;1086.7880763251248;25470.872645636653;29121.8351265947;30672.846635767124;30719.385612287628;31513.842430788158;1003.6687892995294;1057.5135437698746;1061.5382308695741;1090.7654854206226;1125.4766533561933;12630.368481575884;15171.468998584996;16059.116748578941;16075.437359692354;16501.33994640201;600017;666767;709866.9013309801;710844.1324694012;927676;60113.39886601165;60821;60921.695391523645;60995;61018.47453813601;1165.9533618655328;1232.6548566401423;1271.5104684696273;1351.6755978565075;1358.4634069542476;25555.84998675027;26347.473050539265;28590.42819143564;29964.501774910732;33243.5299214682;56763.71618141883;56977.57556060966;57339.42660573341;57460.71269643625;57513;471006;473356.9307376117;474635;474695;475850;23677;24164;26335.34161645974;26511.07211247597;26975.920963161254;43538.564614353454;47413.05173896572;47595.76202118967;48347;48814.75592622014;38653;38698;38735.418968716054;39369;40033.39949900812;38548.42177367398;39044.2191156181;39515.01212469711;39594.60405395909;40899;38144.80927595344;38791.22417551577;39109.82670519889;39155.804220978716;39159;33198.66801332052;34123.14692132716;35015;35046.29907401888;35063.64936350604;25363.746362536138;25476.872615636803;25566;25762.87118564395;26301.34246643849 19:06:56 INFO - PROCESS | 7232 | |2;jquery.html;348.0654442821024;366.8440912612141;376.76452217364147;383.8387877091622;405.42227326060356;15586.532404027868;15762.842371576295;20331.695024574645;20550.972451377445;20662.793372066273;17869.553261168476;19720.605587888233;20318.390448286536;20634;21447.356579302646;5440.564754819625;6071.362506936764;6249.500039996789;6313.842153946165;6330.905036424458;6230.688465576703;6270.31026587075;6282.7172777224905;6290.591111577733;6294.024426213944;6131.172291740625;6147.415995480422;6218.751249949996;6252.874942501147;6254.6247225166635;6020.5785595008365;6109.3890610939;6194.34959329273;6205.999999999977;6236.968815155895;5169.276301317818;5356.223347614613;5362.544183744387;5366.597505187087;5379;25671.229863104138;26353.604695929578;26451.603225951625;26493.867530662417;26585.867070664524;2279.943001424961;2389.928302150938;2838.843863587502;3135.686431356869;3270.4767237241927;15633.92183039089;16004;16006.5998350041;16045.438409655722;16274;1825.3702472647049;1882.7458293130526;1899.6770549006565;1919.6928491441302;1995.012468827925;124480.37759811143;124595.75404246023;128303.32056832552;129275;130211;10662.786744265226;10680.145588352912;10807.891921080845;11077.390743509022;11351.602693905674;13384;13864.514741983981;13867.23730194849;13868;13874.65313367174;7229.8554028919125;8583.828323433621;8642.30861531076;8692.826143477096;8828.690995815245;7087.362137407658;8693.087225841196;8722.171393717586;8876.778080548038;8947;6569.24453687832;6711.328867113347;6711.932880671228;6726.831829204211;6728.394444500019;1852.4535262097713;1876.4464482977555;1894.6968485042328;1919.184346652667;1919.8368138708088;385.77624977512977;389.24098008882794;728.4995596160394;785.9743035338823;925.745614692096;11462.48418821155;11774;12031.931857376312;12120.939395302967;12153.574624888264;413.4624987516217;423.2276096124585;423.32268370606783;426.7994042799879;449.07266494688366;5790.131480277992;6222.12890195373;6222.639774521707;6231.75072997075;6247.656378899204;522342;557055;620504;626795;632094;135954;137610;138251.30874345565;138512;146308;956.0726095687172;990.5839547390113;1000.4197565412044;1000.979980400388;1028.994855025735;17870.374536891148;19426.4172074838;19744.703829442857;19834.008299584675;19982;39340.803295983336;51268.230976534636;54763.209003536314;57275.55882191185;58293.12560311514;82082;84481.70701222983;85989.81653920742;86168.56915715382;86886;6563.621639455577;7237.3486386225495;7543.43424243173;8235.21765432283;8370.790730231793;12246.448909798903;12496.437660305126;13096.214227146402;13595;13633.182009079486;10570.841437378293;11229.66311010671;11347.886521134684;11412.543498259976;11679.474423650787;11097;11419.60031398897;11604.94197529007;11745.882541174478;11894.821577676516;10870.891291087304;11470.254433461798;11627.767444651228;11756.470958807045;11767.23512971654;6118.911509215638;6404.359564043559;7482.625868706434;7532.811679708051;7538.3969282458365;9910.819619683658;11323.830142547704;11650.6504804856;11654.592089277174;11668.70828229301 19:06:56 INFO - PROCESS | 7232 | |3;mootools.html;1370.2121280263848;1505.796717443145;1671.5821044738816;1850.2414010255798;1860.925562977481;8511.957440212798;9074.36479446439;9089.500077495735;9102.726918192448;9150.313726470516;7890.684372625088;8070.071941726703;8513;8679.609417576208;8849.469031858076;2081;2363.196513185517;2376.32274801682;2417.129833260025;2419.721732000825;2375.4655202579456;2398.352444839892;2425.0178677635586;2429.0648100481353;2437.427204606916;2400.711914570254;2416.4804567018086;2427.0655797517993;2428.3079322393123;2434.354895952565;2384.9284521464383;2384.95230095398;2402.543516731824;2405.230326295586;2418.504206637635;2369.3721163891582;2381.4522659788345;2386.248331775485;2388.689470368858;2397.544466551367;8784.560771961376;8987.595558199897;8998.460092394478;9006.909930900672;9007.144321289532;5286.788528458857;5456.781728730846;5456.863578410532;5457.781688732485;5463.590230732671;3538.9115272118147;3626.8911932642063;3681.631836816324;3685.4103343464917;3704.2776658551593;1085.8154113800672;1093.316677076827;1102.6802227354058;1104.0725790336155;1116.4027245423658;9917.355371900803;11564.479598418091;11626.76746465066;11745.882541174564;11752.47113879869;4355.673324500612;4433.578810013044;4618.445786505574;4643.164230438553;4657.184992626277;7869.1737367576725;9228.307876909124;9244.90755092454;9253.398529095723;9256.8611470828;5301.6818990860675;5307.893842123213;5326.946730532645;5335.039692855245;5335.919961200508;4393.055493069006;4541.455025396974;4786.832460863853;4899.5590396863745;4968.975155124273;3747.2318174774105;3771.245750849786;3863.7874916879277;3874.9150237933422;3922.627350401707;1018.8675472188568;1172.28490620723;1191.2971346905367;1198.0894520164738;1201.9759604807855;852.6461518469765;865.3726048614704;898.6854600889636;918.1598837064145;947.5215016416665;5317.627766056339;5876.412358764175;5906.202662640574;6045.78839740607;6078.908816367758;908.6410867707218;953.8521529162991;958.3195930889008;968.162539403411;985.2955137076881;3931.8230679619487;3989.1024519482885;4007.2987227235117;4066.898327541835;4102.446169767103;10423.635172768916;10804.351738895692;10839.729006774895;10875;10896.346219226873;9643.855342169873;10694.091002264739;10709.839352409721;10833.399984003783;10839.403832789272;8059.073206581201;8279.337652987746;8290.419670622998;8291.875621865676;8300.377471689648;5847.210626565529;5914.408559144051;5935.584509084322;6070.271567411939;6194.0320709248535;9797.20779577817;9867.851982220416;9951.552180152055;10034.598616055277;10047.739258666741;9915.900840991499;10134;10195.898041019494;10314.381137131797;10452.843207351743;6775.220849602359;7018.345661472092;7114.110736157981;7714.0743110824815;7867.331276841533;7974.960125199337;8664.220220180214;8674.91325086741;8686.131386861263;8686.478811271343;7500.774976750488;7556.093268807778;7559.130699969354;7598.848023039619;7694.115176754521;7407.259274072765;7439.665215065227;7443.106827180773;7452.888206676796;7518.097828260425;5934.43622855828;6974.755883544051;7039.824004399931;7050.612216328152;7056;4988.152014157675;5033.84898453047;5057.089723849595;5080.949190508048;5168.3797944246935;4894.632902532396;5012.546361555108;5021.07110184617;5150.253213284128;5156.819511317086 19:06:56 INFO - PROCESS | 7232 | |4;prototype.html;252.7434653826366;288.587320132211;307.49877699350054;313.42173689542784;314.5093653899918;4255.872323830282;4370.8688739337795;4846.006568653426;5280;5448.37343705474;4333.7399756014665;5139.974300128503;5286.466924591873;5370.489803468664;5379.838604841861;4124.814383352741;4136.66906647469;4141.316682747343;4141.503019637647;4150.190712811011;3390.372781035504;3986.441898134249;4142.855000074998;4184.644305234044;4187.225363307783;3421.760476766627;4052.7973601319964;4084.550699423061;4086.5913408659194;4111.897202569945;4032.576579459144;4035.6367926886574;4070.715049946475;4075.0219947212754;4090.9386359204486;3157.715805577486;3543.557055368079;3553.875614353485;3565.5899571549344;3600.6579374959338;4270;5084.593232541388;5208.583313334924;5248.81901572147;5267.656747529356;4688.203005489075;4691;4697.533794757745;4706.5293470653005;4712.6465515086165;5109.901371205208;5224.320838290998;5570.805021824217;5616.803411880564;5634.408387119334;3092.6443459002053;3227.8547465364113;3787.6023017583034;3882.1847412043503;3925.568187499373;4804.327394164785;5540.50135487804;5892.882142357172;5918.970405147947;5920.437558431942;2995.206270338362;2997.970020299769;3985.7608543487486;3997.260506806192;4010.8942805027596;3881.204353107606;3924.9980458063474;4048.1296521248073;4058;4065.3495440729343;4128.720096770011;4665.510121437268;4670.497127154634;4672.696274742131;4682.648801339913;5369.48989845964;5609.551235901118;5633.802816901389;5649.774009039593;5702.429757024347;1173.4191575170346;1290.1291628150962;1326.907116501855;1339.3102552185633;1353.5668586052566;790.5770412829108;899.820035992804;937.7280588629221;944.6693657219918;944.8393773058597;4926.5073492650445;5211.557017653542;5235.110031294689;5238.580913526908;5241.453771137523;4695.694779839368;5036.445990940993;5484.2596249506605;5725.914111288334;5731.3665605302285;4331.6534677225745;4360.645251564183;4850.990422937346;5154.355705536808;5225.503577160163;2987.9402411951637;3017.969820301813;3031.8332491712727;3039.5592639067213;3051.71008754168;5188.5330320270605;5870.383609721003;5920.289565252111;5931.228940237742;5952.613080149777;4818.349522814446;4823.541763532458;5778.393268706809;5836.5992161881695;5897.115432685132;4962.60299176065;5834.299884013945;5892.351841297368;5925.48167029011;5939.851503712355;5453.454654534515;5457.9727101365825;5711.857203569944;5726.942730572641;5754.597178197486;5174.301469301596;5177.4822517749435;5316.2786049764545;5577.051901176728;5631.63394379364;5410.404855465817;5892.587518873637;5950.047992321208;5970.791022314198;5970.984932561388;3692.9815350923072;3924.803759811941;3948.44721738951;3966;3970.6823454123023;4629.398178236942;4808.639352048541;5620.072688006597;5759.9136012959;5916.230889984275;4822.493638168082;5712.7714891403875;5714.14295350645;5731;5737.454941780524;4485.977570112129;4487.551244875486;5364;5685.147227915846;5715.828525144252;5493.313335833021;5550.5282051026115;5582.804601838916;5623.959567479866;5651.378348381592;5345.492178243061;5428.123094759783;5719.225311294525;5725.169850371756;5739.454751798572;4362.978185109054;4578.168732506891;4906.975465122794;5564.499195072301;5597.384287728265 19:06:56 INFO - PROCESS | 7232 | |5;yui.html;659.294554826336;682.5120039171991;748.5321673953779;778.3034184404958;804.4409135650722;15514.922425387871;16883.155842207903;17092.658146837057;17150.885192462498;17165;15297.158656273901;15457;15479.380824767024;15489.5353139406;15614.843851561482;831.1107115386524;848.5375470368664;850.1158794853376;850.8425941200867;852.9402941794077;680.8434060166164;693.3898169610735;840.7813968368238;854.3763052971335;862.6463150108457;902.8510295801185;905.1355955062899;905.2803021597846;905.741863568883;905.9864102038474;898.6899519665704;901.1034021148998;901.1664210605197;901.4411065139656;901.4681338010572;526.1397614899629;657.3262406033806;670.6043434373743;671.1174805131286;678.7081554931377;13013.804792928113;15424.305906234247;15447;15571.844281557265;15592.688146237011;13338.866611333859;13990.843870149483;14080.521545237807;14091.2954352283;14149.929250353785;11598.884011159866;11874.762504749857;11880.227785193934;12008.399580020963;12377.009839212931;4608.4930657627965;4651.3023046543285;4753.83361582343;4872.537108974677;4879.707217566922;113332.30001549983;114512.42743786311;114730;118261.40869295684;122959;9874.012598740212;11057.72355691098;11074.557017719362;11321.377324247082;11382.260153090023;20292.695609565868;21752.238671646417;21756.673649895263;21758.78241217599;21777.38671359447;7794.3764498839955;8156.184381561797;8164.265216130577;8251.95874020626;8253.380996425292;4377.8248870044845;4470.552944705503;4645.581897629229;4651.348811166439;4689.531046895283;3571.4821350904494;3702.9259414811554;3765.3410653135593;3767.453719210699;3770.245950809849;618.4217756397783;664.9833754156087;667.3393340592778;673.1787219592089;690.4786885901212;558.3244274427907;650.5413683353224;659.986800263992;668.9498287628447;680.5576375356059;7744.419168562267;8182.386321025947;9151.77120571991;9919.206463482904;10189.082982531607;518.5436815602246;582.6038293960063;604.6583680220662;637.6811594202899;678.4606238040769;4269.87190384289;4570.085982803453;4618.769061546909;4675.719456832533;4693.24908014716;147548;151309;152428;153554.23222884038;153559;129081;132483;133781;134898;139282;106082;109157;109667;111366;113326.43336783264;8866.42368246062;8866.73399798007;8870.689525866565;8934.28525717928;9018.053104423941;59868.70065649644;60117.79764404775;60382.698086509285;60728.6963565197;62475.56579861842;63464;88900;91238;91471;94095.52952235195;11799.469023893773;12231.755364892475;12793.8080928788;13161.30570164327;13510.79733803974;15046.32291546861;15279.236038198267;15443.305051272495;15626.218689065274;16035.599110022344;13868.4452621894;14083.295835208404;14255.786163207767;14435.494757683431;14669.339879705225;12886.09797314179;13619.319034048456;13762.65593360174;14033.719325613227;14312.856871431153;11931.747166547388;12552.87447125517;13721.519746808814;14259;14551.85448145505;11173.776524469627;11324.32054076758;11469.19715619932;11528.193026488065;11709.238899471507;9494.430334179971;11320.6037788677;11502.194846360675;11710.896612397892;11811.291322520676 19:06:56 INFO - PROCESS | 7232 | __end_tp_report 19:06:56 INFO - PROCESS | 7232 | __start_cc_report 19:06:56 INFO - PROCESS | 7232 | _x_x_mozilla_cycle_collect,1371 19:06:56 INFO - PROCESS | 7232 | __end_cc_report 19:06:56 INFO - PROCESS | 7232 | __startTimestamp1471572416343__endTimestamp 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | ------- Summary: start ------- 19:06:56 INFO - PROCESS | 7232 | Number of tests: 6 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#0] dojo.html Cycles:165 Average:5801.86 Median:6049.49 stddev:648.68 (10.7%) stddev-sans-first:621.03 19:06:56 INFO - PROCESS | 7232 | Values: 3330.6 3335.5 3345.7 3358.2 3398.6 5724.0 5779.6 5828.4 5930.5 5978.9 5833.8 5849.2 5861.2 5874.0 5879.3 4746.0 5095.7 5260.1 5298.0 5333.9 5364.2 5375.7 5377.0 5378.9 5388.2 5261.0 5300.0 5300.9 5357.1 5362.9 5377.2 5390.5 5400.5 5407.3 5419.7 4270.7 5133.3 5260.7 5275.0 5325.5 5702.0 5728.3 5782.7 5788.5 5810.9 6254.2 6258.6 6264.3 6265.1 6273.6 6145.7 6186.4 6250.3 6265.6 6316.9 6033.8 6049.5 6055.7 6060.5 6065.7 6369.4 6383.9 6385.3 6392.3 6399.1 6141.9 6144.3 6194.5 6224.9 6228.5 6253.1 6268.3 6276.7 6287.9 6293.4 6156.8 6200.0 6228.8 6230.6 6241.2 5293.2 5851.5 5883.0 5892.5 5961.8 4817.8 4852.7 4852.8 4854.7 5022.8 4547.7 4553.0 4564.1 4564.4 4579.7 5887.2 5891.4 5894.3 5897.4 5960.7 6212.2 6237.9 6269.6 6272.4 6325.5 5785.5 5796.6 5805.6 5809.5 5818.9 5509.8 5513.6 5520.8 5525.5 5526.1 5324.3 6233.7 6265.1 6319.9 6372.2 6319.6 6328.0 6332.4 6340.4 6342.6 6362.6 6367.3 6374.3 6382.8 6391.0 6110.8 6204.3 6237.2 6279.4 6293.5 6284.5 6327.7 6334.7 6339.3 6344.7 6196.9 6202.0 6207.7 6215.9 6224.9 5936.6 6024.7 6114.6 6177.4 6210.9 6027.5 6102.1 6105.6 6128.2 6130.7 6207.6 6222.3 6229.5 6238.3 6245.3 5897.5 6009.5 6076.2 6088.7 6096.6 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#1] ext.html Cycles:180 Average:67457.23 Median:35030.65 stddev:147648.61 (421.5%) stddev-sans-first:147977.10 19:06:56 INFO - PROCESS | 7232 | Values: 453.9 461.5 475.9 514.9 520.2 31166.4 40776.6 41913.4 42369.4 44199.0 44085.3 44305.8 44445.6 44450.8 44583.8 8213.4 8849.5 9882.7 9884.2 9888.0 8789.0 12009.2 12224.8 12244.2 12477.9 12112.6 12167.1 12189.2 12211.8 12222.7 12100.6 12150.1 12171.3 12171.5 12175.9 11950.5 12008.5 12016.2 12033.0 12072.3 33329.3 40962.0 40997.2 41017.6 41124.2 3371.8 3437.2 3440.7 3442.7 3559.3 31133.5 32055.7 32116.5 32152.7 32264.2 3242.3 3346.1 3374.5 3379.3 3412.7 387123.0 387531.0 388514.0 388662.0 391868.0 62070.7 62225.7 62494.0 62498.4 62586.7 62051.0 62289.7 62843.4 62879.4 62884.7 16137.6 16333.2 16334.8 16348.8 16354.6 11516.3 11681.0 12065.7 12755.7 12863.2 14230.7 16594.6 17168.8 17317.8 17361.2 6265.5 7361.4 7403.9 7407.2 7482.8 879.9 935.8 1014.8 1042.8 1086.8 25470.9 29121.8 30672.8 30719.4 31513.8 1003.7 1057.5 1061.5 1090.8 1125.5 12630.4 15171.5 16059.1 16075.4 16501.3 600017.0 666767.0 709866.9 710844.1 927676.0 60113.4 60821.0 60921.7 60995.0 61018.5 1166.0 1232.7 1271.5 1351.7 1358.5 25555.8 26347.5 28590.4 29964.5 33243.5 56763.7 56977.6 57339.4 57460.7 57513.0 471006.0 473356.9 474635.0 474695.0 475850.0 23677.0 24164.0 26335.3 26511.1 26975.9 43538.6 47413.1 47595.8 48347.0 48814.8 38653.0 38698.0 38735.4 39369.0 40033.4 38548.4 39044.2 39515.0 39594.6 40899.0 38144.8 38791.2 39109.8 39155.8 39159.0 33198.7 34123.1 35015.0 35046.3 35063.6 25363.7 25476.9 25566.0 25762.9 26301.3 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#2] jquery.html Cycles:180 Average:35403.54 Median:3203.08 stddev:99778.63 (3115.1%) stddev-sans-first:100023.82 19:06:56 INFO - PROCESS | 7232 | Values: 348.1 366.8 376.8 383.8 405.4 15586.5 15762.8 20331.7 20551.0 20662.8 17869.6 19720.6 20318.4 20634.0 21447.4 5440.6 6071.4 6249.5 6313.8 6330.9 6230.7 6270.3 6282.7 6290.6 6294.0 6131.2 6147.4 6218.8 6252.9 6254.6 6020.6 6109.4 6194.3 6206.0 6237.0 5169.3 5356.2 5362.5 5366.6 5379.0 25671.2 26353.6 26451.6 26493.9 26585.9 2279.9 2389.9 2838.8 3135.7 3270.5 15633.9 16004.0 16006.6 16045.4 16274.0 1825.4 1882.7 1899.7 1919.7 1995.0 124480.4 124595.8 128303.3 129275.0 130211.0 10662.8 10680.1 10807.9 11077.4 11351.6 13384.0 13864.5 13867.2 13868.0 13874.7 7229.9 8583.8 8642.3 8692.8 8828.7 7087.4 8693.1 8722.2 8876.8 8947.0 6569.2 6711.3 6711.9 6726.8 6728.4 1852.5 1876.4 1894.7 1919.2 1919.8 385.8 389.2 728.5 786.0 925.7 11462.5 11774.0 12031.9 12120.9 12153.6 413.5 423.2 423.3 426.8 449.1 5790.1 6222.1 6222.6 6231.8 6247.7 522342.0 557055.0 620504.0 626795.0 632094.0 135954.0 137610.0 138251.3 138512.0 146308.0 956.1 990.6 1000.4 1001.0 1029.0 17870.4 19426.4 19744.7 19834.0 19982.0 39340.8 51268.2 54763.2 57275.6 58293.1 82082.0 84481.7 85989.8 86168.6 86886.0 6563.6 7237.3 7543.4 8235.2 8370.8 12246.4 12496.4 13096.2 13595.0 13633.2 10570.8 11229.7 11347.9 11412.5 11679.5 11097.0 11419.6 11604.9 11745.9 11894.8 10870.9 11470.3 11627.8 11756.5 11767.2 6118.9 6404.4 7482.6 7532.8 7538.4 9910.8 11323.8 11650.7 11654.6 11668.7 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#3] mootools.html Cycles:180 Average:5699.51 Median:5115.60 stddev:3161.54 (61.8%) stddev-sans-first:3153.67 19:06:56 INFO - PROCESS | 7232 | Values: 1370.2 1505.8 1671.6 1850.2 1860.9 8512.0 9074.4 9089.5 9102.7 9150.3 7890.7 8070.1 8513.0 8679.6 8849.5 2081.0 2363.2 2376.3 2417.1 2419.7 2375.5 2398.4 2425.0 2429.1 2437.4 2400.7 2416.5 2427.1 2428.3 2434.4 2384.9 2385.0 2402.5 2405.2 2418.5 2369.4 2381.5 2386.2 2388.7 2397.5 8784.6 8987.6 8998.5 9006.9 9007.1 5286.8 5456.8 5456.9 5457.8 5463.6 3538.9 3626.9 3681.6 3685.4 3704.3 1085.8 1093.3 1102.7 1104.1 1116.4 9917.4 11564.5 11626.8 11745.9 11752.5 4355.7 4433.6 4618.4 4643.2 4657.2 7869.2 9228.3 9244.9 9253.4 9256.9 5301.7 5307.9 5326.9 5335.0 5335.9 4393.1 4541.5 4786.8 4899.6 4969.0 3747.2 3771.2 3863.8 3874.9 3922.6 1018.9 1172.3 1191.3 1198.1 1202.0 852.6 865.4 898.7 918.2 947.5 5317.6 5876.4 5906.2 6045.8 6078.9 908.6 953.9 958.3 968.2 985.3 3931.8 3989.1 4007.3 4066.9 4102.4 10423.6 10804.4 10839.7 10875.0 10896.3 9643.9 10694.1 10709.8 10833.4 10839.4 8059.1 8279.3 8290.4 8291.9 8300.4 5847.2 5914.4 5935.6 6070.3 6194.0 9797.2 9867.9 9951.6 10034.6 10047.7 9915.9 10134.0 10195.9 10314.4 10452.8 6775.2 7018.3 7114.1 7714.1 7867.3 7975.0 8664.2 8674.9 8686.1 8686.5 7500.8 7556.1 7559.1 7598.8 7694.1 7407.3 7439.7 7443.1 7452.9 7518.1 5934.4 6974.8 7039.8 7050.6 7056.0 4988.2 5033.8 5057.1 5080.9 5168.4 4894.6 5012.5 5021.1 5150.3 5156.8 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#4] prototype.html Cycles:180 Average:4509.02 Median:4944.56 stddev:1382.92 (28.0%) stddev-sans-first:1349.40 19:06:56 INFO - PROCESS | 7232 | Values: 252.7 288.6 307.5 313.4 314.5 4255.9 4370.9 4846.0 5280.0 5448.4 4333.7 5140.0 5286.5 5370.5 5379.8 4124.8 4136.7 4141.3 4141.5 4150.2 3390.4 3986.4 4142.9 4184.6 4187.2 3421.8 4052.8 4084.6 4086.6 4111.9 4032.6 4035.6 4070.7 4075.0 4090.9 3157.7 3543.6 3553.9 3565.6 3600.7 4270.0 5084.6 5208.6 5248.8 5267.7 4688.2 4691.0 4697.5 4706.5 4712.6 5109.9 5224.3 5570.8 5616.8 5634.4 3092.6 3227.9 3787.6 3882.2 3925.6 4804.3 5540.5 5892.9 5919.0 5920.4 2995.2 2998.0 3985.8 3997.3 4010.9 3881.2 3925.0 4048.1 4058.0 4065.3 4128.7 4665.5 4670.5 4672.7 4682.6 5369.5 5609.6 5633.8 5649.8 5702.4 1173.4 1290.1 1326.9 1339.3 1353.6 790.6 899.8 937.7 944.7 944.8 4926.5 5211.6 5235.1 5238.6 5241.5 4695.7 5036.4 5484.3 5725.9 5731.4 4331.7 4360.6 4851.0 5154.4 5225.5 2987.9 3018.0 3031.8 3039.6 3051.7 5188.5 5870.4 5920.3 5931.2 5952.6 4818.3 4823.5 5778.4 5836.6 5897.1 4962.6 5834.3 5892.4 5925.5 5939.9 5453.5 5458.0 5711.9 5726.9 5754.6 5174.3 5177.5 5316.3 5577.1 5631.6 5410.4 5892.6 5950.0 5970.8 5971.0 3693.0 3924.8 3948.4 3966.0 3970.7 4629.4 4808.6 5620.1 5759.9 5916.2 4822.5 5712.8 5714.1 5731.0 5737.5 4486.0 4487.6 5364.0 5685.1 5715.8 5493.3 5550.5 5582.8 5624.0 5651.4 5345.5 5428.1 5719.2 5725.2 5739.5 4363.0 4578.2 4907.0 5564.5 5597.4 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - PROCESS | 7232 | [#5] yui.html Cycles:180 Average:25192.24 Median:3734.13 stddev:40329.82 (1080.0%) stddev-sans-first:40400.89 19:06:56 INFO - PROCESS | 7232 | Values: 659.3 682.5 748.5 778.3 804.4 15514.9 16883.2 17092.7 17150.9 17165.0 15297.2 15457.0 15479.4 15489.5 15614.8 831.1 848.5 850.1 850.8 852.9 680.8 693.4 840.8 854.4 862.6 902.9 905.1 905.3 905.7 906.0 898.7 901.1 901.2 901.4 901.5 526.1 657.3 670.6 671.1 678.7 13013.8 15424.3 15447.0 15571.8 15592.7 13338.9 13990.8 14080.5 14091.3 14149.9 11598.9 11874.8 11880.2 12008.4 12377.0 4608.5 4651.3 4753.8 4872.5 4879.7 113332.3 114512.4 114730.0 118261.4 122959.0 9874.0 11057.7 11074.6 11321.4 11382.3 20292.7 21752.2 21756.7 21758.8 21777.4 7794.4 8156.2 8164.3 8252.0 8253.4 4377.8 4470.6 4645.6 4651.3 4689.5 3571.5 3702.9 3765.3 3767.5 3770.2 618.4 665.0 667.3 673.2 690.5 558.3 650.5 660.0 668.9 680.6 7744.4 8182.4 9151.8 9919.2 10189.1 518.5 582.6 604.7 637.7 678.5 4269.9 4570.1 4618.8 4675.7 4693.2 147548.0 151309.0 152428.0 153554.2 153559.0 129081.0 132483.0 133781.0 134898.0 139282.0 106082.0 109157.0 109667.0 111366.0 113326.4 8866.4 8866.7 8870.7 8934.3 9018.1 59868.7 60117.8 60382.7 60728.7 62475.6 63464.0 88900.0 91238.0 91471.0 94095.5 11799.5 12231.8 12793.8 13161.3 13510.8 15046.3 15279.2 15443.3 15626.2 16035.6 13868.4 14083.3 14255.8 14435.5 14669.3 12886.1 13619.3 13762.7 14033.7 14312.9 11931.7 12552.9 13721.5 14259.0 14551.9 11173.8 11324.3 11469.2 11528.2 11709.2 9494.4 11320.6 11502.2 11710.9 11811.3 19:06:56 INFO - PROCESS | 7232 | -------- Summary: end -------- 19:06:56 INFO - PROCESS | 7232 | 19:06:56 INFO - TEST-INFO | 7232: exit 0 19:06:56 INFO - TEST-OK | dromaeo_css | took 1119191ms 19:06:56 INFO - TEST-START | kraken 19:06:56 INFO - operating with platform_type : mac_ 19:06:56 INFO - Initialising browser for kraken test... 19:06:56 INFO - TEST-INFO | started process 7234 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpwvzv0a/profile http://localhost:50433/getInfo.html) 19:06:59 INFO - PROCESS | 7234 | __metrics Screen width/height:1600/1200 19:06:59 INFO - PROCESS | 7234 | colorDepth:24 19:06:59 INFO - PROCESS | 7234 | Browser inner width/height: 1024/689 19:06:59 INFO - PROCESS | 7234 | __metrics 19:06:59 INFO - PROCESS | 7234 | 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" 19:06:59 INFO - PROCESS | 7234 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 19:07:00 INFO - TEST-INFO | 7234: exit 0 19:07:00 INFO - Browser initialized. 19:07:00 INFO - Running cycle 1/1 for kraken test... 19:07:00 INFO - TEST-INFO | started process 7235 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/T/tmpwvzv0a/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:07:11 INFO - PROCESS | 7235 | RSS: Main: 228421632 19:07:11 INFO - PROCESS | 7235 | 19:07:14 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50433/tests/kraken/driver.html?testName=audio-beat-detection) 19:07:15 INFO - PROCESS | 7235 | RSS: Main: 284811264 19:07:15 INFO - PROCESS | 7235 | 19:07:18 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50433/tests/kraken/driver.html?testName=audio-dft) 19:07:18 INFO - PROCESS | 7235 | RSS: Main: 293822464 19:07:18 INFO - PROCESS | 7235 | 19:07:22 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50433/tests/kraken/driver.html?testName=audio-fft) 19:07:23 INFO - PROCESS | 7235 | RSS: Main: 387346432 19:07:23 INFO - PROCESS | 7235 | 19:07:25 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50433/tests/kraken/driver.html?testName=audio-oscillator) 19:07:25 INFO - PROCESS | 7235 | RSS: Main: 290385920 19:07:25 INFO - PROCESS | 7235 | 19:07:27 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50433/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:07:28 INFO - PROCESS | 7235 | RSS: Main: 294416384 19:07:28 INFO - PROCESS | 7235 | 19:07:31 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50433/tests/kraken/driver.html?testName=imaging-darkroom) 19:07:31 INFO - PROCESS | 7235 | RSS: Main: 333496320 19:07:31 INFO - PROCESS | 7235 | 19:07:34 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50433/tests/kraken/driver.html?testName=imaging-desaturate) 19:07:35 INFO - PROCESS | 7235 | RSS: Main: 332431360 19:07:35 INFO - PROCESS | 7235 | 19:07:38 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50433/tests/kraken/driver.html?testName=json-parse-financial) 19:07:38 INFO - PROCESS | 7235 | RSS: Main: 333590528 19:07:38 INFO - PROCESS | 7235 | 19:07:40 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50433/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:07:40 INFO - PROCESS | 7235 | RSS: Main: 300597248 19:07:40 INFO - PROCESS | 7235 | 19:07:42 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:07:43 INFO - PROCESS | 7235 | RSS: Main: 307593216 19:07:43 INFO - PROCESS | 7235 | 19:07:45 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:07:46 INFO - PROCESS | 7235 | RSS: Main: 300490752 19:07:46 INFO - PROCESS | 7235 | 19:07:49 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:07:50 INFO - PROCESS | 7235 | RSS: Main: 315064320 19:07:50 INFO - PROCESS | 7235 | 19:07:52 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:07:53 INFO - PROCESS | 7235 | RSS: Main: 317292544 19:07:53 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | Cycle 1(1): loaded http://localhost:50433/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50433/tests/kraken/driver.html?testName=ai-astar) 19:07:55 INFO - PROCESS | 7235 | RSS: Main: 360636416 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | __start_tp_report 19:07:55 INFO - PROCESS | 7235 | _x_x_mozilla_page_load 19:07:55 INFO - PROCESS | 7235 | _x_x_mozilla_page_load_details 19:07:55 INFO - PROCESS | 7235 | |i|pagename|runs| 19:07:55 INFO - PROCESS | 7235 | |0;ai-astar;102;113;112;112;107;113;112;115;113;111 19:07:55 INFO - PROCESS | 7235 | |1;audio-beat-detection;147;116;107;170;107;107;130;111;161;136 19:07:55 INFO - PROCESS | 7235 | |2;audio-dft;180;187;186;185;187;183;181;187;185;185 19:07:55 INFO - PROCESS | 7235 | |3;audio-fft;76;58;63;89;59;58;67;65;82;57 19:07:55 INFO - PROCESS | 7235 | |4;audio-oscillator;86;94;96;71;70;70;97;71;67;104 19:07:55 INFO - PROCESS | 7235 | |5;imaging-gaussian-blur;88;86;89;86;88;84;89;85;89;85 19:07:55 INFO - PROCESS | 7235 | |6;imaging-darkroom;98;94;93;95;93;95;95;94;93;93 19:07:55 INFO - PROCESS | 7235 | |7;imaging-desaturate;87;84;85;84;86;101;135;84;84;86 19:07:55 INFO - PROCESS | 7235 | |8;json-parse-financial;55;51;52;55;53;54;53;52;53;50 19:07:55 INFO - PROCESS | 7235 | |9;json-stringify-tinderbox;61;73;84;77;76;72;68;69;69;69 19:07:55 INFO - PROCESS | 7235 | |10;stanford-crypto-aes;86;86;85;84;84;85;102;85;85;86 19:07:55 INFO - PROCESS | 7235 | |11;stanford-crypto-ccm;141;136;142;134;143;140;141;144;231;135 19:07:55 INFO - PROCESS | 7235 | |12;stanford-crypto-pbkdf2;171;171;171;168;168;168;167;168;167;167 19:07:55 INFO - PROCESS | 7235 | |13;stanford-crypto-sha256-iterative;68;69;68;66;67;66;67;68;66;68 19:07:55 INFO - PROCESS | 7235 | __end_tp_report 19:07:55 INFO - PROCESS | 7235 | __start_cc_report 19:07:55 INFO - PROCESS | 7235 | _x_x_mozilla_cycle_collect,2068 19:07:55 INFO - PROCESS | 7235 | __end_cc_report 19:07:55 INFO - PROCESS | 7235 | __startTimestamp1471572475501__endTimestamp 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | ------- Summary: start ------- 19:07:55 INFO - PROCESS | 7235 | Number of tests: 14 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#0] ai-astar Cycles:10 Average:111.00 Median:112.50 stddev:3.77 (3.4%) stddev-sans-first:2.18 19:07:55 INFO - PROCESS | 7235 | Values: 102.0 113.0 112.0 112.0 107.0 113.0 112.0 115.0 113.0 111.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#1] audio-beat-detection Cycles:10 Average:129.20 Median:133.00 stddev:23.63 (17.8%) stddev-sans-first:24.16 19:07:55 INFO - PROCESS | 7235 | Values: 147.0 116.0 107.0 170.0 107.0 107.0 130.0 111.0 161.0 136.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#2] audio-dft Cycles:10 Average:184.60 Median:185.50 stddev:2.50 (1.3%) stddev-sans-first:2.03 19:07:55 INFO - PROCESS | 7235 | Values: 180.0 187.0 186.0 185.0 187.0 183.0 181.0 187.0 185.0 185.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#3] audio-fft Cycles:10 Average:67.40 Median:66.00 stddev:11.23 (17.0%) stddev-sans-first:11.47 19:07:55 INFO - PROCESS | 7235 | Values: 76.0 58.0 63.0 89.0 59.0 58.0 67.0 65.0 82.0 57.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#4] audio-oscillator Cycles:10 Average:82.60 Median:78.50 stddev:14.21 (18.1%) stddev-sans-first:15.01 19:07:55 INFO - PROCESS | 7235 | Values: 86.0 94.0 96.0 71.0 70.0 70.0 97.0 71.0 67.0 104.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#5] imaging-gaussian-blur Cycles:10 Average:86.90 Median:88.00 stddev:1.91 (2.2%) stddev-sans-first:1.99 19:07:55 INFO - PROCESS | 7235 | Values: 88.0 86.0 89.0 86.0 88.0 84.0 89.0 85.0 89.0 85.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#6] imaging-darkroom Cycles:10 Average:94.30 Median:94.50 stddev:1.57 (1.7%) stddev-sans-first:0.93 19:07:55 INFO - PROCESS | 7235 | Values: 98.0 94.0 93.0 95.0 93.0 95.0 95.0 94.0 93.0 93.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#7] imaging-desaturate Cycles:10 Average:91.60 Median:84.50 stddev:16.09 (19.0%) stddev-sans-first:16.98 19:07:55 INFO - PROCESS | 7235 | Values: 87.0 84.0 85.0 84.0 86.0 101.0 135.0 84.0 84.0 86.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#8] json-parse-financial Cycles:10 Average:52.80 Median:53.00 stddev:1.62 (3.1%) stddev-sans-first:1.51 19:07:55 INFO - PROCESS | 7235 | Values: 55.0 51.0 52.0 55.0 53.0 54.0 53.0 52.0 53.0 50.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#9] json-stringify-tinderbox Cycles:10 Average:71.80 Median:72.50 stddev:6.23 (8.6%) stddev-sans-first:5.24 19:07:55 INFO - PROCESS | 7235 | Values: 61.0 73.0 84.0 77.0 76.0 72.0 68.0 69.0 69.0 69.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#10] stanford-crypto-aes Cycles:10 Average:86.80 Median:85.00 stddev:5.39 (6.3%) stddev-sans-first:5.71 19:07:55 INFO - PROCESS | 7235 | Values: 86.0 86.0 85.0 84.0 84.0 85.0 102.0 85.0 85.0 86.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#11] stanford-crypto-ccm Cycles:10 Average:148.70 Median:141.50 stddev:29.12 (20.6%) stddev-sans-first:30.75 19:07:55 INFO - PROCESS | 7235 | Values: 141.0 136.0 142.0 134.0 143.0 140.0 141.0 144.0 231.0 135.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:168.00 stddev:1.71 (1.0%) stddev-sans-first:1.58 19:07:55 INFO - PROCESS | 7235 | Values: 171.0 171.0 171.0 168.0 168.0 168.0 167.0 168.0 167.0 167.0 19:07:55 INFO - PROCESS | 7235 | 19:07:55 INFO - PROCESS | 7235 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.09 19:07:55 INFO - PROCESS | 7235 | Values: 68.0 69.0 68.0 66.0 67.0 66.0 67.0 68.0 66.0 68.0 19:07:55 INFO - PROCESS | 7235 | -------- Summary: end -------- 19:07:55 INFO - PROCESS | 7235 | 19:07:56 INFO - TEST-INFO | 7235: exit 0 19:07:56 INFO - TEST-OK | kraken | took 59448ms 19:07:56 INFO - SUITE-END | took 1178s 19:07:56 INFO - Completed test suite (00:19:38) 19:07:56 INFO - javascript benchmark 19:07:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3330.6169790474096, 3335.5497007903928, 3345.7360320009566, 3358.2108204571937, 3398.558187435633, 5724.0, 5779.595428320009, 5828.358880523145, 5930.525557955359, 5978.9402105978925, 5833.766649334032, 5849.210356601858, 5861.208736820538, 5873.970630146854, 5879.294484661847, 4745.976270118645, 5095.719735414551, 5260.053190425722, 5297.993381257549, 5333.919991200135, 5364.168553874156, 5375.650582712111, 5377.0, 5378.865528361783, 5388.191771234327, 5261.0, 5299.973500132494, 5300.893982120355, 5357.142857142858, 5362.865928351784, 5377.247185394048, 5390.5418039466695, 5400.486953739389, 5407.297051383334, 5419.674819510842, 4270.658347332206, 5133.25567792668, 5260.736963151827, 5275.0, 5325.467453254682, 5702.030654788696, 5728.283964504437, 5782.653040817522, 5788.507976821975, 5810.88378232433, 6254.249490061176, 6258.624482531062, 6264.342244064399, 6265.0915617235705, 6273.592216505913, 6145.661988590626, 6186.381361863822, 6250.281217659982, 6265.592736472115, 6316.873662526769, 6033.7586496540525, 6049.48579370754, 6055.666938318347, 6060.454559089615, 6065.727042283108, 6369.394907483782, 6383.872322553616, 6385.297617262097, 6392.296847346788, 6399.104125422445, 6141.854248382324, 6144.324124346318, 6194.473469755031, 6224.875502489925, 6228.4705800007505, 6253.1245625612455, 6268.310485846553, 6276.717547710364, 6287.9056814147825, 6293.37066293367, 6156.846078848064, 6200.007998720183, 6228.750849966042, 6230.626162430269, 6241.18864547615, 5293.20601909709, 5851.473367396869, 5883.02930016551, 5892.469677729025, 5961.821145365646, 4817.8313759018265, 4852.708837469763, 4852.757362131879, 4854.660173787801, 5022.799088036438, 4547.658925580595, 4553.0, 4564.064366804797, 4564.383808185852, 4579.656525760582, 5887.20522729426, 5891.351951285355, 5894.32215295238, 5897.351291357947, 5960.6721630309885, 6212.192414986023, 6237.937620623827, 6269.62382257066, 6272.383605252613, 6325.462335701517, 5785.508231800344, 5796.62321949072, 5805.564582656318, 5809.506191973645, 5818.912716309259, 5509.815389691155, 5513.641613295204, 5520.779168833202, 5525.530329922001, 5526.143447765603, 5324.254604355471, 6233.657148856856, 6265.060240963892, 6319.873602527831, 6372.235331760218, 6319.6208227506495, 6328.0, 6332.398422149889, 6340.4293613575, 6342.556021078481, 6362.586431881913, 6367.331430199948, 6374.29882712892, 6382.776602818879, 6390.968045159931, 6110.832160913912, 6204.255489341308, 6237.1891654084675, 6279.434850863444, 6293.465055470337, 6284.465820405134, 6327.746890124344, 6334.714937827716, 6339.270983836917, 6344.651044192429, 6196.876062478816, 6202.0, 6207.689615519115, 6215.9378406217165, 6224.9377506224355, 5936.5844390892225, 6024.66864322449, 6114.633122012693, 6177.351378105414, 6210.875782484235, 6027.4876635484725, 6102.0651310565, 6105.641896956745, 6128.228138398679, 6130.693465326626, 6207.6275423474735, 6222.284437289589, 6229.50163986889, 6238.344973777869, 6245.313015568375, 5897.498712609477, 6009.54928380382, 6076.179715738495, 6088.665123418255, 6096.603720758137], "value": 5759.833973193877, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [453.8615722204726, 461.4716150008243, 475.9333693282939, 514.8827045311673, 520.2014907117575, 31166.376672466537, 40776.59223407769, 41913.37129943053, 42369.364459533055, 44199.0, 44085.33871991923, 44305.77847110768, 44445.55554444455, 44450.77774611123, 44583.77708111463, 8213.42506024577, 8849.469031858076, 9882.654107106253, 9884.159846413064, 9887.950560247189, 8789.0, 12009.159358844885, 12224.755504889941, 12244.204126731778, 12477.875221247763, 12112.636620901387, 12167.087468439859, 12189.20770149942, 12211.755764884698, 12222.694432639166, 12100.576479823209, 12150.088743344284, 12171.330576818227, 12171.513139474411, 12175.878241217562, 11950.521979120826, 12008.519659213622, 12016.218945768496, 12032.954397752403, 12072.336021518813, 33329.333413331595, 40962.0, 40997.2354561497, 41017.58982410168, 41124.177516449505, 3371.831408429569, 3437.226624009603, 3440.724742020632, 3442.6557344265625, 3559.2525569630407, 31133.532997004837, 32055.679443205503, 32116.518252226237, 32152.6784732152, 32264.193395165075, 3242.319112986252, 3346.0965539304493, 3374.54443650109, 3379.3241351729507, 3412.692857642824, 387123.0, 387531.0, 388514.0, 388662.0, 391868.0406597977, 62070.68964655148, 62225.68887155535, 62494.0, 62498.37501624926, 62586.68706656437, 62051.0, 62289.68855155786, 62843.37156628375, 62879.37120628827, 62884.74230515364, 16137.59656008609, 16333.183340833148, 16334.75497867533, 16348.836511634969, 16354.591135221477, 11516.251443656136, 11681.0, 12065.698357541132, 12755.68110797219, 12863.22820630765, 14230.715385692436, 16594.58513537171, 17168.79818412724, 17317.82682173162, 17361.218745156497, 6265.498760099181, 7361.374283185882, 7403.85192296151, 7407.185209626937, 7482.812929676802, 879.9164079412445, 935.7988032573029, 1014.7868947520956, 1042.801867645145, 1086.7880763251248, 25470.872645636653, 29121.8351265947, 30672.846635767124, 30719.385612287628, 31513.842430788158, 1003.6687892995294, 1057.5135437698746, 1061.5382308695741, 1090.7654854206226, 1125.4766533561933, 12630.368481575884, 15171.468998584996, 16059.116748578941, 16075.437359692354, 16501.33994640201, 600017.0, 666767.0, 709866.9013309801, 710844.1324694012, 927676.0, 60113.39886601165, 60821.0, 60921.695391523645, 60995.0, 61018.47453813601, 1165.9533618655328, 1232.6548566401423, 1271.5104684696273, 1351.6755978565075, 1358.4634069542476, 25555.84998675027, 26347.473050539265, 28590.42819143564, 29964.501774910732, 33243.5299214682, 56763.71618141883, 56977.57556060966, 57339.42660573341, 57460.71269643625, 57513.0, 471006.0, 473356.9307376117, 474635.0, 474695.0, 475850.0, 23677.0, 24164.0, 26335.34161645974, 26511.07211247597, 26975.920963161254, 43538.564614353454, 47413.05173896572, 47595.76202118967, 48347.0, 48814.75592622014, 38653.0, 38698.0, 38735.418968716054, 39369.0, 40033.39949900812, 38548.42177367398, 39044.2191156181, 39515.01212469711, 39594.60405395909, 40899.0, 38144.80927595344, 38791.22417551577, 39109.82670519889, 39155.804220978716, 39159.0, 33198.66801332052, 34123.14692132716, 35015.0, 35046.29907401888, 35063.64936350604, 25363.746362536138, 25476.872615636803, 25566.0, 25762.87118564395, 26301.34246643849], "value": 20293.959826954124, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.0654442821024, 366.8440912612141, 376.76452217364147, 383.8387877091622, 405.42227326060356, 15586.532404027868, 15762.842371576295, 20331.695024574645, 20550.972451377445, 20662.793372066273, 17869.553261168476, 19720.605587888233, 20318.390448286536, 20634.0, 21447.356579302646, 5440.564754819625, 6071.362506936764, 6249.500039996789, 6313.842153946165, 6330.905036424458, 6230.688465576703, 6270.31026587075, 6282.7172777224905, 6290.591111577733, 6294.024426213944, 6131.172291740625, 6147.415995480422, 6218.751249949996, 6252.874942501147, 6254.6247225166635, 6020.5785595008365, 6109.3890610939, 6194.34959329273, 6205.999999999977, 6236.968815155895, 5169.276301317818, 5356.223347614613, 5362.544183744387, 5366.597505187087, 5379.0, 25671.229863104138, 26353.604695929578, 26451.603225951625, 26493.867530662417, 26585.867070664524, 2279.943001424961, 2389.928302150938, 2838.843863587502, 3135.686431356869, 3270.4767237241927, 15633.92183039089, 16004.0, 16006.5998350041, 16045.438409655722, 16274.0, 1825.3702472647049, 1882.7458293130526, 1899.6770549006565, 1919.6928491441302, 1995.012468827925, 124480.37759811143, 124595.75404246023, 128303.32056832552, 129275.0, 130211.0, 10662.786744265226, 10680.145588352912, 10807.891921080845, 11077.390743509022, 11351.602693905674, 13384.0, 13864.514741983981, 13867.23730194849, 13868.0, 13874.65313367174, 7229.8554028919125, 8583.828323433621, 8642.30861531076, 8692.826143477096, 8828.690995815245, 7087.362137407658, 8693.087225841196, 8722.171393717586, 8876.778080548038, 8947.0, 6569.24453687832, 6711.328867113347, 6711.932880671228, 6726.831829204211, 6728.394444500019, 1852.4535262097713, 1876.4464482977555, 1894.6968485042328, 1919.184346652667, 1919.8368138708088, 385.77624977512977, 389.24098008882794, 728.4995596160394, 785.9743035338823, 925.745614692096, 11462.48418821155, 11774.0, 12031.931857376312, 12120.939395302967, 12153.574624888264, 413.4624987516217, 423.2276096124585, 423.32268370606783, 426.7994042799879, 449.07266494688366, 5790.131480277992, 6222.12890195373, 6222.639774521707, 6231.75072997075, 6247.656378899204, 522342.0, 557055.0, 620504.0, 626795.0, 632094.0, 135954.0, 137610.0, 138251.30874345565, 138512.0, 146308.0, 956.0726095687172, 990.5839547390113, 1000.4197565412044, 1000.979980400388, 1028.994855025735, 17870.374536891148, 19426.4172074838, 19744.703829442857, 19834.008299584675, 19982.0, 39340.803295983336, 51268.230976534636, 54763.209003536314, 57275.55882191185, 58293.12560311514, 82082.0, 84481.70701222983, 85989.81653920742, 86168.56915715382, 86886.0, 6563.621639455577, 7237.3486386225495, 7543.43424243173, 8235.21765432283, 8370.790730231793, 12246.448909798903, 12496.437660305126, 13096.214227146402, 13595.0, 13633.182009079486, 10570.841437378293, 11229.66311010671, 11347.886521134684, 11412.543498259976, 11679.474423650787, 11097.0, 11419.60031398897, 11604.94197529007, 11745.882541174478, 11894.821577676516, 10870.891291087304, 11470.254433461798, 11627.767444651228, 11756.470958807045, 11767.23512971654, 6118.911509215638, 6404.359564043559, 7482.625868706434, 7532.811679708051, 7538.3969282458365, 9910.819619683658, 11323.830142547704, 11650.6504804856, 11654.592089277174, 11668.70828229301], "value": 9285.438286859395, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1370.2121280263848, 1505.796717443145, 1671.5821044738816, 1850.2414010255798, 1860.925562977481, 8511.957440212798, 9074.36479446439, 9089.500077495735, 9102.726918192448, 9150.313726470516, 7890.684372625088, 8070.071941726703, 8513.0, 8679.609417576208, 8849.469031858076, 2081.0, 2363.196513185517, 2376.32274801682, 2417.129833260025, 2419.721732000825, 2375.4655202579456, 2398.352444839892, 2425.0178677635586, 2429.0648100481353, 2437.427204606916, 2400.711914570254, 2416.4804567018086, 2427.0655797517993, 2428.3079322393123, 2434.354895952565, 2384.9284521464383, 2384.95230095398, 2402.543516731824, 2405.230326295586, 2418.504206637635, 2369.3721163891582, 2381.4522659788345, 2386.248331775485, 2388.689470368858, 2397.544466551367, 8784.560771961376, 8987.595558199897, 8998.460092394478, 9006.909930900672, 9007.144321289532, 5286.788528458857, 5456.781728730846, 5456.863578410532, 5457.781688732485, 5463.590230732671, 3538.9115272118147, 3626.8911932642063, 3681.631836816324, 3685.4103343464917, 3704.2776658551593, 1085.8154113800672, 1093.316677076827, 1102.6802227354058, 1104.0725790336155, 1116.4027245423658, 9917.355371900803, 11564.479598418091, 11626.76746465066, 11745.882541174564, 11752.47113879869, 4355.673324500612, 4433.578810013044, 4618.445786505574, 4643.164230438553, 4657.184992626277, 7869.1737367576725, 9228.307876909124, 9244.90755092454, 9253.398529095723, 9256.8611470828, 5301.6818990860675, 5307.893842123213, 5326.946730532645, 5335.039692855245, 5335.919961200508, 4393.055493069006, 4541.455025396974, 4786.832460863853, 4899.5590396863745, 4968.975155124273, 3747.2318174774105, 3771.245750849786, 3863.7874916879277, 3874.9150237933422, 3922.627350401707, 1018.8675472188568, 1172.28490620723, 1191.2971346905367, 1198.0894520164738, 1201.9759604807855, 852.6461518469765, 865.3726048614704, 898.6854600889636, 918.1598837064145, 947.5215016416665, 5317.627766056339, 5876.412358764175, 5906.202662640574, 6045.78839740607, 6078.908816367758, 908.6410867707218, 953.8521529162991, 958.3195930889008, 968.162539403411, 985.2955137076881, 3931.8230679619487, 3989.1024519482885, 4007.2987227235117, 4066.898327541835, 4102.446169767103, 10423.635172768916, 10804.351738895692, 10839.729006774895, 10875.0, 10896.346219226873, 9643.855342169873, 10694.091002264739, 10709.839352409721, 10833.399984003783, 10839.403832789272, 8059.073206581201, 8279.337652987746, 8290.419670622998, 8291.875621865676, 8300.377471689648, 5847.210626565529, 5914.408559144051, 5935.584509084322, 6070.271567411939, 6194.0320709248535, 9797.20779577817, 9867.851982220416, 9951.552180152055, 10034.598616055277, 10047.739258666741, 9915.900840991499, 10134.0, 10195.898041019494, 10314.381137131797, 10452.843207351743, 6775.220849602359, 7018.345661472092, 7114.110736157981, 7714.0743110824815, 7867.331276841533, 7974.960125199337, 8664.220220180214, 8674.91325086741, 8686.131386861263, 8686.478811271343, 7500.774976750488, 7556.093268807778, 7559.130699969354, 7598.848023039619, 7694.115176754521, 7407.259274072765, 7439.665215065227, 7443.106827180773, 7452.888206676796, 7518.097828260425, 5934.43622855828, 6974.755883544051, 7039.824004399931, 7050.612216328152, 7056.0, 4988.152014157675, 5033.84898453047, 5057.089723849595, 5080.949190508048, 5168.3797944246935, 4894.632902532396, 5012.546361555108, 5021.07110184617, 5150.253213284128, 5156.819511317086], "value": 4593.001504415947, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [252.7434653826366, 288.587320132211, 307.49877699350054, 313.42173689542784, 314.5093653899918, 4255.872323830282, 4370.8688739337795, 4846.006568653426, 5280.0, 5448.37343705474, 4333.7399756014665, 5139.974300128503, 5286.466924591873, 5370.489803468664, 5379.838604841861, 4124.814383352741, 4136.66906647469, 4141.316682747343, 4141.503019637647, 4150.190712811011, 3390.372781035504, 3986.441898134249, 4142.855000074998, 4184.644305234044, 4187.225363307783, 3421.760476766627, 4052.7973601319964, 4084.550699423061, 4086.5913408659194, 4111.897202569945, 4032.576579459144, 4035.6367926886574, 4070.715049946475, 4075.0219947212754, 4090.9386359204486, 3157.715805577486, 3543.557055368079, 3553.875614353485, 3565.5899571549344, 3600.6579374959338, 4270.0, 5084.593232541388, 5208.583313334924, 5248.81901572147, 5267.656747529356, 4688.203005489075, 4691.0, 4697.533794757745, 4706.5293470653005, 4712.6465515086165, 5109.901371205208, 5224.320838290998, 5570.805021824217, 5616.803411880564, 5634.408387119334, 3092.6443459002053, 3227.8547465364113, 3787.6023017583034, 3882.1847412043503, 3925.568187499373, 4804.327394164785, 5540.50135487804, 5892.882142357172, 5918.970405147947, 5920.437558431942, 2995.206270338362, 2997.970020299769, 3985.7608543487486, 3997.260506806192, 4010.8942805027596, 3881.204353107606, 3924.9980458063474, 4048.1296521248073, 4058.0, 4065.3495440729343, 4128.720096770011, 4665.510121437268, 4670.497127154634, 4672.696274742131, 4682.648801339913, 5369.48989845964, 5609.551235901118, 5633.802816901389, 5649.774009039593, 5702.429757024347, 1173.4191575170346, 1290.1291628150962, 1326.907116501855, 1339.3102552185633, 1353.5668586052566, 790.5770412829108, 899.820035992804, 937.7280588629221, 944.6693657219918, 944.8393773058597, 4926.5073492650445, 5211.557017653542, 5235.110031294689, 5238.580913526908, 5241.453771137523, 4695.694779839368, 5036.445990940993, 5484.2596249506605, 5725.914111288334, 5731.3665605302285, 4331.6534677225745, 4360.645251564183, 4850.990422937346, 5154.355705536808, 5225.503577160163, 2987.9402411951637, 3017.969820301813, 3031.8332491712727, 3039.5592639067213, 3051.71008754168, 5188.5330320270605, 5870.383609721003, 5920.289565252111, 5931.228940237742, 5952.613080149777, 4818.349522814446, 4823.541763532458, 5778.393268706809, 5836.5992161881695, 5897.115432685132, 4962.60299176065, 5834.299884013945, 5892.351841297368, 5925.48167029011, 5939.851503712355, 5453.454654534515, 5457.9727101365825, 5711.857203569944, 5726.942730572641, 5754.597178197486, 5174.301469301596, 5177.4822517749435, 5316.2786049764545, 5577.051901176728, 5631.63394379364, 5410.404855465817, 5892.587518873637, 5950.047992321208, 5970.791022314198, 5970.984932561388, 3692.9815350923072, 3924.803759811941, 3948.44721738951, 3966.0, 3970.6823454123023, 4629.398178236942, 4808.639352048541, 5620.072688006597, 5759.9136012959, 5916.230889984275, 4822.493638168082, 5712.7714891403875, 5714.14295350645, 5731.0, 5737.454941780524, 4485.977570112129, 4487.551244875486, 5364.0, 5685.147227915846, 5715.828525144252, 5493.313335833021, 5550.5282051026115, 5582.804601838916, 5623.959567479866, 5651.378348381592, 5345.492178243061, 5428.123094759783, 5719.225311294525, 5725.169850371756, 5739.454751798572, 4362.978185109054, 4578.168732506891, 4906.975465122794, 5564.499195072301, 5597.384287728265], "value": 4069.010697756059, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.294554826336, 682.5120039171991, 748.5321673953779, 778.3034184404958, 804.4409135650722, 15514.922425387871, 16883.155842207903, 17092.658146837057, 17150.885192462498, 17165.0, 15297.158656273901, 15457.0, 15479.380824767024, 15489.5353139406, 15614.843851561482, 831.1107115386524, 848.5375470368664, 850.1158794853376, 850.8425941200867, 852.9402941794077, 680.8434060166164, 693.3898169610735, 840.7813968368238, 854.3763052971335, 862.6463150108457, 902.8510295801185, 905.1355955062899, 905.2803021597846, 905.741863568883, 905.9864102038474, 898.6899519665704, 901.1034021148998, 901.1664210605197, 901.4411065139656, 901.4681338010572, 526.1397614899629, 657.3262406033806, 670.6043434373743, 671.1174805131286, 678.7081554931377, 13013.804792928113, 15424.305906234247, 15447.0, 15571.844281557265, 15592.688146237011, 13338.866611333859, 13990.843870149483, 14080.521545237807, 14091.2954352283, 14149.929250353785, 11598.884011159866, 11874.762504749857, 11880.227785193934, 12008.399580020963, 12377.009839212931, 4608.4930657627965, 4651.3023046543285, 4753.83361582343, 4872.537108974677, 4879.707217566922, 113332.30001549983, 114512.42743786311, 114730.0, 118261.40869295684, 122959.0, 9874.012598740212, 11057.72355691098, 11074.557017719362, 11321.377324247082, 11382.260153090023, 20292.695609565868, 21752.238671646417, 21756.673649895263, 21758.78241217599, 21777.38671359447, 7794.3764498839955, 8156.184381561797, 8164.265216130577, 8251.95874020626, 8253.380996425292, 4377.8248870044845, 4470.552944705503, 4645.581897629229, 4651.348811166439, 4689.531046895283, 3571.4821350904494, 3702.9259414811554, 3765.3410653135593, 3767.453719210699, 3770.245950809849, 618.4217756397783, 664.9833754156087, 667.3393340592778, 673.1787219592089, 690.4786885901212, 558.3244274427907, 650.5413683353224, 659.986800263992, 668.9498287628447, 680.5576375356059, 7744.419168562267, 8182.386321025947, 9151.77120571991, 9919.206463482904, 10189.082982531607, 518.5436815602246, 582.6038293960063, 604.6583680220662, 637.6811594202899, 678.4606238040769, 4269.87190384289, 4570.085982803453, 4618.769061546909, 4675.719456832533, 4693.24908014716, 147548.0, 151309.0, 152428.0, 153554.23222884038, 153559.0, 129081.0, 132483.0, 133781.0, 134898.0, 139282.0, 106082.0, 109157.0, 109667.0, 111366.0, 113326.43336783264, 8866.42368246062, 8866.73399798007, 8870.689525866565, 8934.28525717928, 9018.053104423941, 59868.70065649644, 60117.79764404775, 60382.698086509285, 60728.6963565197, 62475.56579861842, 63464.0, 88900.0, 91238.0, 91471.0, 94095.52952235195, 11799.469023893773, 12231.755364892475, 12793.8080928788, 13161.30570164327, 13510.79733803974, 15046.32291546861, 15279.236038198267, 15443.305051272495, 15626.218689065274, 16035.599110022344, 13868.4452621894, 14083.295835208404, 14255.786163207767, 14435.494757683431, 14669.339879705225, 12886.09797314179, 13619.319034048456, 13762.65593360174, 14033.719325613227, 14312.856871431153, 11931.747166547388, 12552.87447125517, 13721.519746808814, 14259.0, 14551.85448145505, 11173.776524469627, 11324.32054076758, 11469.19715619932, 11528.193026488065, 11709.238899471507, 9494.430334179971, 11320.6037788677, 11502.194846360675, 11710.896612397892, 11811.291322520676], "value": 7912.545464116142, "unit": "score"}], "name": "dromaeo_css", "value": 7372.042765644021, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 113.0, 112.0, 112.0, 107.0, 113.0, 112.0, 115.0, 113.0, 111.0], "value": 111.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 107.0, 170.0, 107.0, 107.0, 130.0, 111.0, 161.0, 136.0], "value": 129.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [180.0, 187.0, 186.0, 185.0, 187.0, 183.0, 181.0, 187.0, 185.0, 185.0], "value": 184.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 58.0, 63.0, 89.0, 59.0, 58.0, 67.0, 65.0, 82.0, 57.0], "value": 67.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 94.0, 96.0, 71.0, 70.0, 70.0, 97.0, 71.0, 67.0, 104.0], "value": 82.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 88.0, 84.0, 89.0, 85.0, 89.0, 85.0], "value": 86.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 94.0, 93.0, 95.0, 93.0, 95.0, 95.0, 94.0, 93.0, 93.0], "value": 94.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 85.0, 84.0, 86.0, 101.0, 135.0, 84.0, 84.0, 86.0], "value": 91.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 51.0, 52.0, 55.0, 53.0, 54.0, 53.0, 52.0, 53.0, 50.0], "value": 52.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [61.0, 73.0, 84.0, 77.0, 76.0, 72.0, 68.0, 69.0, 69.0, 69.0], "value": 71.8, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 85.0, 84.0, 84.0, 85.0, 102.0, 85.0, 85.0, 86.0], "value": 86.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 136.0, 142.0, 134.0, 143.0, 140.0, 141.0, 144.0, 231.0, 135.0], "value": 148.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 171.0, 171.0, 168.0, 168.0, 168.0, 167.0, 168.0, 167.0, 167.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 69.0, 68.0, 66.0, 67.0, 66.0, 67.0, 68.0, 66.0, 68.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1443.5999999999997, "alertThreshold": 2.0}]} 19:07:56 INFO - Return code: 0 19:07:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:07:56 INFO - # TBPL SUCCESS # 19:07:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:07:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:07:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:07:56 INFO - 2016-08-18 19:07:56.807 screenresolution[7239:181027] starting screenresolution argv=screenresolution get 19:07:56 INFO - 2016-08-18 19:07:56.811 screenresolution[7239:181027] Display 0: 1600x1200x32@60 19:07:56 INFO - 2016-08-18 19:07:56.817 screenresolution[7240:181031] starting screenresolution argv=screenresolution list 19:07:56 INFO - Available Modes on Display 0 19:07:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:07:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:07:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:07:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:07:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:07:57 INFO - Intel Iris: 19:07:57 INFO - Chipset Model: Intel Iris 19:07:57 INFO - Type: GPU 19:07:57 INFO - Bus: Built-In 19:07:57 INFO - VRAM (Dynamic, Max): 1536 MB 19:07:57 INFO - Vendor: Intel (0x8086) 19:07:57 INFO - Device ID: 0x0a2e 19:07:57 INFO - Revision ID: 0x0009 19:07:57 INFO - Displays: 19:07:57 INFO - Display: 19:07:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:07:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:07:57 INFO - Display Serial Number: 1600x1200 60 19:07:57 INFO - Main Display: Yes 19:07:57 INFO - Mirror: Off 19:07:57 INFO - Online: Yes 19:07:57 INFO - Rotation: Supported 19:07:57 INFO - Return code: 0 19:07:57 INFO - Running post-action listener: _resource_record_post_action 19:07:57 INFO - [mozharness: 2016-08-19 02:07:57.066155Z] Finished run-tests step (success) 19:07:57 INFO - Running post-run listener: _resource_record_post_run 19:07:57 INFO - Total resource usage - Wall time: 1214s; CPU: 54.0%; Read bytes: 0; Write bytes: 705697280; Read time: 0; Write time: 1892 19:07:57 INFO - TinderboxPrint: CPU usage
53.5% 19:07:57 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 19:07:57 INFO - TinderboxPrint: I/O write bytes / time
705,697,280 / 1,892 19:07:57 INFO - TinderboxPrint: CPU idle
2,256.7 (46.5%) 19:07:57 INFO - TinderboxPrint: CPU system
973.7 (20.0%) 19:07:57 INFO - TinderboxPrint: CPU user
1,627.3 (33.5%) 19:07:57 INFO - TinderboxPrint: Swap in / out
404,721,664 / 0 19:07:57 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 392356352; Write bytes: 386340864; Read time: 29435; Write time: 734 19:07:57 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 0; Write bytes: 296679424; Read time: 0; Write time: 1114 19:07:57 INFO - Running post-run listener: _upload_blobber_files 19:07:57 INFO - Blob upload gear active. 19:07:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:07:57 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 19:07:57 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'] 19:07:57 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 19:07:57 INFO - (blobuploader) - INFO - Open directory for files ... 19:07:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 19:07:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:07:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 19:07:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:07:59 INFO - (blobuploader) - INFO - Done attempting. 19:07:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 19:07:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:07:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 19:08:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:00 INFO - (blobuploader) - INFO - Done attempting. 19:08:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:08:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:08:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:08:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:08:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:08:03 INFO - (blobuploader) - INFO - Done attempting. 19:08:03 INFO - (blobuploader) - INFO - Iteration through files over. 19:08:03 INFO - Return code: 0 19:08:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:08:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:08:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6376f413cd6ff5ddd71ac0687eaf8175e65b14b58c338b91e2a0bd539c5476e73d03aa001833de1c6ca2c94aaed5e3ae3a8f0e909e922c52ce75c2d0c243831", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50c07e2083746fb57b9f1b669edfecd7132d2648a5150197f13a1b2d7d84aeb9923047af691df7887eee1c876fd9a04c00d23512dbfc703efe47b689db9a2326", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0539089984ce54a87e60679eb660074f1b1f66d78f013516ca9cd85008b012e9069d073d5aea75cdf207a061fe59133f0fd77a1d88e396a0c1c51a899df7df23"} 19:08:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:08:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:08:03 INFO - Contents: 19:08:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c6376f413cd6ff5ddd71ac0687eaf8175e65b14b58c338b91e2a0bd539c5476e73d03aa001833de1c6ca2c94aaed5e3ae3a8f0e909e922c52ce75c2d0c243831", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50c07e2083746fb57b9f1b669edfecd7132d2648a5150197f13a1b2d7d84aeb9923047af691df7887eee1c876fd9a04c00d23512dbfc703efe47b689db9a2326", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0539089984ce54a87e60679eb660074f1b1f66d78f013516ca9cd85008b012e9069d073d5aea75cdf207a061fe59133f0fd77a1d88e396a0c1c51a899df7df23"} 19:08:03 INFO - Running post-run listener: copy_logs_to_upload_dir 19:08:03 INFO - Copying logs to upload dir... 19:08:03 INFO - mkdir: /builds/slave/test/build/upload/logs 19:08:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.514442 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-18 19:08:03.761215) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 19:08:03.764428) ========= 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.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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/c6376f413cd6ff5ddd71ac0687eaf8175e65b14b58c338b91e2a0bd539c5476e73d03aa001833de1c6ca2c94aaed5e3ae3a8f0e909e922c52ce75c2d0c243831", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50c07e2083746fb57b9f1b669edfecd7132d2648a5150197f13a1b2d7d84aeb9923047af691df7887eee1c876fd9a04c00d23512dbfc703efe47b689db9a2326", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0539089984ce54a87e60679eb660074f1b1f66d78f013516ca9cd85008b012e9069d073d5aea75cdf207a061fe59133f0fd77a1d88e396a0c1c51a899df7df23"} build_url:https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011302 build_url: 'https://queue.taskcluster.net/v1/task/d23S2IYFTpaeT-W9iM9FVg/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/c6376f413cd6ff5ddd71ac0687eaf8175e65b14b58c338b91e2a0bd539c5476e73d03aa001833de1c6ca2c94aaed5e3ae3a8f0e909e922c52ce75c2d0c243831", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/50c07e2083746fb57b9f1b669edfecd7132d2648a5150197f13a1b2d7d84aeb9923047af691df7887eee1c876fd9a04c00d23512dbfc703efe47b689db9a2326", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/0539089984ce54a87e60679eb660074f1b1f66d78f013516ca9cd85008b012e9069d073d5aea75cdf207a061fe59133f0fd77a1d88e396a0c1c51a899df7df23"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-18 19:08:03.798099) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 19:08:03.798419) ========= 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.cuJDHawgQn/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.MgwAtl2xFU/Listeners TMPDIR=/var/folders/7m/bcbqjw3n3vg902dvqyjs3pcm00000w/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.005286 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-18 19:08:03.846912) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 19:08:03.847236) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-18 19:08:03.847602) ========= ========= Total master_lag: 0.17 =========