builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0200 starttime: 1470657906.89 results: success (0) buildid: 20160805131321 builduid: 3935528495d04780899dc77e8ecc8206 revision: f54fea6078ddf0023a4307a25264c6ece653e503 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.885725) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.886199) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:06.886485) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.270696) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.270985) ========= 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.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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-08 05:05:07-- 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% 14.7M=0.001s 2016-08-08 05:05:07 (14.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.529929 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.833970) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.834244) ========= 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.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.036246 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:07.890319) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:05:07.890649) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev f54fea6078ddf0023a4307a25264c6ece653e503 --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 f54fea6078ddf0023a4307a25264c6ece653e503 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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-08 05:05:07,976 truncating revision to first 12 chars 2016-08-08 05:05:07,976 Setting DEBUG logging. 2016-08-08 05:05:07,976 attempt 1/10 2016-08-08 05:05:07,976 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/f54fea6078dd?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 05:05:09,102 unpacking tar archive at: fx-team-f54fea6078dd/testing/mozharness/ program finished with exit code 0 elapsedTime=1.380587 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:05:09.292857) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:09.293157) ========= script_repo_revision: f54fea6078ddf0023a4307a25264c6ece653e503 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:09.293543) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:09.293803) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 05:05:09.312286) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-08 05:05:09.312625) ========= /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.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:09 INFO - MultiFileLogger online at 20160808 05:05:09 in /builds/slave/test 05:05:09 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 05:05:09 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:05:09 INFO - {'append_to_log': False, 05:05:09 INFO - 'base_work_dir': '/builds/slave/test', 05:05:09 INFO - 'blob_upload_branch': 'Fx-Team', 05:05:09 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:05:09 INFO - 'branch': 'Fx-Team', 05:05:09 INFO - 'buildbot_json_path': 'buildprops.json', 05:05:09 INFO - 'config_files': ('talos/mac_config.py',), 05:05:09 INFO - 'default_actions': ('clobber', 05:05:09 INFO - 'read-buildbot-config', 05:05:09 INFO - 'download-and-extract', 05:05:09 INFO - 'populate-webroot', 05:05:09 INFO - 'create-virtualenv', 05:05:09 INFO - 'install', 05:05:09 INFO - 'run-tests'), 05:05:09 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:05:09 INFO - 'download_minidump_stackwalk': True, 05:05:09 INFO - 'download_symbols': 'ondemand', 05:05:09 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:05:09 INFO - 'tooltool.py': '/tools/tooltool.py', 05:05:09 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:05:09 INFO - '/tools/misc-python/virtualenv.py')}, 05:05:09 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:05:09 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:05:09 INFO - 'installer_path': 'installer.exe', 05:05:09 INFO - 'log_level': 'info', 05:05:09 INFO - 'log_name': 'talos', 05:05:09 INFO - 'log_to_console': True, 05:05:09 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:05:09 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:05:09 INFO - 'opt_config_files': (), 05:05:09 INFO - 'pip_index': False, 05:05:09 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:09 INFO - 'cmd': ('bash', 05:05:09 INFO - '-c', 05:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:09 INFO - 'enabled': True, 05:05:09 INFO - 'halt_on_failure': False, 05:05:09 INFO - 'name': 'check_screen_resolution'},), 05:05:09 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:05:09 INFO - 'cmd': ('bash', 05:05:09 INFO - '-c', 05:05:09 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:05:09 INFO - 'enabled': True, 05:05:09 INFO - 'halt_on_failure': False, 05:05:09 INFO - 'name': 'check_screen_resolution'},), 05:05:09 INFO - 'run_cmd_checks_enabled': True, 05:05:09 INFO - 'sps_profile': False, 05:05:09 INFO - 'sps_profile_interval': 0, 05:05:09 INFO - 'suite': 'dromaeojs', 05:05:09 INFO - 'system_bits': '32', 05:05:09 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:05:09 INFO - 'title': 't-yosemite-r7-0200', 05:05:09 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:05:09 INFO - 'use_talos_json': True, 05:05:09 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:05:09 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:05:09 INFO - 'work_dir': 'build'} 05:05:09 INFO - [mozharness: 2016-08-08 12:05:09.512129Z] Running clobber step. 05:05:09 INFO - Running pre-action listener: _resource_record_pre_action 05:05:09 INFO - Running main action method: clobber 05:05:09 INFO - rmtree: /builds/slave/test/build 05:05:09 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:05:11 INFO - Running post-action listener: _resource_record_post_action 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.205249Z] Finished clobber step (success) 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.205605Z] Running read-buildbot-config step. 05:05:11 INFO - Running pre-action listener: _resource_record_pre_action 05:05:11 INFO - Running main action method: read_buildbot_config 05:05:11 INFO - Using buildbot properties: 05:05:11 INFO - { 05:05:11 INFO - "project": "", 05:05:11 INFO - "product": "firefox", 05:05:11 INFO - "script_repo_revision": "production", 05:05:11 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 05:05:11 INFO - "repository": "", 05:05:11 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 05:05:11 INFO - "buildid": "20160805131321", 05:05:11 INFO - "pgo_build": "False", 05:05:11 INFO - "basedir": "/builds/slave/test", 05:05:11 INFO - "buildnumber": 906, 05:05:11 INFO - "slavename": "t-yosemite-r7-0200", 05:05:11 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 05:05:11 INFO - "platform": "yosemite_r7", 05:05:11 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 05:05:11 INFO - "branch": "fx-team", 05:05:11 INFO - "repo_path": "integration/fx-team", 05:05:11 INFO - "revision": "f54fea6078ddf0023a4307a25264c6ece653e503", 05:05:11 INFO - "stage_platform": "macosx64", 05:05:11 INFO - "builduid": "3935528495d04780899dc77e8ecc8206", 05:05:11 INFO - "slavebuilddir": "test" 05:05:11 INFO - } 05:05:11 INFO - Found installer url https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 05:05:11 INFO - Running post-action listener: _resource_record_post_action 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.211406Z] Finished read-buildbot-config step (success) 05:05:11 INFO - [mozharness: 2016-08-08 12:05:11.211520Z] Running download-and-extract step. 05:05:11 INFO - Running pre-action listener: _resource_record_pre_action 05:05:11 INFO - Running main action method: download_and_extract 05:05:11 INFO - mkdir: /builds/slave/test/build/tests 05:05:11 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')]} 05:05:11 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:12 INFO - Downloaded 1199 bytes. 05:05:12 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 05:05:12 INFO - Using the following test package requirements: 05:05:12 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:12 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 05:05:12 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 05:05:12 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'jsshell-mac.zip'], 05:05:12 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 05:05:12 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 05:05:12 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 05:05:12 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 05:05:12 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 05:05:12 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 05:05:12 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 05:05:12 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 05:05:12 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')]} 05:05:12 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:12 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:12 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:13 INFO - Downloaded 16320553 bytes. 05:05:13 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05:20 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 05:05: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')]} 05:05:20 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 05:05:20 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:21 INFO - Downloaded 16320553 bytes. 05:05:21 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 05:05: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')]} 05:05:27 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 05:05:27 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:28 INFO - Downloaded 14413723 bytes. 05:05:28 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 05:05:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:05:29 INFO - trying https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:29 INFO - Downloading https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 05:05:29 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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 05:05:32 INFO - Downloaded 111902381 bytes. 05:05:32 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:32 INFO - mkdir: /builds/slave/test/properties 05:05:32 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:05:32 INFO - Writing to file /builds/slave/test/properties/build_url 05:05:32 INFO - Contents: 05:05:32 INFO - build_url:https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 05:05:33 INFO - Running post-action listener: _resource_record_post_action 05:05:33 INFO - Running post-action listener: set_extra_try_arguments 05:05:33 INFO - [mozharness: 2016-08-08 12:05:33.277739Z] Finished download-and-extract step (success) 05:05:33 INFO - [mozharness: 2016-08-08 12:05:33.277898Z] Running populate-webroot step. 05:05:33 INFO - Running pre-action listener: _resource_record_pre_action 05:05:33 INFO - Running main action method: populate_webroot 05:05:33 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:05:33 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:05:33 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:05:33 INFO - '--tppagecycles', 05:05:33 INFO - '7'], 05:05:33 INFO - 'tests': ['tsvgm']}}, 05:05:33 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:05:33 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:05:33 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:05:33 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:05:33 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:33 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:05:33 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['damp', 'tps']}, 05:05:33 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['damp', 'tps']}, 05:05:33 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['dromaeo_dom']}, 05:05:33 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:05:33 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['basic_compositor_video']}, 05:05:33 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:05:33 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['a11yr', 05:05:33 INFO - 'ts_paint', 05:05:33 INFO - 'tpaint', 05:05:33 INFO - 'sessionrestore', 05:05:33 INFO - 'sessionrestore_no_auto_restore', 05:05:33 INFO - 'tabpaint']}, 05:05:33 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['tsvgx', 05:05:33 INFO - 'tsvgr_opacity', 05:05:33 INFO - 'tart', 05:05:33 INFO - 'tscrollx', 05:05:33 INFO - 'cart']}, 05:05:33 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:05:33 INFO - 'tsvgr_opacity', 05:05:33 INFO - 'tart', 05:05:33 INFO - 'tscrollx', 05:05:33 INFO - 'cart']}, 05:05:33 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--disable-e10s'], 05:05:33 INFO - 'tests': ['tp5o']}, 05:05:33 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'tests': ['tp5o']}, 05:05:33 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--disable-e10s', 05:05:33 INFO - '--xperf_path', 05:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:33 INFO - 'tests': ['tp5n']}, 05:05:33 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 05:05:33 INFO - 'talos_options': ['--xperf_path', 05:05:33 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:05:33 INFO - 'tests': ['tp5n']}}, 05:05:33 INFO - 'talos.zip': {'path': '', 05:05:33 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:05:33 INFO - Running post-action listener: _resource_record_post_action 05:05:33 INFO - [mozharness: 2016-08-08 12:05:33.296466Z] Finished populate-webroot step (success) 05:05:33 INFO - [mozharness: 2016-08-08 12:05:33.296572Z] Running create-virtualenv step. 05:05:33 INFO - Running pre-action listener: _resource_record_pre_action 05:05:33 INFO - Running main action method: create_virtualenv 05:05:33 INFO - Creating virtualenv /builds/slave/test/build/venv 05:05:33 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 05:05:33 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:05:33 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:05:33 INFO - Using real prefix '/tools/python27' 05:05:33 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:05:34 INFO - Installing distribute.............................................................................................................................................................................................done. 05:05:37 INFO - Installing pip.................done. 05:05:37 INFO - Return code: 0 05:05:37 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:05:37 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')]} 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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')]} 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:37 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 05:05:37 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 05:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:37 INFO - 'HOME': '/Users/cltbld', 05:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:37 INFO - 'LOGNAME': 'cltbld', 05:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:37 INFO - 'MOZ_NO_REMOTE': '1', 05:05:37 INFO - 'NO_EM_RESTART': '1', 05:05:37 INFO - 'PAGER': '/bin/cat', 05:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:37 INFO - 'PWD': '/builds/slave/test', 05:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:37 INFO - 'SHELL': '/bin/bash', 05:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:37 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:37 INFO - 'USER': 'cltbld', 05:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:37 INFO - 'XPC_FLAGS': '0x0', 05:05:37 INFO - 'XPC_SERVICE_NAME': '0', 05:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 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 05:05:37 INFO - Cleaning up... 05:05:37 INFO - Return code: 0 05:05:37 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:05:37 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')]} 05:05:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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')]} 05:05:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:37 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:37 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 05:05:37 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 05:05:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:37 INFO - 'HOME': '/Users/cltbld', 05:05:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:37 INFO - 'LOGNAME': 'cltbld', 05:05:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:37 INFO - 'MOZ_NO_REMOTE': '1', 05:05:37 INFO - 'NO_EM_RESTART': '1', 05:05:37 INFO - 'PAGER': '/bin/cat', 05:05:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:37 INFO - 'PWD': '/builds/slave/test', 05:05:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:37 INFO - 'SHELL': '/bin/bash', 05:05:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:37 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:37 INFO - 'USER': 'cltbld', 05:05:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:37 INFO - 'XPC_FLAGS': '0x0', 05:05:37 INFO - 'XPC_SERVICE_NAME': '0', 05:05:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:37 INFO - Downloading/unpacking psutil>=3.1.1 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:37 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 05:05:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:05:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:41 INFO - Installing collected packages: psutil 05:05:41 INFO - Running setup.py install for psutil 05:05:41 INFO - building 'psutil._psutil_osx' extension 05:05:41 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 05:05:41 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 05:05:42 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 05:05:42 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 05:05:42 INFO - building 'psutil._psutil_posix' extension 05:05:42 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 05:05:42 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:05:42 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:05:42 INFO - ^ 05:05:42 INFO - 1 warning generated. 05:05:42 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 05:05:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:05:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:05:42 INFO - Successfully installed psutil 05:05:42 INFO - Cleaning up... 05:05:42 INFO - Return code: 0 05:05:42 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:05: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 mozsystemmonitor==0.3 05:05:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:42 INFO - 'HOME': '/Users/cltbld', 05:05:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:42 INFO - 'LOGNAME': 'cltbld', 05:05:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:42 INFO - 'MOZ_NO_REMOTE': '1', 05:05:42 INFO - 'NO_EM_RESTART': '1', 05:05:42 INFO - 'PAGER': '/bin/cat', 05:05:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:42 INFO - 'PWD': '/builds/slave/test', 05:05:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:42 INFO - 'SHELL': '/bin/bash', 05:05:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:42 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:42 INFO - 'USER': 'cltbld', 05:05:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:42 INFO - 'XPC_FLAGS': '0x0', 05:05:42 INFO - 'XPC_SERVICE_NAME': '0', 05:05:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:05:42 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 05:05:42 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 05:05:42 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 05:05:42 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 05:05:42 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 05:05:42 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 05:05:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:05:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:05:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:05:45 INFO - Installing collected packages: mozsystemmonitor 05:05:45 INFO - Running setup.py install for mozsystemmonitor 05:05:45 INFO - Successfully installed mozsystemmonitor 05:05:45 INFO - Cleaning up... 05:05:45 INFO - Return code: 0 05:05:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:05:45 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')]} 05:05:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:45 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')]} 05:05:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:45 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:45 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 05:05:45 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 05:05:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:45 INFO - 'HOME': '/Users/cltbld', 05:05:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:45 INFO - 'LOGNAME': 'cltbld', 05:05:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:45 INFO - 'MOZ_NO_REMOTE': '1', 05:05:45 INFO - 'NO_EM_RESTART': '1', 05:05:45 INFO - 'PAGER': '/bin/cat', 05:05:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:45 INFO - 'PWD': '/builds/slave/test', 05:05:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:45 INFO - 'SHELL': '/bin/bash', 05:05:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:45 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:45 INFO - 'USER': 'cltbld', 05:05:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:45 INFO - 'XPC_FLAGS': '0x0', 05:05:45 INFO - 'XPC_SERVICE_NAME': '0', 05:05:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:45 INFO - Downloading/unpacking jsonschema==2.5.1 05:05:45 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 05:05:45 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 05:05:45 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 05:05:45 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 05:05:45 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 05:05:45 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 05:05:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:05:48 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:48 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:48 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:48 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:48 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:48 INFO - Downloading functools32-3.2.3-2.tar.gz 05:05:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:05:48 INFO - warning: no files found matching '*.txt' 05:05:48 INFO - no previously-included directories found matching 'build' 05:05:48 INFO - no previously-included directories found matching 'dist' 05:05:48 INFO - no previously-included directories found matching '.git*' 05:05:48 INFO - Installing collected packages: jsonschema, functools32 05:05:48 INFO - Running setup.py install for functools32 05:05:49 INFO - warning: no files found matching '*.txt' 05:05:49 INFO - no previously-included directories found matching 'build' 05:05:49 INFO - no previously-included directories found matching 'dist' 05:05:49 INFO - no previously-included directories found matching '.git*' 05:05:49 INFO - Successfully installed jsonschema functools32 05:05:49 INFO - Cleaning up... 05:05:49 INFO - Return code: 0 05:05:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:49 INFO - 'HOME': '/Users/cltbld', 05:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:49 INFO - 'LOGNAME': 'cltbld', 05:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:49 INFO - 'MOZ_NO_REMOTE': '1', 05:05:49 INFO - 'NO_EM_RESTART': '1', 05:05:49 INFO - 'PAGER': '/bin/cat', 05:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:49 INFO - 'PWD': '/builds/slave/test', 05:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:49 INFO - 'SHELL': '/bin/bash', 05:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:49 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:49 INFO - 'USER': 'cltbld', 05:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:49 INFO - 'XPC_FLAGS': '0x0', 05:05:49 INFO - 'XPC_SERVICE_NAME': '0', 05:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:05:49 INFO - Cleaning up... 05:05:49 INFO - Return code: 0 05:05:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:05:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:05:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:49 INFO - 'HOME': '/Users/cltbld', 05:05:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:49 INFO - 'LOGNAME': 'cltbld', 05:05:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:49 INFO - 'MOZ_NO_REMOTE': '1', 05:05:49 INFO - 'NO_EM_RESTART': '1', 05:05:49 INFO - 'PAGER': '/bin/cat', 05:05:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:49 INFO - 'PWD': '/builds/slave/test', 05:05:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:49 INFO - 'SHELL': '/bin/bash', 05:05:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:49 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:49 INFO - 'USER': 'cltbld', 05:05:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:49 INFO - 'XPC_FLAGS': '0x0', 05:05:49 INFO - 'XPC_SERVICE_NAME': '0', 05:05:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:49 INFO - Downloading/unpacking blobuploader==1.2.4 05:05:49 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 05:05:49 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 05:05:49 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 05:05:49 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 05:05:49 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 05:05:49 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 05:05:52 INFO - Downloading blobuploader-1.2.4.tar.gz 05:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:05:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:05:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:05:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:05:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:05:53 INFO - Downloading docopt-0.6.1.tar.gz 05:05:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:05:53 INFO - Installing collected packages: blobuploader, requests, docopt 05:05:53 INFO - Running setup.py install for blobuploader 05:05:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:05:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:05:53 INFO - Running setup.py install for requests 05:05:54 INFO - Running setup.py install for docopt 05:05:54 INFO - Successfully installed blobuploader requests docopt 05:05:54 INFO - Cleaning up... 05:05:54 INFO - Return code: 0 05:05:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05:54 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')]} 05:05:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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')]} 05:05:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:54 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:54 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 05:05:54 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 05:05:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:54 INFO - 'HOME': '/Users/cltbld', 05:05:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:54 INFO - 'LOGNAME': 'cltbld', 05:05:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:54 INFO - 'MOZ_NO_REMOTE': '1', 05:05:54 INFO - 'NO_EM_RESTART': '1', 05:05:54 INFO - 'PAGER': '/bin/cat', 05:05:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:54 INFO - 'PWD': '/builds/slave/test', 05:05:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:54 INFO - 'SHELL': '/bin/bash', 05:05:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:54 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:54 INFO - 'USER': 'cltbld', 05:05:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:54 INFO - 'XPC_FLAGS': '0x0', 05:05:54 INFO - 'XPC_SERVICE_NAME': '0', 05:05:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:54 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-6vBJy0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:54 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-WjnHVx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:54 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-e0MO6X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:54 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-l_EBFV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:54 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-lMNYus-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-0UoNp_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-zGwA2V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-s_Eo8L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-y_Koq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-eX0Kdb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-BoCLfL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-y4hHHU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-OPCpT0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:05:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:05:55 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8HMH7L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:56 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-mPuPe9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:05:56 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-8ozDf7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:05:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:05:56 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-CGsI3I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:05:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:05:56 INFO - Running setup.py install for manifestparser 05:05:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:05:56 INFO - Running setup.py install for mozcrash 05:05:56 INFO - Running setup.py install for mozdebug 05:05:56 INFO - Running setup.py install for mozdevice 05:05:57 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozfile 05:05:57 INFO - Running setup.py install for mozhttpd 05:05:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozinfo 05:05:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozInstall 05:05:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for mozleak 05:05:57 INFO - Running setup.py install for mozlog 05:05:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:05:57 INFO - Running setup.py install for moznetwork 05:05:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:05:58 INFO - Running setup.py install for mozprocess 05:05:58 INFO - Running setup.py install for mozprofile 05:05:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:05:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:05:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:05:58 INFO - Running setup.py install for mozrunner 05:05:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:05:58 INFO - Running setup.py install for mozscreenshot 05:05:58 INFO - Running setup.py install for moztest 05:05:58 INFO - Running setup.py install for mozversion 05:05:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:05:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:05:59 INFO - Cleaning up... 05:05:59 INFO - Return code: 0 05:05:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:05: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')]} 05:05:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05: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')]} 05:05:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:05:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:05:59 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:05:59 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 05:05:59 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 05:05:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:05:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:05:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:05:59 INFO - 'HOME': '/Users/cltbld', 05:05:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:05:59 INFO - 'LOGNAME': 'cltbld', 05:05:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:05:59 INFO - 'MOZ_NO_REMOTE': '1', 05:05:59 INFO - 'NO_EM_RESTART': '1', 05:05:59 INFO - 'PAGER': '/bin/cat', 05:05:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:05:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:05:59 INFO - 'PWD': '/builds/slave/test', 05:05:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:05:59 INFO - 'SHELL': '/bin/bash', 05:05:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:05:59 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:05:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:05:59 INFO - 'USER': 'cltbld', 05:05:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:05:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:05:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:05:59 INFO - 'XPC_FLAGS': '0x0', 05:05:59 INFO - 'XPC_SERVICE_NAME': '0', 05:05:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:05:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-0LKcP5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YEWfmH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-kWbRDW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-bW069U-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-IhzkuN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-NK5vMW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:05:59 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)) 05:05:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:05:59 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-yV1WuA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-9qkGA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-YU1Qok-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-vpVzES-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-GxUvMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-MQylOv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-hY1VkR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-i_hsYe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-FM83aa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:06:00 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)) 05:06:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:06:00 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-HAnKcy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:06:01 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)) 05:06:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:06:01 INFO - Running setup.py (path:/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/pip-W5SGOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:06:01 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)) 05:06:01 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)) 05:06:01 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)) 05:06:01 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)) 05:06:01 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)) 05:06:01 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)) 05:06:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:06:01 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 05:06:01 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 05:06:01 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 05:06:01 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 05:06:01 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 05:06:01 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 05:06:03 INFO - Downloading blessings-1.6.tar.gz 05:06:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:06:03 INFO - Installing collected packages: blessings 05:06:03 INFO - Running setup.py install for blessings 05:06:03 INFO - Successfully installed blessings 05:06:03 INFO - Cleaning up... 05:06:03 INFO - Return code: 0 05:06:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:06:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:04 INFO - Reading from file tmpfile_stdout 05:06:04 INFO - Current package versions: 05:06:04 INFO - blessings == 1.6 05:06:04 INFO - blobuploader == 1.2.4 05:06:04 INFO - docopt == 0.6.1 05:06:04 INFO - functools32 == 3.2.3-2 05:06:04 INFO - jsonschema == 2.5.1 05:06:04 INFO - manifestparser == 1.1 05:06:04 INFO - mozInstall == 1.12 05:06:04 INFO - mozcrash == 0.17 05:06:04 INFO - mozdebug == 0.1 05:06:04 INFO - mozdevice == 0.48 05:06:04 INFO - mozfile == 1.2 05:06:04 INFO - mozhttpd == 0.7 05:06:04 INFO - mozinfo == 0.9 05:06:04 INFO - mozleak == 0.1 05:06:04 INFO - mozlog == 3.3 05:06:04 INFO - moznetwork == 0.27 05:06:04 INFO - mozprocess == 0.23 05:06:04 INFO - mozprofile == 0.28 05:06:04 INFO - mozrunner == 6.12 05:06:04 INFO - mozscreenshot == 0.1 05:06:04 INFO - mozsystemmonitor == 0.3 05:06:04 INFO - moztest == 0.7 05:06:04 INFO - mozversion == 1.4 05:06:04 INFO - psutil == 3.1.1 05:06:04 INFO - requests == 1.2.3 05:06:04 INFO - wsgiref == 0.1.2 05:06:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:06:04 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')]} 05:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 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')]} 05:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:06:04 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 05:06:04 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 05:06:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:06:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:04 INFO - 'HOME': '/Users/cltbld', 05:06:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:04 INFO - 'LOGNAME': 'cltbld', 05:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:04 INFO - 'MOZ_NO_REMOTE': '1', 05:06:04 INFO - 'NO_EM_RESTART': '1', 05:06:04 INFO - 'PAGER': '/bin/cat', 05:06:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:04 INFO - 'PWD': '/builds/slave/test', 05:06:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:04 INFO - 'SHELL': '/bin/bash', 05:06:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:06:04 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:06:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:04 INFO - 'USER': 'cltbld', 05:06:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:04 INFO - 'XPC_FLAGS': '0x0', 05:06:04 INFO - 'XPC_SERVICE_NAME': '0', 05:06:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 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)) 05:06:04 INFO - Cleaning up... 05:06:04 INFO - Return code: 0 05:06:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:06:04 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')]} 05:06:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 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')]} 05:06:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:06:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:06:04 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 0x1011d1a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101266618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa5514c7690>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, '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.xz58nYqN5j/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.blhWUGfecm/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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 05:06:04 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 05:06:04 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 05:06:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:06:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:04 INFO - 'HOME': '/Users/cltbld', 05:06:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:04 INFO - 'LOGNAME': 'cltbld', 05:06:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:04 INFO - 'MOZ_NO_REMOTE': '1', 05:06:04 INFO - 'NO_EM_RESTART': '1', 05:06:04 INFO - 'PAGER': '/bin/cat', 05:06:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:04 INFO - 'PWD': '/builds/slave/test', 05:06:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:04 INFO - 'SHELL': '/bin/bash', 05:06:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:06:04 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:06:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:04 INFO - 'USER': 'cltbld', 05:06:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:04 INFO - 'XPC_FLAGS': '0x0', 05:06:04 INFO - 'XPC_SERVICE_NAME': '0', 05:06:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:06:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:06:04 INFO - Cleaning up... 05:06:04 INFO - Return code: 0 05:06:04 INFO - Running post-action listener: _resource_record_post_action 05:06:04 INFO - Running post-action listener: _start_resource_monitoring 05:06:04 INFO - Starting resource monitoring. 05:06:04 INFO - [mozharness: 2016-08-08 12:06:04.693790Z] Finished create-virtualenv step (success) 05:06:04 INFO - [mozharness: 2016-08-08 12:06:04.694801Z] Running install step. 05:06:04 INFO - Running pre-action listener: _resource_record_pre_action 05:06:04 INFO - Running main action method: install 05:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:06:04 INFO - Reading from file tmpfile_stdout 05:06:04 INFO - Detecting whether we're running mozinstall >=1.0... 05:06:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:06:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:06:05 INFO - Reading from file tmpfile_stdout 05:06:05 INFO - Output received: 05:06:05 INFO - Usage: mozinstall [options] installer 05:06:05 INFO - Options: 05:06:05 INFO - -h, --help show this help message and exit 05:06:05 INFO - -d DEST, --destination=DEST 05:06:05 INFO - Directory to install application into. [default: 05:06:05 INFO - "/builds/slave/test"] 05:06:05 INFO - --app=APP Application being installed. [default: firefox] 05:06:05 INFO - mkdir: /builds/slave/test/build/application 05:06:05 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'] 05:06:05 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 05:06:39 INFO - Reading from file tmpfile_stdout 05:06:39 INFO - Output received: 05:06:39 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 05:06:39 INFO - Running post-action listener: _resource_record_post_action 05:06:39 INFO - [mozharness: 2016-08-08 12:06:39.264380Z] Finished install step (success) 05:06:39 INFO - [mozharness: 2016-08-08 12:06:39.264563Z] Running run-tests step. 05:06:39 INFO - Running pre-action listener: _resource_record_pre_action 05:06:39 INFO - Running main action method: run_tests 05:06:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:06:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:06:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:06:39 INFO - 2016-08-08 05:06:39.278 screenresolution[1638:7884] starting screenresolution argv=screenresolution get 05:06:39 INFO - 2016-08-08 05:06:39.281 screenresolution[1638:7884] Display 0: 1600x1200x32@60 05:06:39 INFO - 2016-08-08 05:06:39.287 screenresolution[1639:7886] starting screenresolution argv=screenresolution list 05:06:39 INFO - Available Modes on Display 0 05:06:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:06:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:06:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:06:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:06:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:06:39 INFO - Intel Iris: 05:06:39 INFO - Chipset Model: Intel Iris 05:06:39 INFO - Type: GPU 05:06:39 INFO - Bus: Built-In 05:06:39 INFO - VRAM (Dynamic, Max): 1536 MB 05:06:39 INFO - Vendor: Intel (0x8086) 05:06:39 INFO - Device ID: 0x0a2e 05:06:39 INFO - Revision ID: 0x0009 05:06:39 INFO - Displays: 05:06:39 INFO - Display: 05:06:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:06:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:06:39 INFO - Display Serial Number: 1600x1200 60 05:06:39 INFO - Main Display: Yes 05:06:39 INFO - Mirror: Off 05:06:39 INFO - Online: Yes 05:06:39 INFO - Rotation: Supported 05:06:39 INFO - Return code: 0 05:06:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:06:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:06:39 INFO - Python 2.7.3 05:06:39 INFO - Return code: 0 05:06:39 INFO - grabbing minidump binary from tooltool 05:06:39 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')]} 05:06:39 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 0x101135d30>, '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 0x100e9adb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10112db20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:06:39 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 05:06:39 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 05:06:39 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 05:06:39 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:06:39 INFO - Return code: 0 05:06:39 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:06:39 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:06:39 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:06:39 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:06:39 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:06:39 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 05:06:39 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0200 --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 05:06:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.xz58nYqN5j/Render', 05:06:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:06:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:06:39 INFO - 'HOME': '/Users/cltbld', 05:06:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:06:39 INFO - 'LOGNAME': 'cltbld', 05:06:39 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:06:39 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:06:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:06:39 INFO - 'MOZ_NO_REMOTE': '1', 05:06:39 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:06:39 INFO - 'NO_EM_RESTART': '1', 05:06:39 INFO - 'PAGER': '/bin/cat', 05:06:39 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:06:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:06:39 INFO - 'PWD': '/builds/slave/test', 05:06:39 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:06:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:06:39 INFO - 'SHELL': '/bin/bash', 05:06:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.blhWUGfecm/Listeners', 05:06:39 INFO - 'TMPDIR': '/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/', 05:06:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:06:39 INFO - 'USER': 'cltbld', 05:06:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:06:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:06:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:06:39 INFO - 'XPC_FLAGS': '0x0', 05:06:39 INFO - 'XPC_SERVICE_NAME': '0', 05:06:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:06:39 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/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0200', '--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 05:06:39 INFO - mozversion application_buildid: 20160805131321 05:06:39 INFO - mozversion application_changeset: f54fea6078ddf0023a4307a25264c6ece653e503 05:06:39 INFO - mozversion application_display_name: Nightly 05:06:39 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:06:39 INFO - mozversion application_name: Firefox 05:06:39 INFO - mozversion application_remotingname: firefox 05:06:39 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 05:06:39 INFO - mozversion application_vendor: Mozilla 05:06:39 INFO - mozversion application_version: 51.0a1 05:06:39 INFO - mozversion platform_buildid: 20160805131321 05:06:39 INFO - mozversion platform_changeset: f54fea6078ddf0023a4307a25264c6ece653e503 05:06:39 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 05:06:39 INFO - mozversion platform_version: 51.0a1 05:06:39 INFO - using testdate: 1470657999 05:06:39 INFO - actual date: 1470657999 05:06:39 INFO - starting webserver on 'localhost:49266' 05:06:39 INFO - SUITE-START | Running 2 tests 05:06:39 INFO - TEST-START | dromaeo_css 05:06:39 INFO - operating with platform_type : mac_ 05:06:39 INFO - Initialising browser for dromaeo_css test... 05:06:39 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpUcOWzH/profile http://localhost:49266/getInfo.html) 05:06:42 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 05:06:42 INFO - PROCESS | 1649 | colorDepth:24 05:06:42 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 05:06:42 INFO - PROCESS | 1649 | __metrics 05:06:42 INFO - PROCESS | 1649 | 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" 05:06:42 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:06:42 INFO - TEST-INFO | 1649: exit 0 05:06:42 INFO - Browser initialized. 05:06:42 INFO - Running cycle 1/1 for dromaeo_css test... 05:06:42 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpUcOWzH/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:06:54 INFO - PROCESS | 1651 | RSS: Main: 243396608 05:06:54 INFO - PROCESS | 1651 | 05:07:46 INFO - PROCESS | 1651 | 1470658066711 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] 05:09:59 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49266/tests/dromaeo/cssquery-ext.html) 05:10:00 INFO - PROCESS | 1651 | RSS: Main: 354430976 05:10:00 INFO - PROCESS | 1651 | 05:13:03 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-ext.html (next: http://localhost:49266/tests/dromaeo/cssquery-jquery.html) 05:13:04 INFO - PROCESS | 1651 | RSS: Main: 399732736 05:13:04 INFO - PROCESS | 1651 | 05:16:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49266/tests/dromaeo/cssquery-mootools.html) 05:16:07 INFO - PROCESS | 1651 | RSS: Main: 352354304 05:16:07 INFO - PROCESS | 1651 | 05:19:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49266/tests/dromaeo/cssquery-prototype.html) 05:19:10 INFO - PROCESS | 1651 | RSS: Main: 369291264 05:19:10 INFO - PROCESS | 1651 | 05:22:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49266/tests/dromaeo/cssquery-yui.html) 05:22:14 INFO - PROCESS | 1651 | RSS: Main: 351395840 05:22:14 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49266/tests/dromaeo/cssquery-yui.html (next: http://localhost:49266/tests/dromaeo/cssquery-dojo.html) 05:25:17 INFO - PROCESS | 1651 | RSS: Main: 398884864 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | __start_tp_report 05:25:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 05:25:17 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 05:25:17 INFO - PROCESS | 1651 | |i|pagename|runs| 05:25:17 INFO - PROCESS | 1651 | |0;dojo.html;3308.3383323335333;3332.233586275155;3336.916577085571;3349.9447673982686;3390.2541440882997;4535.501094879561;5646.350669672989;5682.630629009117;5693.8576535586635;5714.371419143896;4716.10394025135;5692.345380281269;5719;5736.340320863102;5736.741846616902;4660.788195069273;4796.3524924135145;5158.43257241704;5170.457102004273;5220.712860792655;3855.209682015194;5187.195984622371;5237.162054071349;5274.630775845692;5275.788968441258;5166.793328266865;5197.220416937452;5220.843374698765;5224.45143259958;5261.579073674096;5295.841124766263;5306.495882891119;5311.893762124774;5339.225812257239;5376.247325374451;5108.616853735947;5117.46266642001;5160.200168973777;5215.660982036194;5224.294720212761;5616;5616.045272303718;5684.63049901755;5691.146328050825;5694.886102277973;6213.03697926822;6217.658028808413;6228.252609686821;6229.2836323822885;6241.594296370721;6105.126180223406;6178.505719542426;6214.192155019864;6250.15622890913;6256;5851.912221316684;5854.385289544579;5858.502027327725;5858.619189752652;5877.47102760745;6290.2766181889665;6329.905051424233;6366.681665916778;6371.23545174582;6378;5981.6710080945095;5996.2504686914135;6072.453479186895;6101.389861013863;6139.324674285825;6211.9068213976825;6217.689115544295;6220.968895155495;6228.34602366745;6233.376662333731;5085.237214417867;6093.6039157454625;6146.877062458726;6153.600015999035;6186.1648677427975;6151.507879369639;6208.72060757267;6227.06594010902;6241.21984751906;6247;4972.279019542146;4978.6763860348965;4986.850394488099;4992.101421744121;5005.899882002413;3683.7605555638797;4404.471463424409;4406.735595864258;4441.067375851043;4492.528284530143;5652.519535839499;5653.858653533613;5660.433956604306;5668.801591944262;5710.971445142748;6211.9068213976825;6273.592216506004;6289.7169627366875;6292.433680968735;6307.180066591313;5572.58205634579;5601.355844077902;5645.040343141593;5651.2935883014625;5661.547076233974;4608.746518941491;5438.15708565173;5445.945540544623;5447.1829225616475;5458.699771512525;6304.338044505261;6317.873642527032;6323.747050117944;6334.841628959313;6341.239051313872;6111.8956224267595;6172.598781079216;6253.530985176221;6268.561200715906;6313.842153946188;6276.529260305404;6320.051992201206;6322.399372059647;6338.397852204033;6370.1718776558755;6113.877722445437;6157.84605384869;6161.815145545641;6162.26052873658;6167.96916015435;6139.171211886338;6154.323024467216;6203.751849925952;6261.686915654108;6265.342139075331;6082.2093127893095;6086.452219300107;6103.572749907464;6115.755369785159;6125.295591007091;5942.524598032233;6092.786752463642;6106.389361063858;6125.295591006913;6128.1726966858905;5685.20407142992;5863.20846685692;5891.970540147272;5912;5977.671228082323;6103.708412588929;6114.66235542843;6132.845047373448;6144.095330447575;6146.0473626587955;5962.642241465526;5963.970180149072;5970.850728731816;5971.970140149271;5989.101634754647 05:25:17 INFO - PROCESS | 1651 | |1;ext.html;477.92114301140316;523.1446584833797;528.1391332128629;534.5830252403127;549.9945000549993;30669;30969.690303096962;32018.839905800498;32108.518372224407;40151.99620009501;40284.39573406401;42634.7209583713;42672;42680.78659606705;42760.78619606898;9579.473128977903;9754.707358779213;9772.706818795448;9780.119789218968;9799;8696.826063478728;8874.023857375683;9616.951915240414;9723.951380243125;9733.367331123454;11592.826107608393;11840.05279577636;11867.228630139056;12080.275183489019;12092.33492157931;11675.357855317954;11738.589149379815;11741;11741.94322510978;11754.823677644843;11640.06879449642;11706.473208705629;11712.707182320424;11721.472533736003;11731.296122232694;38305.23389532221;39948.40077398841;40209.59790402088;40294.59705402938;40325.79837100796;3468.150303175713;3611.4402267648557;3645.7447978641508;3706.4810926470313;4158.896027599304;31033;31825.30405153322;32428.675713243036;32476.675233247603;32588.6741132588;3204.455242608762;3205.7069719909928;3253.3981213475345;3261.266215101607;3266.216108134031;497925;499272;502541;503154.4842275802;504242;56792;57054.429455704914;57122;57239.427605724246;57322.7133864328;61287;61406.0789088164;61467;61522.69238653778;61556;16185.757213641804;16374.672506549803;16386.508404747878;16396.508104756875;16416.343346266014;12851.16467429614;12904;12926.67683307906;12934.805977910339;12937.482500700055;13806.309684515734;14197.21915294648;14276.85723142776;15379.000364976464;15650.295736691649;6441.484681225491;6590.703418346186;6645.667716614246;6646.401823835878;6661.067450556926;914.9451032938046;959.5633986536109;988.7824678570703;1038.99480502597;1123.786310784351;25960;28812.711872881002;30007.249818754706;30250.78996840146;30930.69069309323;1063.7819247054335;1114.8272017837248;1128.4639796096953;1141;1153.9423028848692;15207.695846083237;15549.611259718598;15625.843741562667;15636.3745450183;16035.518934431986;186477.20284195748;187536;187561;187968.9023323256;188435;50288.748556256985;56923.43076569264;57399.56501087423;57957.71021144867;58227.4177258222;1355.0717758335572;1363.1003537665292;1393.3312010235145;1397.2734178227063;1401.6776141487514;29877.850610746806;30646.540301895042;31241.531377029816;31861.05704446755;32262.709491620073;49849.25226121684;50215;50232.72556381673;50259;50319;786131;810091;835688;839800;839883;26282.60576091322;26541.867290663424;26552.937882484483;26557.20328390074;26581;47337.763311183226;47463.762681186374;47779;47983.76008119937;48983.755081224364;38610.227795443374;39082.413763792996;39367.80316098401;40375.39436908507;40554.79722601368;38287.61712382952;38842.41736374013;39499.01252468596;39754.80122599369;40063.599364007154;37967.62032379641;38006;38662;38800;38827.8351649451;31803.68196318007;31829;32450.675493244766;34431.76193547411;35385.292294153456;20138.798612013692;25457.87271063633;25562.744372556037;25596.872015640547;25940.740592593833 05:25:17 INFO - PROCESS | 1651 | |2;jquery.html;352.8817846021583;360.7745159275453;370.36482432628037;375.48370989888895;398.64321432318076;14985.475508357209;15490.535283941472;20362.273579013116;20446.897765511152;20525.07637156328;20643;20959.952002399896;21256;21379.67930481044;21584.676229856566;5783.132530120473;5851.3270973838025;6012.278526576795;6115.938840611581;6117.724702388381;3461.9480707789403;5953.940460595382;6060.151578778975;6062.787802426916;6064.120702498135;6156.753729850823;6413.679316034203;6422.26143993442;6518.576292540994;6544.869102617945;6266.248050234001;6442.48460123189;6481.935180648204;6502.089707440962;6535.21577410709;5686.289213848269;5696.572757043197;5697.829065128053;5697.857553561153;5701.600887937804;26383.868080659475;26504;26689.466210675677;26734;26851;2223.7109175807204;2646.6559347284924;2862.298736809474;3096.28785379364;3234.7412207023617;15585.922070389688;15842.44551440694;16382.0170789753;16397;16406.589835254093;1816;1841.815818418161;1860.3488778927267;1881.397952655151;1987.9403617891487;123286.38356808248;126878.36560817229;127285;128964.99999999907;129309.7069029307;8945.552722363856;10546.947265263625;10930.398828064373;10961.397123158144;11152.944235278934;13934.860651393356;13951.860481395057;13956.371963261678;13960.371783269777;13971.86028139726;8267.586620668942;8408.537530435884;8716.302695784321;8726.723905286364;8846.95576522126;8779.956100219586;8799.384043116921;8802.91197088021;8973.910260897437;8997.910020899839;6550.508711846631;6680.565763225375;6687.465002799764;6719.798406047827;6833.93166068333;1772.5125590462726;1814.9455516334267;1822.261983896524;1824.7373257161025;1829.1494455078453;724.6376811594203;805.6052534258172;817.3093735793245;959.788846453779;1069.0699091790193;21680.699158050567;22243.666345004836;22340.55318893613;22664.433389165402;23127.88436057809;847.9236868681849;907.4373888189365;921.6635927886273;941.5904081724335;1025.7640742629237;11030.28303160292;11285.927836855486;11462.197646164856;11531.654050378502;11541.07671386287;498712;546132;576927.1153644288;600431;603879;123716.38141809234;125026.37486812507;127311;128153;133615;914.6615752171606;932.8758845591046;997.4613708597276;1013.3210748798323;1016.8627235323285;18891.43325700231;19504.414867553995;19523.70714439313;19530.31643892457;19599.824010559412;46821.765891171686;52642.894284228205;54125;54550.7272463651;55536.444635553125;78908.21091789239;82316.3536729274;84559;84926.57536712277;85121;11131.554737810397;11982.760344792881;12184.390780461119;12202.901738843546;12792.808107878576;13687.041907066809;13895.359881622458;13899.37452814646;14372.568822935329;14468.421263149778;11889.978703620578;12203.75592488163;12300.815487767512;12343.321117338633;12362.567310144103;11557.826632600685;11731.23746956445;11912.940435297767;11962.58130965412;12216.816747748968;11751.47118379692;11864.40677966081;12047.096467765128;12251.203671761306;12284.385780711107;12363.814542782045;12445.875541244472;12556.121071525271;12674.302913339854;12815.743685126432;11427.771444571228;11599.884001160219;12244;12340.876591234331;12346.506139754309 05:25:17 INFO - PROCESS | 1651 | |3;mootools.html;1483.2138966347839;1500.0849481816088;1528.128966489101;1860.6185731924957;1877.3898482993031;8293.917060829397;8852.336074794384;8876.689315873946;8886.333524985635;8899.95550022249;7717.614119294041;7945.602719863999;7963.562004089773;8077.071136819253;8633.827323453543;2154.3967689047054;2226.743924448685;2298.2645553422913;2302.51647154097;2309.3187509684685;2335.089315167086;2369.7985671217966;2370.3363058343693;2370.9525809483885;2372.1578839511985;2359.7050368703913;2365.727941286757;2367.266147494274;2371.134535894395;2372.2764556810166;2037.9184832606677;2300.551392478476;2358.9528209435716;2366.538524987628;2368.514454536833;2327.034280773477;2349.4126468382906;2353.2351985604746;2364.9054037838637;2367.0650093213085;8718.476891386474;8762.21140097388;8828.955855220747;8860.822783544358;8882.777930551723;5186.7925282988635;5339.092354299778;5342.759575819097;5343.225232341289;5344.973275133639;3124.703153200442;3469.9826500867584;3596.6762991330907;3653.451982202664;3657.1040095176586;1028.110684258119;1079.0773888325525;1082.577794660083;1090.2259395828971;1091.5742860284495;11596.710082247926;11602;11612.88387116135;11633.243839150427;11637.301761894229;4573.588377046081;4624.630029597624;4774.021325628238;4781.2350023996;4809.975950120297;9852.261080418853;9857.408555486694;9863.112319891245;9865.901340986642;9874.40753554789;5305.31030965972;5313.229581710631;5318.308619879391;5323.813666521653;5324.866878327995;4796.37647105874;4826;4864.537868902448;4885.731284779371;4905.705657660552;3861.691064714816;3922.5685174630767;3924.7056470764805;3968.047668559525;4032.7580345179385;1277.2783377391745;1286.2861112082883;1286.408252203978;1295.430010795247;1301.6095171448717;883.0992387764444;915.9221466175317;922.4188761080476;937.8604994931192;939.5349302095507;4782.928256076161;5843.18195452637;5848.54430505183;5930.970345148333;6157.538184636171;441.04073639833234;444.7776111944028;445.9085887393077;989.5497548615363;1053.115383078218;3926.2736393766672;4007.358822588372;4141.233871733739;4145.3781932709735;4167.99968007682;10399.94800025995;10816.08063314627;10824.837627435594;10830.296030757976;10861.619843305605;10771.946140269405;10773.35359878394;10777.030067293981;10788.676339709822;10817.567297308176;7661.233876612294;7855.253750893656;8057.267687447227;8203.671853125808;8220.3834712398;5883.764649413975;5922.585419020627;6083.178770865877;6152.938470615416;6186.319504854552;9778.533220017054;9972.800543989224;10087.848682269625;10108.1408080314;10121.823807881341;8034.799130021796;10353.223508237063;10371.844422333821;10379.792404151724;10388.065074143364;6996.265392133752;7231.5299505531975;7241.312075352833;7318;7326.3040011198855;7451.515651482637;7936.56348900816;7955.721549745963;8426.157384261525;8509.10654381281;7491.550506969599;7518.812029699301;7536.208698086842;7606.20134885829;7690.884636730341;7346.889796653161;7355.0070740449355;7375.594342311224;7400;7552.697892084255;6876.553024053621;6907.240203577502;6914.412274956685;6967.65161741921;6979.092717946634;4604.976975115103;4738.573528382462;4939.678920870132;5064.29099926018;5221.451747566451;4608.953910460987;5015.04714104319;5035.974820125876;5149.176131819041;5276.314079169683 05:25:17 INFO - PROCESS | 1651 | |4;prototype.html;265.8165865552769;292.7160654165459;295.62160434643664;310.2151556561899;310.43810702628247;4262.72118364491;4663.580277775;5054.963732434851;5059.392872855254;5265.1041926940225;4594.081183763244;4741.549552792488;4921.80312787488;5169.353830771153;5176.5341119299255;4000.83975647062;4015.6586690131303;4021.2761702893467;4024.134811015631;4084.36692312692;3722.274156539476;4044.413560033793;4063.3295506241434;4083.530394004683;4086.468759061318;3913.921721565567;3935.862244821432;3938.7045971552106;3942.3192229284077;3975.8012099394914;3748.775073495599;3899.337112690835;3899.8050097495297;3905.628965248325;3973.284808734427;2988.7907846450757;3379.9691094216255;3418.2479854431986;3425.880094196716;3428.7428442866885;4319.460067491564;4760.428748550162;5029.597632189416;5046.318746969149;5116;4455.331700244957;4459.576340247671;4461.5315391883705;4464.6874718769695;4471.821127154943;5120.462351453081;5356.937579032062;5393.2449457076045;5455.863603409907;5485.204645326446;3451.430513965193;3547.155479150853;3733.5893051764283;3755.793431361274;3762.8682996095004;5144.6913185209005;5647.887042259132;5676.574256930747;5694.943050569483;5714.457126572927;3178.872845086171;3795.4686343911876;3835.884923452301;3843.5964223756664;3844.0774214188395;3463.3073385323037;3705.5368078990127;3889.436031775433;3904.04350934023;3906.7655940643654;4040.4747382840633;4299.871003869889;4511.70673906195;4537.591616754508;4620.0990806793025;5417.214503896913;5436;5442.2108794225405;5519.69641669704;5566.520924422236;1155.9769603900609;1264.5647190438917;1321.7686904791624;1335.7662409078566;1339.1228745171927;838.6645341863303;845.5941148248875;855.170484629908;870.7735988642999;882.885224920769;4030.1133750574827;4151.854685086068;4175.68682348825;4208.169446290874;4244.533101358848;4446.333050042519;4621.815127394933;4665.367121507513;4679.742614156254;4702.882427939329;4638.8608341749805;5105.285260063594;5137.434882162959;5166.664166829144;5202.76587553561;2921.378634857632;2950.645922489315;2956.512175491063;2961.392914452532;2980.478416277186;4829.5653391194965;5410.404855465896;5493.642913210629;5684.77260909559;5785.363610002896;5746.224259724967;5770.538356931435;5770.971145144247;5780.508656764222;5786;4823.445303790109;4967.528084832007;5192.688438693615;5816.76732930678;5861.560382971379;5347.411784703601;5589.748461319332;5633.324001119892;5637.3235211774845;5661.179129026351;4641.443026836801;5473.261109750292;5552;5552.250446189712;5613.775448981995;4997.85006449807;5256.579473642023;5318.680879147263;5360.115580929103;5398.64908780928;3556.7332450065833;3769.3026790043928;3816.751911125768;3820.350540408193;3845.6924645620625;5489.451054894478;5539.446055394428;5675.971620141873;5699.344575373885;5860.769238459971;5322.33470816148;5562.581541706839;5570.220169176399;5576.832695019156;5588.993981083444;4717.589054234302;5258.368995720538;5391.62258641907;5437.592180586393;5559.555235581063;4652.255639097728;5119.513646203604;5341;5348.117560602457;5371.409144994126;4508.729476231437;5106.66806657566;5259.500347466985;5389;5445.782168713207;5360.517553420211;5394.379646340564;5396.64921780083;5465.426130256425;5494.835154945358 05:25:17 INFO - PROCESS | 1651 | |5;yui.html;709.7977076533188;716.292690648621;766.5247546521158;819.6024927909966;830.7590798668386;14911.254437278134;16085.758713619276;16166.7574986375;16184.999999999985;16192.352305907765;14491.637709057279;14622.780658290134;14634.195119268463;14667.63330916725;14691.632709182275;656.3010393930473;681.1961884975726;815.2988429950238;816.2368185746346;844.4764246167354;820.4134044158426;829.531314807132;836.6987884361625;837.0541288344162;837.1461109668135;836.5945212043764;851.9957400213021;890.6170346750927;891.7012800711736;894.8657701344786;692.9965350173217;723.6345645449014;885.0618344554761;886.9512176830272;887.8357503861806;533.6717918480145;534.0680512505702;534.9331333583302;639.9264084630234;662.7713438863584;15085.019473734283;15141.167235802026;15162.31769570372;15171.089734615958;15174.696506069928;12332.568360107443;14311.212883291415;14371.640708982255;14501.637459063502;14572;10874.401907895062;12703.93648031754;12958.935205324007;13425.462981480729;13490.662733431645;4593.931091033637;4628.583427491509;4653.767311634439;4823.686460380064;4825.879353016167;98868.50565747198;100054;104204.47897760538;106940.93059069387;122332;9242.260619150451;9697.515124243759;11059.50232239535;11168.944155279334;11200.887991119984;17905.641887162183;20255.291064812955;20982.2656207032;21794.78205217959;21892.671609925863;7105.218425973138;7120;8084.555349455719;8237.79405514867;8267.751967440987;4405.405270288534;4470.8435204767675;4482.7534485602955;4504.09918016398;4665.906681866344;3651.3427583034777;3800.638939300762;3804.2771873343977;3806.3148633246283;3814.294355544233;680.363859791097;710.1442761472414;713.201214639607;725.9310365515267;727.3453891497694;593.2258402784322;666.4535081233342;677.9830504237335;679.9762008329784;688.6694386694414;8987.55062246885;9010.621017118581;9553.570089345996;9687.564059617334;9777.134472776228;594.7234535940809;634.4892361648917;661.9238787539398;665.766981556461;685.8491131950963;4606.30905364198;4621.491635920046;4652.674312798072;4655.953440465552;4798.1123492154065;93521.06478935124;113701;141917;150892;158403.20798395935;94451;109150;109646.45176774065;130521;136953.3152334232;106442;108494.45752771186;112629.74740505144;117069.41465292619;118863;6839.589624622538;9880.209583233467;9943.950280248553;9982.450965196984;9993;64548;64705.67647161734;65639.68720625465;65938;66420.33579664142;68746.65626671835;92864;93191.53404232935;93531.53234233786;95480;10901.277734396232;12816.615501534969;13178.538751143664;13454.394552245369;13480.865191348354;14852.40590376416;14869.47956821506;14987.325570349143;15081.54755357341;15209.695806083595;13900.791488127888;13996.790048149489;14013.649658758613;14056.78914816299;14434.278286085864;13940.512082077079;14056.859431405555;14085.225312607496;14242.003059785715;14354.282285885454;13504.392302346614;13647.86352136466;13820.516281930084;13832.515861944783;14400.495982640557;9920.255980801614;10603.46982650849;10814.35113893169;11283.435828208392;11415.315081095161;9140.6800761973;9160.954195229247;11095.389753563308;11624.128190385585;11657.591984280509 05:25:17 INFO - PROCESS | 1651 | __end_tp_report 05:25:17 INFO - PROCESS | 1651 | __start_cc_report 05:25:17 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1174 05:25:17 INFO - PROCESS | 1651 | __end_cc_report 05:25:17 INFO - PROCESS | 1651 | __startTimestamp1470659117561__endTimestamp 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | ------- Summary: start ------- 05:25:17 INFO - PROCESS | 1651 | Number of tests: 6 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5703.55 Median:5963.97 stddev:683.41 (11.5%) stddev-sans-first:659.17 05:25:17 INFO - PROCESS | 1651 | Values: 3308.3 3332.2 3336.9 3349.9 3390.3 4535.5 5646.4 5682.6 5693.9 5714.4 4716.1 5692.3 5719.0 5736.3 5736.7 4660.8 4796.4 5158.4 5170.5 5220.7 3855.2 5187.2 5237.2 5274.6 5275.8 5166.8 5197.2 5220.8 5224.5 5261.6 5295.8 5306.5 5311.9 5339.2 5376.2 5108.6 5117.5 5160.2 5215.7 5224.3 5616.0 5616.0 5684.6 5691.1 5694.9 6213.0 6217.7 6228.3 6229.3 6241.6 6105.1 6178.5 6214.2 6250.2 6256.0 5851.9 5854.4 5858.5 5858.6 5877.5 6290.3 6329.9 6366.7 6371.2 6378.0 5981.7 5996.3 6072.5 6101.4 6139.3 6211.9 6217.7 6221.0 6228.3 6233.4 5085.2 6093.6 6146.9 6153.6 6186.2 6151.5 6208.7 6227.1 6241.2 6247.0 4972.3 4978.7 4986.9 4992.1 5005.9 3683.8 4404.5 4406.7 4441.1 4492.5 5652.5 5653.9 5660.4 5668.8 5711.0 6211.9 6273.6 6289.7 6292.4 6307.2 5572.6 5601.4 5645.0 5651.3 5661.5 4608.7 5438.2 5445.9 5447.2 5458.7 6304.3 6317.9 6323.7 6334.8 6341.2 6111.9 6172.6 6253.5 6268.6 6313.8 6276.5 6320.1 6322.4 6338.4 6370.2 6113.9 6157.8 6161.8 6162.3 6168.0 6139.2 6154.3 6203.8 6261.7 6265.3 6082.2 6086.5 6103.6 6115.8 6125.3 5942.5 6092.8 6106.4 6125.3 6128.2 5685.2 5863.2 5892.0 5912.0 5977.7 6103.7 6114.7 6132.8 6144.1 6146.0 5962.6 5964.0 5970.9 5972.0 5989.1 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:64579.52 Median:19426.72 stddev:153548.94 (790.4%) stddev-sans-first:153904.26 05:25:17 INFO - PROCESS | 1651 | Values: 477.9 523.1 528.1 534.6 550.0 30669.0 30969.7 32018.8 32108.5 40152.0 40284.4 42634.7 42672.0 42680.8 42760.8 9579.5 9754.7 9772.7 9780.1 9799.0 8696.8 8874.0 9617.0 9724.0 9733.4 11592.8 11840.1 11867.2 12080.3 12092.3 11675.4 11738.6 11741.0 11741.9 11754.8 11640.1 11706.5 11712.7 11721.5 11731.3 38305.2 39948.4 40209.6 40294.6 40325.8 3468.2 3611.4 3645.7 3706.5 4158.9 31033.0 31825.3 32428.7 32476.7 32588.7 3204.5 3205.7 3253.4 3261.3 3266.2 497925.0 499272.0 502541.0 503154.5 504242.0 56792.0 57054.4 57122.0 57239.4 57322.7 61287.0 61406.1 61467.0 61522.7 61556.0 16185.8 16374.7 16386.5 16396.5 16416.3 12851.2 12904.0 12926.7 12934.8 12937.5 13806.3 14197.2 14276.9 15379.0 15650.3 6441.5 6590.7 6645.7 6646.4 6661.1 914.9 959.6 988.8 1039.0 1123.8 25960.0 28812.7 30007.2 30250.8 30930.7 1063.8 1114.8 1128.5 1141.0 1153.9 15207.7 15549.6 15625.8 15636.4 16035.5 186477.2 187536.0 187561.0 187968.9 188435.0 50288.7 56923.4 57399.6 57957.7 58227.4 1355.1 1363.1 1393.3 1397.3 1401.7 29877.9 30646.5 31241.5 31861.1 32262.7 49849.3 50215.0 50232.7 50259.0 50319.0 786131.0 810091.0 835688.0 839800.0 839883.0 26282.6 26541.9 26552.9 26557.2 26581.0 47337.8 47463.8 47779.0 47983.8 48983.8 38610.2 39082.4 39367.8 40375.4 40554.8 38287.6 38842.4 39499.0 39754.8 40063.6 37967.6 38006.0 38662.0 38800.0 38827.8 31803.7 31829.0 32450.7 34431.8 35385.3 20138.8 25457.9 25562.7 25596.9 25940.7 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35179.10 Median:21317.84 stddev:95011.07 (445.7%) stddev-sans-first:95241.61 05:25:17 INFO - PROCESS | 1651 | Values: 352.9 360.8 370.4 375.5 398.6 14985.5 15490.5 20362.3 20446.9 20525.1 20643.0 20960.0 21256.0 21379.7 21584.7 5783.1 5851.3 6012.3 6115.9 6117.7 3461.9 5953.9 6060.2 6062.8 6064.1 6156.8 6413.7 6422.3 6518.6 6544.9 6266.2 6442.5 6481.9 6502.1 6535.2 5686.3 5696.6 5697.8 5697.9 5701.6 26383.9 26504.0 26689.5 26734.0 26851.0 2223.7 2646.7 2862.3 3096.3 3234.7 15585.9 15842.4 16382.0 16397.0 16406.6 1816.0 1841.8 1860.3 1881.4 1987.9 123286.4 126878.4 127285.0 128965.0 129309.7 8945.6 10546.9 10930.4 10961.4 11152.9 13934.9 13951.9 13956.4 13960.4 13971.9 8267.6 8408.5 8716.3 8726.7 8847.0 8780.0 8799.4 8802.9 8973.9 8997.9 6550.5 6680.6 6687.5 6719.8 6833.9 1772.5 1814.9 1822.3 1824.7 1829.1 724.6 805.6 817.3 959.8 1069.1 21680.7 22243.7 22340.6 22664.4 23127.9 847.9 907.4 921.7 941.6 1025.8 11030.3 11285.9 11462.2 11531.7 11541.1 498712.0 546132.0 576927.1 600431.0 603879.0 123716.4 125026.4 127311.0 128153.0 133615.0 914.7 932.9 997.5 1013.3 1016.9 18891.4 19504.4 19523.7 19530.3 19599.8 46821.8 52642.9 54125.0 54550.7 55536.4 78908.2 82316.4 84559.0 84926.6 85121.0 11131.6 11982.8 12184.4 12202.9 12792.8 13687.0 13895.4 13899.4 14372.6 14468.4 11890.0 12203.8 12300.8 12343.3 12362.6 11557.8 11731.2 11912.9 11962.6 12216.8 11751.5 11864.4 12047.1 12251.2 12284.4 12363.8 12445.9 12556.1 12674.3 12815.7 11427.8 11599.9 12244.0 12340.9 12346.5 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5677.63 Median:4817.99 stddev:3186.72 (66.1%) stddev-sans-first:3180.07 05:25:17 INFO - PROCESS | 1651 | Values: 1483.2 1500.1 1528.1 1860.6 1877.4 8293.9 8852.3 8876.7 8886.3 8900.0 7717.6 7945.6 7963.6 8077.1 8633.8 2154.4 2226.7 2298.3 2302.5 2309.3 2335.1 2369.8 2370.3 2371.0 2372.2 2359.7 2365.7 2367.3 2371.1 2372.3 2037.9 2300.6 2359.0 2366.5 2368.5 2327.0 2349.4 2353.2 2364.9 2367.1 8718.5 8762.2 8829.0 8860.8 8882.8 5186.8 5339.1 5342.8 5343.2 5345.0 3124.7 3470.0 3596.7 3653.5 3657.1 1028.1 1079.1 1082.6 1090.2 1091.6 11596.7 11602.0 11612.9 11633.2 11637.3 4573.6 4624.6 4774.0 4781.2 4810.0 9852.3 9857.4 9863.1 9865.9 9874.4 5305.3 5313.2 5318.3 5323.8 5324.9 4796.4 4826.0 4864.5 4885.7 4905.7 3861.7 3922.6 3924.7 3968.0 4032.8 1277.3 1286.3 1286.4 1295.4 1301.6 883.1 915.9 922.4 937.9 939.5 4782.9 5843.2 5848.5 5931.0 6157.5 441.0 444.8 445.9 989.5 1053.1 3926.3 4007.4 4141.2 4145.4 4168.0 10399.9 10816.1 10824.8 10830.3 10861.6 10771.9 10773.4 10777.0 10788.7 10817.6 7661.2 7855.3 8057.3 8203.7 8220.4 5883.8 5922.6 6083.2 6152.9 6186.3 9778.5 9972.8 10087.8 10108.1 10121.8 8034.8 10353.2 10371.8 10379.8 10388.1 6996.3 7231.5 7241.3 7318.0 7326.3 7451.5 7936.6 7955.7 8426.2 8509.1 7491.6 7518.8 7536.2 7606.2 7690.9 7346.9 7355.0 7375.6 7400.0 7552.7 6876.6 6907.2 6914.4 6967.7 6979.1 4605.0 4738.6 4939.7 5064.3 5221.5 4609.0 5015.0 5036.0 5149.2 5276.3 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4392.86 Median:4791.94 stddev:1334.13 (27.8%) stddev-sans-first:1301.42 05:25:17 INFO - PROCESS | 1651 | Values: 265.8 292.7 295.6 310.2 310.4 4262.7 4663.6 5055.0 5059.4 5265.1 4594.1 4741.5 4921.8 5169.4 5176.5 4000.8 4015.7 4021.3 4024.1 4084.4 3722.3 4044.4 4063.3 4083.5 4086.5 3913.9 3935.9 3938.7 3942.3 3975.8 3748.8 3899.3 3899.8 3905.6 3973.3 2988.8 3380.0 3418.2 3425.9 3428.7 4319.5 4760.4 5029.6 5046.3 5116.0 4455.3 4459.6 4461.5 4464.7 4471.8 5120.5 5356.9 5393.2 5455.9 5485.2 3451.4 3547.2 3733.6 3755.8 3762.9 5144.7 5647.9 5676.6 5694.9 5714.5 3178.9 3795.5 3835.9 3843.6 3844.1 3463.3 3705.5 3889.4 3904.0 3906.8 4040.5 4299.9 4511.7 4537.6 4620.1 5417.2 5436.0 5442.2 5519.7 5566.5 1156.0 1264.6 1321.8 1335.8 1339.1 838.7 845.6 855.2 870.8 882.9 4030.1 4151.9 4175.7 4208.2 4244.5 4446.3 4621.8 4665.4 4679.7 4702.9 4638.9 5105.3 5137.4 5166.7 5202.8 2921.4 2950.6 2956.5 2961.4 2980.5 4829.6 5410.4 5493.6 5684.8 5785.4 5746.2 5770.5 5771.0 5780.5 5786.0 4823.4 4967.5 5192.7 5816.8 5861.6 5347.4 5589.7 5633.3 5637.3 5661.2 4641.4 5473.3 5552.0 5552.3 5613.8 4997.9 5256.6 5318.7 5360.1 5398.6 3556.7 3769.3 3816.8 3820.4 3845.7 5489.5 5539.4 5676.0 5699.3 5860.8 5322.3 5562.6 5570.2 5576.8 5589.0 4717.6 5258.4 5391.6 5437.6 5559.6 4652.3 5119.5 5341.0 5348.1 5371.4 4508.7 5106.7 5259.5 5389.0 5445.8 5360.5 5394.4 5396.6 5465.4 5494.8 05:25:17 INFO - PROCESS | 1651 | 05:25:17 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:24072.71 Median:4549.02 stddev:37491.39 (824.2%) stddev-sans-first:37555.52 05:25:17 INFO - PROCESS | 1651 | Values: 709.8 716.3 766.5 819.6 830.8 14911.3 16085.8 16166.8 16185.0 16192.4 14491.6 14622.8 14634.2 14667.6 14691.6 656.3 681.2 815.3 816.2 844.5 820.4 829.5 836.7 837.1 837.1 836.6 852.0 890.6 891.7 894.9 693.0 723.6 885.1 887.0 887.8 533.7 534.1 534.9 639.9 662.8 15085.0 15141.2 15162.3 15171.1 15174.7 12332.6 14311.2 14371.6 14501.6 14572.0 10874.4 12703.9 12958.9 13425.5 13490.7 4593.9 4628.6 4653.8 4823.7 4825.9 98868.5 100054.0 104204.5 106940.9 122332.0 9242.3 9697.5 11059.5 11168.9 11200.9 17905.6 20255.3 20982.3 21794.8 21892.7 7105.2 7120.0 8084.6 8237.8 8267.8 4405.4 4470.8 4482.8 4504.1 4665.9 3651.3 3800.6 3804.3 3806.3 3814.3 680.4 710.1 713.2 725.9 727.3 593.2 666.5 678.0 680.0 688.7 8987.6 9010.6 9553.6 9687.6 9777.1 594.7 634.5 661.9 665.8 685.8 4606.3 4621.5 4652.7 4656.0 4798.1 93521.1 113701.0 141917.0 150892.0 158403.2 94451.0 109150.0 109646.5 130521.0 136953.3 106442.0 108494.5 112629.7 117069.4 118863.0 6839.6 9880.2 9944.0 9982.5 9993.0 64548.0 64705.7 65639.7 65938.0 66420.3 68746.7 92864.0 93191.5 93531.5 95480.0 10901.3 12816.6 13178.5 13454.4 13480.9 14852.4 14869.5 14987.3 15081.5 15209.7 13900.8 13996.8 14013.6 14056.8 14434.3 13940.5 14056.9 14085.2 14242.0 14354.3 13504.4 13647.9 13820.5 13832.5 14400.5 9920.3 10603.5 10814.4 11283.4 11415.3 9140.7 9161.0 11095.4 11624.1 11657.6 05:25:17 INFO - PROCESS | 1651 | -------- Summary: end -------- 05:25:17 INFO - PROCESS | 1651 | 05:25:18 INFO - TEST-INFO | 1651: exit 0 05:25:18 INFO - TEST-OK | dromaeo_css | took 1118367ms 05:25:18 INFO - TEST-START | kraken 05:25:18 INFO - operating with platform_type : mac_ 05:25:18 INFO - Initialising browser for kraken test... 05:25:18 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpiyGI1L/profile http://localhost:49266/getInfo.html) 05:25:20 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 05:25:20 INFO - PROCESS | 1658 | colorDepth:24 05:25:20 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 05:25:20 INFO - PROCESS | 1658 | __metrics 05:25:20 INFO - PROCESS | 1658 | 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" 05:25:20 INFO - PROCESS | 1658 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 122: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 05:25:21 INFO - TEST-INFO | 1658: exit 0 05:25:21 INFO - Browser initialized. 05:25:21 INFO - Running cycle 1/1 for kraken test... 05:25:21 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/T/tmpiyGI1L/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:25:33 INFO - PROCESS | 1659 | RSS: Main: 243691520 05:25:33 INFO - PROCESS | 1659 | 05:25:36 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection) 05:25:37 INFO - PROCESS | 1659 | RSS: Main: 294592512 05:25:37 INFO - PROCESS | 1659 | 05:25:39 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-dft) 05:25:40 INFO - PROCESS | 1659 | RSS: Main: 301256704 05:25:40 INFO - PROCESS | 1659 | 05:25:44 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-fft) 05:25:44 INFO - PROCESS | 1659 | RSS: Main: 398692352 05:25:44 INFO - PROCESS | 1659 | 05:25:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator) 05:25:47 INFO - PROCESS | 1659 | RSS: Main: 297955328 05:25:47 INFO - PROCESS | 1659 | 05:25:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:25:50 INFO - PROCESS | 1659 | RSS: Main: 302288896 05:25:50 INFO - PROCESS | 1659 | 05:25:52 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom) 05:25:53 INFO - PROCESS | 1659 | RSS: Main: 341471232 05:25:53 INFO - PROCESS | 1659 | 05:25:56 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate) 05:25:57 INFO - PROCESS | 1659 | RSS: Main: 341213184 05:25:57 INFO - PROCESS | 1659 | 05:25:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial) 05:26:00 INFO - PROCESS | 1659 | RSS: Main: 340140032 05:26:00 INFO - PROCESS | 1659 | 05:26:01 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:26:02 INFO - PROCESS | 1659 | RSS: Main: 313548800 05:26:02 INFO - PROCESS | 1659 | 05:26:04 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:26:05 INFO - PROCESS | 1659 | RSS: Main: 318251008 05:26:05 INFO - PROCESS | 1659 | 05:26:07 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:26:07 INFO - PROCESS | 1659 | RSS: Main: 321757184 05:26:07 INFO - PROCESS | 1659 | 05:26:10 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:26:11 INFO - PROCESS | 1659 | RSS: Main: 330842112 05:26:11 INFO - PROCESS | 1659 | 05:26:14 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:26:15 INFO - PROCESS | 1659 | RSS: Main: 332255232 05:26:15 INFO - PROCESS | 1659 | 05:26:16 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49266/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49266/tests/kraken/driver.html?testName=ai-astar) 05:26:17 INFO - PROCESS | 1659 | RSS: Main: 375517184 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | __start_tp_report 05:26:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 05:26:17 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 05:26:17 INFO - PROCESS | 1659 | |i|pagename|runs| 05:26:17 INFO - PROCESS | 1659 | |0;ai-astar;101;113;112;111;110;112;113;112;113;112 05:26:17 INFO - PROCESS | 1659 | |1;audio-beat-detection;144;110;110;198;135;134;127;108;159;90 05:26:17 INFO - PROCESS | 1659 | |2;audio-dft;186;183;185;185;184;183;186;184;180;183 05:26:17 INFO - PROCESS | 1659 | |3;audio-fft;80;58;61;89;58;57;65;70;84;56 05:26:17 INFO - PROCESS | 1659 | |4;audio-oscillator;84;69;126;102;110;99;96;70;69;100 05:26:17 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;87;86;89;86;90;86;90;85;88;86 05:26:17 INFO - PROCESS | 1659 | |6;imaging-darkroom;94;94;94;96;94;94;94;94;94;101 05:26:17 INFO - PROCESS | 1659 | |7;imaging-desaturate;85;86;83;84;84;85;84;83;84;83 05:26:17 INFO - PROCESS | 1659 | |8;json-parse-financial;55;52;53;53;52;53;54;53;52;53 05:26:17 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;65;72;68;75;71;69;70;72;71;71 05:26:17 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;86;84;84;84;85;82;81;84;86;85 05:26:17 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;138;137;136;136;138;135;153;133;138;138 05:26:17 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;168;168;172;169;174;168;168;167;144;191 05:26:17 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;69;67;67;68;69;67;66;66;67;67 05:26:17 INFO - PROCESS | 1659 | __end_tp_report 05:26:17 INFO - PROCESS | 1659 | __start_cc_report 05:26:17 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,2139 05:26:17 INFO - PROCESS | 1659 | __end_cc_report 05:26:17 INFO - PROCESS | 1659 | __startTimestamp1470659177005__endTimestamp 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | ------- Summary: start ------- 05:26:17 INFO - PROCESS | 1659 | Number of tests: 14 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:110.90 Median:112.00 stddev:3.60 (3.2%) stddev-sans-first:1.00 05:26:17 INFO - PROCESS | 1659 | Values: 101.0 113.0 112.0 111.0 110.0 112.0 113.0 112.0 113.0 112.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:139.50 stddev:30.86 (22.1%) stddev-sans-first:32.40 05:26:17 INFO - PROCESS | 1659 | Values: 144.0 110.0 110.0 198.0 135.0 134.0 127.0 108.0 159.0 90.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:183.90 Median:184.50 stddev:1.79 (1.0%) stddev-sans-first:1.73 05:26:17 INFO - PROCESS | 1659 | Values: 186.0 183.0 185.0 185.0 184.0 183.0 186.0 184.0 180.0 183.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:67.80 Median:67.50 stddev:12.33 (18.3%) stddev-sans-first:12.26 05:26:17 INFO - PROCESS | 1659 | Values: 80.0 58.0 61.0 89.0 58.0 57.0 65.0 70.0 84.0 56.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:92.50 Median:69.50 stddev:19.18 (27.6%) stddev-sans-first:20.10 05:26:17 INFO - PROCESS | 1659 | Values: 84.0 69.0 126.0 102.0 110.0 99.0 96.0 70.0 69.0 100.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:87.30 Median:87.50 stddev:1.83 (2.1%) stddev-sans-first:1.94 05:26:17 INFO - PROCESS | 1659 | Values: 87.0 86.0 89.0 86.0 90.0 86.0 90.0 85.0 88.0 86.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:94.90 Median:94.00 stddev:2.23 (2.4%) stddev-sans-first:2.35 05:26:17 INFO - PROCESS | 1659 | Values: 94.0 94.0 94.0 96.0 94.0 94.0 94.0 94.0 94.0 101.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:84.10 Median:84.00 stddev:0.99 (1.2%) stddev-sans-first:1.00 05:26:17 INFO - PROCESS | 1659 | Values: 85.0 86.0 83.0 84.0 84.0 85.0 84.0 83.0 84.0 83.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:53.00 Median:53.00 stddev:0.94 (1.8%) stddev-sans-first:0.67 05:26:17 INFO - PROCESS | 1659 | Values: 55.0 52.0 53.0 53.0 52.0 53.0 54.0 53.0 52.0 53.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:70.40 Median:71.00 stddev:2.67 (3.8%) stddev-sans-first:2.00 05:26:17 INFO - PROCESS | 1659 | Values: 65.0 72.0 68.0 75.0 71.0 69.0 70.0 72.0 71.0 71.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:84.10 Median:84.50 stddev:1.60 (1.9%) stddev-sans-first:1.54 05:26:17 INFO - PROCESS | 1659 | Values: 86.0 84.0 84.0 84.0 85.0 82.0 81.0 84.0 86.0 85.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:138.20 Median:138.00 stddev:5.45 (4.0%) stddev-sans-first:5.78 05:26:17 INFO - PROCESS | 1659 | Values: 138.0 137.0 136.0 136.0 138.0 135.0 153.0 133.0 138.0 138.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.90 Median:168.50 stddev:11.31 (6.7%) stddev-sans-first:11.99 05:26:17 INFO - PROCESS | 1659 | Values: 168.0 168.0 172.0 169.0 174.0 168.0 168.0 167.0 144.0 191.0 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.30 Median:67.00 stddev:1.06 (1.6%) stddev-sans-first:0.93 05:26:17 INFO - PROCESS | 1659 | Values: 69.0 67.0 67.0 68.0 69.0 67.0 66.0 66.0 67.0 67.0 05:26:17 INFO - PROCESS | 1659 | -------- Summary: end -------- 05:26:17 INFO - PROCESS | 1659 | 05:26:17 INFO - TEST-INFO | 1659: exit 0 05:26:17 INFO - TEST-OK | kraken | took 59650ms 05:26:17 INFO - SUITE-END | took 1178s 05:26:18 INFO - Completed test suite (00:19:38) 05:26:18 INFO - javascript benchmark 05:26:18 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3308.3383323335333, 3332.233586275155, 3336.916577085571, 3349.9447673982686, 3390.2541440882997, 4535.501094879561, 5646.350669672989, 5682.630629009117, 5693.8576535586635, 5714.371419143896, 4716.10394025135, 5692.345380281269, 5719.0, 5736.340320863102, 5736.741846616902, 4660.788195069273, 4796.3524924135145, 5158.43257241704, 5170.457102004273, 5220.712860792655, 3855.209682015194, 5187.195984622371, 5237.162054071349, 5274.630775845692, 5275.788968441258, 5166.793328266865, 5197.220416937452, 5220.843374698765, 5224.45143259958, 5261.579073674096, 5295.841124766263, 5306.495882891119, 5311.893762124774, 5339.225812257239, 5376.247325374451, 5108.616853735947, 5117.46266642001, 5160.200168973777, 5215.660982036194, 5224.294720212761, 5616.0, 5616.045272303718, 5684.63049901755, 5691.146328050825, 5694.886102277973, 6213.03697926822, 6217.658028808413, 6228.252609686821, 6229.2836323822885, 6241.594296370721, 6105.126180223406, 6178.505719542426, 6214.192155019864, 6250.15622890913, 6256.0, 5851.912221316684, 5854.385289544579, 5858.502027327725, 5858.619189752652, 5877.47102760745, 6290.2766181889665, 6329.905051424233, 6366.681665916778, 6371.23545174582, 6378.0, 5981.6710080945095, 5996.2504686914135, 6072.453479186895, 6101.389861013863, 6139.324674285825, 6211.9068213976825, 6217.689115544295, 6220.968895155495, 6228.34602366745, 6233.376662333731, 5085.237214417867, 6093.6039157454625, 6146.877062458726, 6153.600015999035, 6186.1648677427975, 6151.507879369639, 6208.72060757267, 6227.06594010902, 6241.21984751906, 6247.0, 4972.279019542146, 4978.6763860348965, 4986.850394488099, 4992.101421744121, 5005.899882002413, 3683.7605555638797, 4404.471463424409, 4406.735595864258, 4441.067375851043, 4492.528284530143, 5652.519535839499, 5653.858653533613, 5660.433956604306, 5668.801591944262, 5710.971445142748, 6211.9068213976825, 6273.592216506004, 6289.7169627366875, 6292.433680968735, 6307.180066591313, 5572.58205634579, 5601.355844077902, 5645.040343141593, 5651.2935883014625, 5661.547076233974, 4608.746518941491, 5438.15708565173, 5445.945540544623, 5447.1829225616475, 5458.699771512525, 6304.338044505261, 6317.873642527032, 6323.747050117944, 6334.841628959313, 6341.239051313872, 6111.8956224267595, 6172.598781079216, 6253.530985176221, 6268.561200715906, 6313.842153946188, 6276.529260305404, 6320.051992201206, 6322.399372059647, 6338.397852204033, 6370.1718776558755, 6113.877722445437, 6157.84605384869, 6161.815145545641, 6162.26052873658, 6167.96916015435, 6139.171211886338, 6154.323024467216, 6203.751849925952, 6261.686915654108, 6265.342139075331, 6082.2093127893095, 6086.452219300107, 6103.572749907464, 6115.755369785159, 6125.295591007091, 5942.524598032233, 6092.786752463642, 6106.389361063858, 6125.295591006913, 6128.1726966858905, 5685.20407142992, 5863.20846685692, 5891.970540147272, 5912.0, 5977.671228082323, 6103.708412588929, 6114.66235542843, 6132.845047373448, 6144.095330447575, 6146.0473626587955, 5962.642241465526, 5963.970180149072, 5970.850728731816, 5971.970140149271, 5989.101634754647], "value": 5659.126572436179, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.92114301140316, 523.1446584833797, 528.1391332128629, 534.5830252403127, 549.9945000549993, 30669.0, 30969.690303096962, 32018.839905800498, 32108.518372224407, 40151.99620009501, 40284.39573406401, 42634.7209583713, 42672.0, 42680.78659606705, 42760.78619606898, 9579.473128977903, 9754.707358779213, 9772.706818795448, 9780.119789218968, 9799.0, 8696.826063478728, 8874.023857375683, 9616.951915240414, 9723.951380243125, 9733.367331123454, 11592.826107608393, 11840.05279577636, 11867.228630139056, 12080.275183489019, 12092.33492157931, 11675.357855317954, 11738.589149379815, 11741.0, 11741.94322510978, 11754.823677644843, 11640.06879449642, 11706.473208705629, 11712.707182320424, 11721.472533736003, 11731.296122232694, 38305.23389532221, 39948.40077398841, 40209.59790402088, 40294.59705402938, 40325.79837100796, 3468.150303175713, 3611.4402267648557, 3645.7447978641508, 3706.4810926470313, 4158.896027599304, 31033.0, 31825.30405153322, 32428.675713243036, 32476.675233247603, 32588.6741132588, 3204.455242608762, 3205.7069719909928, 3253.3981213475345, 3261.266215101607, 3266.216108134031, 497925.0, 499272.0, 502541.0, 503154.4842275802, 504242.0, 56792.0, 57054.429455704914, 57122.0, 57239.427605724246, 57322.7133864328, 61287.0, 61406.0789088164, 61467.0, 61522.69238653778, 61556.0, 16185.757213641804, 16374.672506549803, 16386.508404747878, 16396.508104756875, 16416.343346266014, 12851.16467429614, 12904.0, 12926.67683307906, 12934.805977910339, 12937.482500700055, 13806.309684515734, 14197.21915294648, 14276.85723142776, 15379.000364976464, 15650.295736691649, 6441.484681225491, 6590.703418346186, 6645.667716614246, 6646.401823835878, 6661.067450556926, 914.9451032938046, 959.5633986536109, 988.7824678570703, 1038.99480502597, 1123.786310784351, 25960.0, 28812.711872881002, 30007.249818754706, 30250.78996840146, 30930.69069309323, 1063.7819247054335, 1114.8272017837248, 1128.4639796096953, 1141.0, 1153.9423028848692, 15207.695846083237, 15549.611259718598, 15625.843741562667, 15636.3745450183, 16035.518934431986, 186477.20284195748, 187536.0, 187561.0, 187968.9023323256, 188435.0, 50288.748556256985, 56923.43076569264, 57399.56501087423, 57957.71021144867, 58227.4177258222, 1355.0717758335572, 1363.1003537665292, 1393.3312010235145, 1397.2734178227063, 1401.6776141487514, 29877.850610746806, 30646.540301895042, 31241.531377029816, 31861.05704446755, 32262.709491620073, 49849.25226121684, 50215.0, 50232.72556381673, 50259.0, 50319.0, 786131.0, 810091.0, 835688.0, 839800.0, 839883.0, 26282.60576091322, 26541.867290663424, 26552.937882484483, 26557.20328390074, 26581.0, 47337.763311183226, 47463.762681186374, 47779.0, 47983.76008119937, 48983.755081224364, 38610.227795443374, 39082.413763792996, 39367.80316098401, 40375.39436908507, 40554.79722601368, 38287.61712382952, 38842.41736374013, 39499.01252468596, 39754.80122599369, 40063.599364007154, 37967.62032379641, 38006.0, 38662.0, 38800.0, 38827.8351649451, 31803.68196318007, 31829.0, 32450.675493244766, 34431.76193547411, 35385.292294153456, 20138.798612013692, 25457.87271063633, 25562.744372556037, 25596.872015640547, 25940.740592593833], "value": 19641.518834088416, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.8817846021583, 360.7745159275453, 370.36482432628037, 375.48370989888895, 398.64321432318076, 14985.475508357209, 15490.535283941472, 20362.273579013116, 20446.897765511152, 20525.07637156328, 20643.0, 20959.952002399896, 21256.0, 21379.67930481044, 21584.676229856566, 5783.132530120473, 5851.3270973838025, 6012.278526576795, 6115.938840611581, 6117.724702388381, 3461.9480707789403, 5953.940460595382, 6060.151578778975, 6062.787802426916, 6064.120702498135, 6156.753729850823, 6413.679316034203, 6422.26143993442, 6518.576292540994, 6544.869102617945, 6266.248050234001, 6442.48460123189, 6481.935180648204, 6502.089707440962, 6535.21577410709, 5686.289213848269, 5696.572757043197, 5697.829065128053, 5697.857553561153, 5701.600887937804, 26383.868080659475, 26504.0, 26689.466210675677, 26734.0, 26851.0, 2223.7109175807204, 2646.6559347284924, 2862.298736809474, 3096.28785379364, 3234.7412207023617, 15585.922070389688, 15842.44551440694, 16382.0170789753, 16397.0, 16406.589835254093, 1816.0, 1841.815818418161, 1860.3488778927267, 1881.397952655151, 1987.9403617891487, 123286.38356808248, 126878.36560817229, 127285.0, 128964.99999999907, 129309.7069029307, 8945.552722363856, 10546.947265263625, 10930.398828064373, 10961.397123158144, 11152.944235278934, 13934.860651393356, 13951.860481395057, 13956.371963261678, 13960.371783269777, 13971.86028139726, 8267.586620668942, 8408.537530435884, 8716.302695784321, 8726.723905286364, 8846.95576522126, 8779.956100219586, 8799.384043116921, 8802.91197088021, 8973.910260897437, 8997.910020899839, 6550.508711846631, 6680.565763225375, 6687.465002799764, 6719.798406047827, 6833.93166068333, 1772.5125590462726, 1814.9455516334267, 1822.261983896524, 1824.7373257161025, 1829.1494455078453, 724.6376811594203, 805.6052534258172, 817.3093735793245, 959.788846453779, 1069.0699091790193, 21680.699158050567, 22243.666345004836, 22340.55318893613, 22664.433389165402, 23127.88436057809, 847.9236868681849, 907.4373888189365, 921.6635927886273, 941.5904081724335, 1025.7640742629237, 11030.28303160292, 11285.927836855486, 11462.197646164856, 11531.654050378502, 11541.07671386287, 498712.0, 546132.0, 576927.1153644288, 600431.0, 603879.0, 123716.38141809234, 125026.37486812507, 127311.0, 128153.0, 133615.0, 914.6615752171606, 932.8758845591046, 997.4613708597276, 1013.3210748798323, 1016.8627235323285, 18891.43325700231, 19504.414867553995, 19523.70714439313, 19530.31643892457, 19599.824010559412, 46821.765891171686, 52642.894284228205, 54125.0, 54550.7272463651, 55536.444635553125, 78908.21091789239, 82316.3536729274, 84559.0, 84926.57536712277, 85121.0, 11131.554737810397, 11982.760344792881, 12184.390780461119, 12202.901738843546, 12792.808107878576, 13687.041907066809, 13895.359881622458, 13899.37452814646, 14372.568822935329, 14468.421263149778, 11889.978703620578, 12203.75592488163, 12300.815487767512, 12343.321117338633, 12362.567310144103, 11557.826632600685, 11731.23746956445, 11912.940435297767, 11962.58130965412, 12216.816747748968, 11751.47118379692, 11864.40677966081, 12047.096467765128, 12251.203671761306, 12284.385780711107, 12363.814542782045, 12445.875541244472, 12556.121071525271, 12674.302913339854, 12815.743685126432, 11427.771444571228, 11599.884001160219, 12244.0, 12340.876591234331, 12346.506139754309], "value": 10251.42498452951, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1483.2138966347839, 1500.0849481816088, 1528.128966489101, 1860.6185731924957, 1877.3898482993031, 8293.917060829397, 8852.336074794384, 8876.689315873946, 8886.333524985635, 8899.95550022249, 7717.614119294041, 7945.602719863999, 7963.562004089773, 8077.071136819253, 8633.827323453543, 2154.3967689047054, 2226.743924448685, 2298.2645553422913, 2302.51647154097, 2309.3187509684685, 2335.089315167086, 2369.7985671217966, 2370.3363058343693, 2370.9525809483885, 2372.1578839511985, 2359.7050368703913, 2365.727941286757, 2367.266147494274, 2371.134535894395, 2372.2764556810166, 2037.9184832606677, 2300.551392478476, 2358.9528209435716, 2366.538524987628, 2368.514454536833, 2327.034280773477, 2349.4126468382906, 2353.2351985604746, 2364.9054037838637, 2367.0650093213085, 8718.476891386474, 8762.21140097388, 8828.955855220747, 8860.822783544358, 8882.777930551723, 5186.7925282988635, 5339.092354299778, 5342.759575819097, 5343.225232341289, 5344.973275133639, 3124.703153200442, 3469.9826500867584, 3596.6762991330907, 3653.451982202664, 3657.1040095176586, 1028.110684258119, 1079.0773888325525, 1082.577794660083, 1090.2259395828971, 1091.5742860284495, 11596.710082247926, 11602.0, 11612.88387116135, 11633.243839150427, 11637.301761894229, 4573.588377046081, 4624.630029597624, 4774.021325628238, 4781.2350023996, 4809.975950120297, 9852.261080418853, 9857.408555486694, 9863.112319891245, 9865.901340986642, 9874.40753554789, 5305.31030965972, 5313.229581710631, 5318.308619879391, 5323.813666521653, 5324.866878327995, 4796.37647105874, 4826.0, 4864.537868902448, 4885.731284779371, 4905.705657660552, 3861.691064714816, 3922.5685174630767, 3924.7056470764805, 3968.047668559525, 4032.7580345179385, 1277.2783377391745, 1286.2861112082883, 1286.408252203978, 1295.430010795247, 1301.6095171448717, 883.0992387764444, 915.9221466175317, 922.4188761080476, 937.8604994931192, 939.5349302095507, 4782.928256076161, 5843.18195452637, 5848.54430505183, 5930.970345148333, 6157.538184636171, 441.04073639833234, 444.7776111944028, 445.9085887393077, 989.5497548615363, 1053.115383078218, 3926.2736393766672, 4007.358822588372, 4141.233871733739, 4145.3781932709735, 4167.99968007682, 10399.94800025995, 10816.08063314627, 10824.837627435594, 10830.296030757976, 10861.619843305605, 10771.946140269405, 10773.35359878394, 10777.030067293981, 10788.676339709822, 10817.567297308176, 7661.233876612294, 7855.253750893656, 8057.267687447227, 8203.671853125808, 8220.3834712398, 5883.764649413975, 5922.585419020627, 6083.178770865877, 6152.938470615416, 6186.319504854552, 9778.533220017054, 9972.800543989224, 10087.848682269625, 10108.1408080314, 10121.823807881341, 8034.799130021796, 10353.223508237063, 10371.844422333821, 10379.792404151724, 10388.065074143364, 6996.265392133752, 7231.5299505531975, 7241.312075352833, 7318.0, 7326.3040011198855, 7451.515651482637, 7936.56348900816, 7955.721549745963, 8426.157384261525, 8509.10654381281, 7491.550506969599, 7518.812029699301, 7536.208698086842, 7606.20134885829, 7690.884636730341, 7346.889796653161, 7355.0070740449355, 7375.594342311224, 7400.0, 7552.697892084255, 6876.553024053621, 6907.240203577502, 6914.412274956685, 6967.65161741921, 6979.092717946634, 4604.976975115103, 4738.573528382462, 4939.678920870132, 5064.29099926018, 5221.451747566451, 4608.953910460987, 5015.04714104319, 5035.974820125876, 5149.176131819041, 5276.314079169683], "value": 4538.098241936967, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.8165865552769, 292.7160654165459, 295.62160434643664, 310.2151556561899, 310.43810702628247, 4262.72118364491, 4663.580277775, 5054.963732434851, 5059.392872855254, 5265.1041926940225, 4594.081183763244, 4741.549552792488, 4921.80312787488, 5169.353830771153, 5176.5341119299255, 4000.83975647062, 4015.6586690131303, 4021.2761702893467, 4024.134811015631, 4084.36692312692, 3722.274156539476, 4044.413560033793, 4063.3295506241434, 4083.530394004683, 4086.468759061318, 3913.921721565567, 3935.862244821432, 3938.7045971552106, 3942.3192229284077, 3975.8012099394914, 3748.775073495599, 3899.337112690835, 3899.8050097495297, 3905.628965248325, 3973.284808734427, 2988.7907846450757, 3379.9691094216255, 3418.2479854431986, 3425.880094196716, 3428.7428442866885, 4319.460067491564, 4760.428748550162, 5029.597632189416, 5046.318746969149, 5116.0, 4455.331700244957, 4459.576340247671, 4461.5315391883705, 4464.6874718769695, 4471.821127154943, 5120.462351453081, 5356.937579032062, 5393.2449457076045, 5455.863603409907, 5485.204645326446, 3451.430513965193, 3547.155479150853, 3733.5893051764283, 3755.793431361274, 3762.8682996095004, 5144.6913185209005, 5647.887042259132, 5676.574256930747, 5694.943050569483, 5714.457126572927, 3178.872845086171, 3795.4686343911876, 3835.884923452301, 3843.5964223756664, 3844.0774214188395, 3463.3073385323037, 3705.5368078990127, 3889.436031775433, 3904.04350934023, 3906.7655940643654, 4040.4747382840633, 4299.871003869889, 4511.70673906195, 4537.591616754508, 4620.0990806793025, 5417.214503896913, 5436.0, 5442.2108794225405, 5519.69641669704, 5566.520924422236, 1155.9769603900609, 1264.5647190438917, 1321.7686904791624, 1335.7662409078566, 1339.1228745171927, 838.6645341863303, 845.5941148248875, 855.170484629908, 870.7735988642999, 882.885224920769, 4030.1133750574827, 4151.854685086068, 4175.68682348825, 4208.169446290874, 4244.533101358848, 4446.333050042519, 4621.815127394933, 4665.367121507513, 4679.742614156254, 4702.882427939329, 4638.8608341749805, 5105.285260063594, 5137.434882162959, 5166.664166829144, 5202.76587553561, 2921.378634857632, 2950.645922489315, 2956.512175491063, 2961.392914452532, 2980.478416277186, 4829.5653391194965, 5410.404855465896, 5493.642913210629, 5684.77260909559, 5785.363610002896, 5746.224259724967, 5770.538356931435, 5770.971145144247, 5780.508656764222, 5786.0, 4823.445303790109, 4967.528084832007, 5192.688438693615, 5816.76732930678, 5861.560382971379, 5347.411784703601, 5589.748461319332, 5633.324001119892, 5637.3235211774845, 5661.179129026351, 4641.443026836801, 5473.261109750292, 5552.0, 5552.250446189712, 5613.775448981995, 4997.85006449807, 5256.579473642023, 5318.680879147263, 5360.115580929103, 5398.64908780928, 3556.7332450065833, 3769.3026790043928, 3816.751911125768, 3820.350540408193, 3845.6924645620625, 5489.451054894478, 5539.446055394428, 5675.971620141873, 5699.344575373885, 5860.769238459971, 5322.33470816148, 5562.581541706839, 5570.220169176399, 5576.832695019156, 5588.993981083444, 4717.589054234302, 5258.368995720538, 5391.62258641907, 5437.592180586393, 5559.555235581063, 4652.255639097728, 5119.513646203604, 5341.0, 5348.117560602457, 5371.409144994126, 4508.729476231437, 5106.66806657566, 5259.500347466985, 5389.0, 5445.782168713207, 5360.517553420211, 5394.379646340564, 5396.64921780083, 5465.426130256425, 5494.835154945358], "value": 3965.1158806877556, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [709.7977076533188, 716.292690648621, 766.5247546521158, 819.6024927909966, 830.7590798668386, 14911.254437278134, 16085.758713619276, 16166.7574986375, 16184.999999999985, 16192.352305907765, 14491.637709057279, 14622.780658290134, 14634.195119268463, 14667.63330916725, 14691.632709182275, 656.3010393930473, 681.1961884975726, 815.2988429950238, 816.2368185746346, 844.4764246167354, 820.4134044158426, 829.531314807132, 836.6987884361625, 837.0541288344162, 837.1461109668135, 836.5945212043764, 851.9957400213021, 890.6170346750927, 891.7012800711736, 894.8657701344786, 692.9965350173217, 723.6345645449014, 885.0618344554761, 886.9512176830272, 887.8357503861806, 533.6717918480145, 534.0680512505702, 534.9331333583302, 639.9264084630234, 662.7713438863584, 15085.019473734283, 15141.167235802026, 15162.31769570372, 15171.089734615958, 15174.696506069928, 12332.568360107443, 14311.212883291415, 14371.640708982255, 14501.637459063502, 14572.0, 10874.401907895062, 12703.93648031754, 12958.935205324007, 13425.462981480729, 13490.662733431645, 4593.931091033637, 4628.583427491509, 4653.767311634439, 4823.686460380064, 4825.879353016167, 98868.50565747198, 100054.0, 104204.47897760538, 106940.93059069387, 122332.0, 9242.260619150451, 9697.515124243759, 11059.50232239535, 11168.944155279334, 11200.887991119984, 17905.641887162183, 20255.291064812955, 20982.2656207032, 21794.78205217959, 21892.671609925863, 7105.218425973138, 7120.0, 8084.555349455719, 8237.79405514867, 8267.751967440987, 4405.405270288534, 4470.8435204767675, 4482.7534485602955, 4504.09918016398, 4665.906681866344, 3651.3427583034777, 3800.638939300762, 3804.2771873343977, 3806.3148633246283, 3814.294355544233, 680.363859791097, 710.1442761472414, 713.201214639607, 725.9310365515267, 727.3453891497694, 593.2258402784322, 666.4535081233342, 677.9830504237335, 679.9762008329784, 688.6694386694414, 8987.55062246885, 9010.621017118581, 9553.570089345996, 9687.564059617334, 9777.134472776228, 594.7234535940809, 634.4892361648917, 661.9238787539398, 665.766981556461, 685.8491131950963, 4606.30905364198, 4621.491635920046, 4652.674312798072, 4655.953440465552, 4798.1123492154065, 93521.06478935124, 113701.0, 141917.0, 150892.0, 158403.20798395935, 94451.0, 109150.0, 109646.45176774065, 130521.0, 136953.3152334232, 106442.0, 108494.45752771186, 112629.74740505144, 117069.41465292619, 118863.0, 6839.589624622538, 9880.209583233467, 9943.950280248553, 9982.450965196984, 9993.0, 64548.0, 64705.67647161734, 65639.68720625465, 65938.0, 66420.33579664142, 68746.65626671835, 92864.0, 93191.53404232935, 93531.53234233786, 95480.0, 10901.277734396232, 12816.615501534969, 13178.538751143664, 13454.394552245369, 13480.865191348354, 14852.40590376416, 14869.47956821506, 14987.325570349143, 15081.54755357341, 15209.695806083595, 13900.791488127888, 13996.790048149489, 14013.649658758613, 14056.78914816299, 14434.278286085864, 13940.512082077079, 14056.859431405555, 14085.225312607496, 14242.003059785715, 14354.282285885454, 13504.392302346614, 13647.86352136466, 13820.516281930084, 13832.515861944783, 14400.495982640557, 9920.255980801614, 10603.46982650849, 10814.35113893169, 11283.435828208392, 11415.315081095161, 9140.6800761973, 9160.954195229247, 11095.389753563308, 11624.128190385585, 11657.591984280509], "value": 7810.300890055471, "unit": "score"}], "name": "dromaeo_css", "value": 7369.292295524506, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 113.0, 112.0, 111.0, 110.0, 112.0, 113.0, 112.0, 113.0, 112.0], "value": 110.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 110.0, 110.0, 198.0, 135.0, 134.0, 127.0, 108.0, 159.0, 90.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 183.0, 185.0, 185.0, 184.0, 183.0, 186.0, 184.0, 180.0, 183.0], "value": 183.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 61.0, 89.0, 58.0, 57.0, 65.0, 70.0, 84.0, 56.0], "value": 67.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 126.0, 102.0, 110.0, 99.0, 96.0, 70.0, 69.0, 100.0], "value": 92.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 89.0, 86.0, 90.0, 86.0, 90.0, 85.0, 88.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 94.0, 96.0, 94.0, 94.0, 94.0, 94.0, 94.0, 101.0], "value": 94.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 86.0, 83.0, 84.0, 84.0, 85.0, 84.0, 83.0, 84.0, 83.0], "value": 84.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 52.0, 53.0, 53.0, 52.0, 53.0, 54.0, 53.0, 52.0, 53.0], "value": 53.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 72.0, 68.0, 75.0, 71.0, 69.0, 70.0, 72.0, 71.0, 71.0], "value": 70.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 84.0, 84.0, 85.0, 82.0, 81.0, 84.0, 86.0, 85.0], "value": 84.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 136.0, 136.0, 138.0, 135.0, 153.0, 133.0, 138.0, 138.0], "value": 138.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 168.0, 172.0, 169.0, 174.0, 168.0, 168.0, 167.0, 144.0, 191.0], "value": 168.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 67.0, 67.0, 68.0, 69.0, 67.0, 66.0, 66.0, 67.0, 67.0], "value": 67.3, "unit": "score"}], "name": "kraken", "value": 1434.8, "alertThreshold": 2.0}]} 05:26:18 INFO - Return code: 0 05:26:18 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:26:18 INFO - # TBPL SUCCESS # 05:26:18 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:26:18 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:26:18 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:26:18 INFO - 2016-08-08 05:26:18.312 screenresolution[1663:10217] starting screenresolution argv=screenresolution get 05:26:18 INFO - 2016-08-08 05:26:18.315 screenresolution[1663:10217] Display 0: 1600x1200x32@60 05:26:18 INFO - 2016-08-08 05:26:18.322 screenresolution[1664:10220] starting screenresolution argv=screenresolution list 05:26:18 INFO - Available Modes on Display 0 05:26:18 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:26:18 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:26:18 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:26:18 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:26:18 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:26:18 INFO - Intel Iris: 05:26:18 INFO - Chipset Model: Intel Iris 05:26:18 INFO - Type: GPU 05:26:18 INFO - Bus: Built-In 05:26:18 INFO - VRAM (Dynamic, Max): 1536 MB 05:26:18 INFO - Vendor: Intel (0x8086) 05:26:18 INFO - Device ID: 0x0a2e 05:26:18 INFO - Revision ID: 0x0009 05:26:18 INFO - Displays: 05:26:18 INFO - Display: 05:26:18 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:26:18 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:26:18 INFO - Display Serial Number: 1600x1200 60 05:26:18 INFO - Main Display: Yes 05:26:18 INFO - Mirror: Off 05:26:18 INFO - Online: Yes 05:26:18 INFO - Rotation: Supported 05:26:18 INFO - Return code: 0 05:26:18 INFO - Running post-action listener: _resource_record_post_action 05:26:18 INFO - [mozharness: 2016-08-08 12:26:18.558248Z] Finished run-tests step (success) 05:26:18 INFO - Running post-run listener: _resource_record_post_run 05:26:18 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 31808512; Write bytes: 706005504; Read time: 319; Write time: 1984 05:26:18 INFO - TinderboxPrint: CPU usage
53.4% 05:26:18 INFO - TinderboxPrint: I/O read bytes / time
31,808,512 / 319 05:26:18 INFO - TinderboxPrint: I/O write bytes / time
706,005,504 / 1,984 05:26:18 INFO - TinderboxPrint: CPU idle
2,264.0 (46.6%) 05:26:18 INFO - TinderboxPrint: CPU system
964.0 (19.9%) 05:26:18 INFO - TinderboxPrint: CPU user
1,625.7 (33.5%) 05:26:18 INFO - TinderboxPrint: Swap in / out
434,782,208 / 0 05:26:18 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396382720; Write bytes: 390870528; Read time: 29321; Write time: 761 05:26:18 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29936640; Write bytes: 295302144; Read time: 300; Write time: 1181 05:26:19 INFO - Running post-run listener: _upload_blobber_files 05:26:19 INFO - Blob upload gear active. 05:26:19 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:26:19 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 05:26:19 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'] 05:26:19 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 05:26:19 INFO - (blobuploader) - INFO - Open directory for files ... 05:26:19 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 05:26:19 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:19 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:20 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 05:26:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:20 INFO - (blobuploader) - INFO - Done attempting. 05:26:20 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 05:26:20 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:20 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:21 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 05:26:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:21 INFO - (blobuploader) - INFO - Done attempting. 05:26:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:26:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:26:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:26:24 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:26:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:26:24 INFO - (blobuploader) - INFO - Done attempting. 05:26:24 INFO - (blobuploader) - INFO - Iteration through files over. 05:26:24 INFO - Return code: 0 05:26:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:26:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:26:24 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d6e880e2cc5a6b106ddb2f9981ef4140d8d272e2b99c76ecc0cc06554b2bce81e953b5a1f467013d1512d469d35d419ae3efaf68350a440f3195420aeb3395", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ae27272319134eddd34ae0d52113bbb27e128ecf03d77c48615b28063fa5a2c8be792441453b511a36bc951bf814fbbee322ebf3a56f17cd483be539bfd79c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7a2086793ba33d60c4292fd353d08635500da46f1a72e664d7cf3d70c57155e53c5c9d6d40922c794ab787b463aaf6f90fbc3012e3f25a8bd1623c4cb4ace52"} 05:26:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:26:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:26:24 INFO - Contents: 05:26:24 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f6d6e880e2cc5a6b106ddb2f9981ef4140d8d272e2b99c76ecc0cc06554b2bce81e953b5a1f467013d1512d469d35d419ae3efaf68350a440f3195420aeb3395", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ae27272319134eddd34ae0d52113bbb27e128ecf03d77c48615b28063fa5a2c8be792441453b511a36bc951bf814fbbee322ebf3a56f17cd483be539bfd79c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7a2086793ba33d60c4292fd353d08635500da46f1a72e664d7cf3d70c57155e53c5c9d6d40922c794ab787b463aaf6f90fbc3012e3f25a8bd1623c4cb4ace52"} 05:26:24 INFO - Running post-run listener: copy_logs_to_upload_dir 05:26:24 INFO - Copying logs to upload dir... 05:26:24 INFO - mkdir: /builds/slave/test/build/upload/logs 05:26:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.760479 ========= master_lag: 0.40 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-08 05:26:24.477629) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:24.480649) ========= 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.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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/f6d6e880e2cc5a6b106ddb2f9981ef4140d8d272e2b99c76ecc0cc06554b2bce81e953b5a1f467013d1512d469d35d419ae3efaf68350a440f3195420aeb3395", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ae27272319134eddd34ae0d52113bbb27e128ecf03d77c48615b28063fa5a2c8be792441453b511a36bc951bf814fbbee322ebf3a56f17cd483be539bfd79c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7a2086793ba33d60c4292fd353d08635500da46f1a72e664d7cf3d70c57155e53c5c9d6d40922c794ab787b463aaf6f90fbc3012e3f25a8bd1623c4cb4ace52"} build_url:https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010991 build_url: 'https://queue.taskcluster.net/v1/task/Xm0TwQN8QGKRoOIzRgX9RA/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/f6d6e880e2cc5a6b106ddb2f9981ef4140d8d272e2b99c76ecc0cc06554b2bce81e953b5a1f467013d1512d469d35d419ae3efaf68350a440f3195420aeb3395", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a5ae27272319134eddd34ae0d52113bbb27e128ecf03d77c48615b28063fa5a2c8be792441453b511a36bc951bf814fbbee322ebf3a56f17cd483be539bfd79c", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c7a2086793ba33d60c4292fd353d08635500da46f1a72e664d7cf3d70c57155e53c5c9d6d40922c794ab787b463aaf6f90fbc3012e3f25a8bd1623c4cb4ace52"}' ========= master_lag: 0.06 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 05:26:24.551424) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:26:24.552057) ========= 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.xz58nYqN5j/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.blhWUGfecm/Listeners TMPDIR=/var/folders/np/clqv8frd41g38z3rj4xsbrlc00000w/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.005277 ========= master_lag: 1.64 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 05:26:26.192642) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:26:26.192967) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 05:26:26.244232) ========= ========= Total master_lag: 2.17 =========