builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0059 starttime: 1471983983.9 results: success (0) buildid: 20160823104659 builduid: 3a345d588f95454f913bb799c8136d9c revision: 6e17a469a1b137c535adee7e476f86ee9dfcc071 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:23.899401) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:23.899911) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:23.900194) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:23.925275) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:23.925595) ========= 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.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-23 13:26:23-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 39.7M=0s 2016-08-23 13:26:24 (39.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.123745 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:24.072659) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:24.072975) ========= 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.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.027961 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:24.121087) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 13:26:24.121438) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 6e17a469a1b137c535adee7e476f86ee9dfcc071 --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 6e17a469a1b137c535adee7e476f86ee9dfcc071 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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-23 13:26:24,191 truncating revision to first 12 chars 2016-08-23 13:26:24,191 Setting DEBUG logging. 2016-08-23 13:26:24,191 attempt 1/10 2016-08-23 13:26:24,191 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/6e17a469a1b1?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 13:26:25,314 unpacking tar archive at: fx-team-6e17a469a1b1/testing/mozharness/ program finished with exit code 0 elapsedTime=1.363945 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-23 13:26:25.507317) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:25.507654) ========= script_repo_revision: 6e17a469a1b137c535adee7e476f86ee9dfcc071 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:25.508082) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:25.508349) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 13:26:25.526847) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 13:26:25.527130) ========= /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.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:26:25 INFO - MultiFileLogger online at 20160823 13:26:25 in /builds/slave/test 13:26:25 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 13:26:25 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:26:25 INFO - {'append_to_log': False, 13:26:25 INFO - 'base_work_dir': '/builds/slave/test', 13:26:25 INFO - 'blob_upload_branch': 'Fx-Team', 13:26:25 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:26:25 INFO - 'branch': 'Fx-Team', 13:26:25 INFO - 'buildbot_json_path': 'buildprops.json', 13:26:25 INFO - 'config_files': ('talos/mac_config.py',), 13:26:25 INFO - 'default_actions': ('clobber', 13:26:25 INFO - 'read-buildbot-config', 13:26:25 INFO - 'download-and-extract', 13:26:25 INFO - 'populate-webroot', 13:26:25 INFO - 'create-virtualenv', 13:26:25 INFO - 'install', 13:26:25 INFO - 'run-tests'), 13:26:25 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:26:25 INFO - 'download_minidump_stackwalk': True, 13:26:25 INFO - 'download_symbols': 'ondemand', 13:26:25 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:26:25 INFO - 'tooltool.py': '/tools/tooltool.py', 13:26:25 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:26:25 INFO - '/tools/misc-python/virtualenv.py')}, 13:26:25 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:26:25 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:26:25 INFO - 'installer_path': 'installer.exe', 13:26:25 INFO - 'log_level': 'info', 13:26:25 INFO - 'log_name': 'talos', 13:26:25 INFO - 'log_to_console': True, 13:26:25 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 13:26:25 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 13:26:25 INFO - 'opt_config_files': (), 13:26:25 INFO - 'pip_index': False, 13:26:25 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:26:25 INFO - 'cmd': ('bash', 13:26:25 INFO - '-c', 13:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:26:25 INFO - 'enabled': True, 13:26:25 INFO - 'halt_on_failure': False, 13:26:25 INFO - 'name': 'check_screen_resolution'},), 13:26:25 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 13:26:25 INFO - 'cmd': ('bash', 13:26:25 INFO - '-c', 13:26:25 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 13:26:25 INFO - 'enabled': True, 13:26:25 INFO - 'halt_on_failure': False, 13:26:25 INFO - 'name': 'check_screen_resolution'},), 13:26:25 INFO - 'run_cmd_checks_enabled': True, 13:26:25 INFO - 'sps_profile': False, 13:26:25 INFO - 'sps_profile_interval': 0, 13:26:25 INFO - 'suite': 'dromaeojs', 13:26:25 INFO - 'system_bits': '32', 13:26:25 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:26:25 INFO - 'title': 't-yosemite-r7-0059', 13:26:25 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:26:25 INFO - 'use_talos_json': True, 13:26:25 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:26:25 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:26:25 INFO - 'work_dir': 'build'} 13:26:25 INFO - [mozharness: 2016-08-23 20:26:25.721975Z] Running clobber step. 13:26:25 INFO - Running pre-action listener: _resource_record_pre_action 13:26:25 INFO - Running main action method: clobber 13:26:25 INFO - rmtree: /builds/slave/test/build 13:26:25 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:26:27 INFO - Running post-action listener: _resource_record_post_action 13:26:27 INFO - [mozharness: 2016-08-23 20:26:27.402981Z] Finished clobber step (success) 13:26:27 INFO - [mozharness: 2016-08-23 20:26:27.403108Z] Running read-buildbot-config step. 13:26:27 INFO - Running pre-action listener: _resource_record_pre_action 13:26:27 INFO - Running main action method: read_buildbot_config 13:26:27 INFO - Using buildbot properties: 13:26:27 INFO - { 13:26:27 INFO - "project": "", 13:26:27 INFO - "product": "firefox", 13:26:27 INFO - "script_repo_revision": "production", 13:26:27 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 13:26:27 INFO - "repository": "", 13:26:27 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 13:26:27 INFO - "buildid": "20160823104659", 13:26:27 INFO - "pgo_build": "False", 13:26:27 INFO - "basedir": "/builds/slave/test", 13:26:27 INFO - "buildnumber": 821, 13:26:27 INFO - "slavename": "t-yosemite-r7-0059", 13:26:27 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 13:26:27 INFO - "platform": "yosemite_r7", 13:26:27 INFO - "scheduler": "tests-fx-team-macosx64-talos", 13:26:27 INFO - "branch": "fx-team", 13:26:27 INFO - "repo_path": "integration/fx-team", 13:26:27 INFO - "revision": "6e17a469a1b137c535adee7e476f86ee9dfcc071", 13:26:27 INFO - "stage_platform": "macosx64", 13:26:27 INFO - "builduid": "3a345d588f95454f913bb799c8136d9c", 13:26:27 INFO - "slavebuilddir": "test" 13:26:27 INFO - } 13:26:27 INFO - Found installer url https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 13:26:27 INFO - Running post-action listener: _resource_record_post_action 13:26:27 INFO - [mozharness: 2016-08-23 20:26:27.406115Z] Finished read-buildbot-config step (success) 13:26:27 INFO - [mozharness: 2016-08-23 20:26:27.406220Z] Running download-and-extract step. 13:26:27 INFO - Running pre-action listener: _resource_record_pre_action 13:26:27 INFO - Running main action method: download_and_extract 13:26:27 INFO - mkdir: /builds/slave/test/build/tests 13:26: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')]} 13:26:27 INFO - trying https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 13:26:27 INFO - Downloading https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:27 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:28 INFO - Downloaded 1199 bytes. 13:26:28 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 13:26:28 INFO - Using the following test package requirements: 13:26:28 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:26:28 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 13:26:28 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 13:26:28 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'jsshell-mac.zip'], 13:26:28 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 13:26:28 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 13:26:28 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 13:26:28 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 13:26:28 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 13:26:28 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 13:26:28 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 13:26:28 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 13:26:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:26:28 INFO - trying https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:26:28 INFO - Downloading https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:30 INFO - Downloaded 16346235 bytes. 13:26:30 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:26:37 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 13:26: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')]} 13:26:37 INFO - trying https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 13:26:37 INFO - Downloading https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:38 INFO - Downloaded 16346235 bytes. 13:26:38 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 13:26:44 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')]} 13:26:44 INFO - trying https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 13:26:44 INFO - Downloading https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:47 INFO - Downloaded 14413981 bytes. 13:26:47 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 13:26:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:26:47 INFO - trying https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:26:47 INFO - Downloading https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 13:26:47 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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 13:26:50 INFO - Downloaded 111489336 bytes. 13:26:50 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:26:50 INFO - mkdir: /builds/slave/test/properties 13:26:50 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:26:50 INFO - Writing to file /builds/slave/test/properties/build_url 13:26:50 INFO - Contents: 13:26:50 INFO - build_url:https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 13:26:51 INFO - Running post-action listener: _resource_record_post_action 13:26:51 INFO - Running post-action listener: set_extra_try_arguments 13:26:51 INFO - [mozharness: 2016-08-23 20:26:51.688219Z] Finished download-and-extract step (success) 13:26:51 INFO - [mozharness: 2016-08-23 20:26:51.688341Z] Running populate-webroot step. 13:26:51 INFO - Running pre-action listener: _resource_record_pre_action 13:26:51 INFO - Running main action method: populate_webroot 13:26:51 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:26:51 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:26:51 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:26:51 INFO - '--tppagecycles', 13:26:51 INFO - '7'], 13:26:51 INFO - 'tests': ['tsvgm']}}, 13:26:51 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:26:51 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:26:51 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:26:51 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:26:51 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:51 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:26:51 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['damp', 'tps']}, 13:26:51 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'tests': ['damp', 'tps']}, 13:26:51 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['dromaeo_dom']}, 13:26:51 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:26:51 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['basic_compositor_video']}, 13:26:51 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:26:51 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'other-e10s': {'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['a11yr', 13:26:51 INFO - 'ts_paint', 13:26:51 INFO - 'tpaint', 13:26:51 INFO - 'sessionrestore', 13:26:51 INFO - 'sessionrestore_no_auto_restore', 13:26:51 INFO - 'tabpaint']}, 13:26:51 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['tsvgx', 13:26:51 INFO - 'tsvgr_opacity', 13:26:51 INFO - 'tart', 13:26:51 INFO - 'tscrollx', 13:26:51 INFO - 'cart']}, 13:26:51 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:26:51 INFO - 'tsvgr_opacity', 13:26:51 INFO - 'tart', 13:26:51 INFO - 'tscrollx', 13:26:51 INFO - 'cart']}, 13:26:51 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'talos_options': ['--disable-e10s'], 13:26:51 INFO - 'tests': ['tp5o']}, 13:26:51 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'tests': ['tp5o']}, 13:26:51 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'talos_options': ['--disable-e10s', 13:26:51 INFO - '--xperf_path', 13:26:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:51 INFO - 'tests': ['tp5n']}, 13:26:51 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 13:26:51 INFO - 'talos_options': ['--xperf_path', 13:26:51 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:26:51 INFO - 'tests': ['tp5n']}}, 13:26:51 INFO - 'talos.zip': {'path': '', 13:26:51 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:26:51 INFO - Running post-action listener: _resource_record_post_action 13:26:51 INFO - [mozharness: 2016-08-23 20:26:51.707327Z] Finished populate-webroot step (success) 13:26:51 INFO - [mozharness: 2016-08-23 20:26:51.707460Z] Running create-virtualenv step. 13:26:51 INFO - Running pre-action listener: _resource_record_pre_action 13:26:51 INFO - Running main action method: create_virtualenv 13:26:51 INFO - Creating virtualenv /builds/slave/test/build/venv 13:26:51 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 13:26:51 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:26:51 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:26:51 INFO - Using real prefix '/tools/python27' 13:26:51 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:26:52 INFO - Installing distribute.............................................................................................................................................................................................done. 13:26:55 INFO - Installing pip.................done. 13:26:55 INFO - Return code: 0 13:26:55 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:26:55 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')]} 13:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:55 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')]} 13:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:55 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:26:55 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 13:26:55 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 13:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:55 INFO - 'HOME': '/Users/cltbld', 13:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:55 INFO - 'LOGNAME': 'cltbld', 13:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:55 INFO - 'MOZ_NO_REMOTE': '1', 13:26:55 INFO - 'NO_EM_RESTART': '1', 13:26:55 INFO - 'PAGER': '/bin/cat', 13:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:55 INFO - 'PWD': '/builds/slave/test', 13:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:55 INFO - 'SHELL': '/bin/bash', 13:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:26:55 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:55 INFO - 'USER': 'cltbld', 13:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:55 INFO - 'XPC_FLAGS': '0x0', 13:26:55 INFO - 'XPC_SERVICE_NAME': '0', 13:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:55 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 13:26:55 INFO - Cleaning up... 13:26:55 INFO - Return code: 0 13:26:55 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:26:55 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')]} 13:26:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:55 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')]} 13:26:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:26:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:26:55 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:26:55 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 13:26:55 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 13:26:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:26:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:26:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:26:55 INFO - 'HOME': '/Users/cltbld', 13:26:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:26:55 INFO - 'LOGNAME': 'cltbld', 13:26:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:26:55 INFO - 'MOZ_NO_REMOTE': '1', 13:26:55 INFO - 'NO_EM_RESTART': '1', 13:26:55 INFO - 'PAGER': '/bin/cat', 13:26:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:26:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:26:55 INFO - 'PWD': '/builds/slave/test', 13:26:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:26:55 INFO - 'SHELL': '/bin/bash', 13:26:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:26:55 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:26:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:26:55 INFO - 'USER': 'cltbld', 13:26:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:26:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:26:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:26:55 INFO - 'XPC_FLAGS': '0x0', 13:26:55 INFO - 'XPC_SERVICE_NAME': '0', 13:26:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:26:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:26:55 INFO - Downloading/unpacking psutil>=3.1.1 13:26:55 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 13:26:55 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 13:26:55 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 13:26:55 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 13:26:55 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 13:26:55 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 13:26:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:26:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:26:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:26:58 INFO - Installing collected packages: psutil 13:26:58 INFO - Running setup.py install for psutil 13:26:58 INFO - building 'psutil._psutil_osx' extension 13:26:58 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 13:26:59 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 13:26:59 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 13:26:59 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 13:26:59 INFO - building 'psutil._psutil_posix' extension 13:26:59 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 13:26:59 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 13:26:59 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 13:26:59 INFO - ^ 13:26:59 INFO - 1 warning generated. 13:26:59 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 13:27:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:27:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:27:00 INFO - Successfully installed psutil 13:27:00 INFO - Cleaning up... 13:27:00 INFO - Return code: 0 13:27:00 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:00 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:00 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 13:27:00 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 13:27:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:00 INFO - 'HOME': '/Users/cltbld', 13:27:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:00 INFO - 'LOGNAME': 'cltbld', 13:27:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:00 INFO - 'MOZ_NO_REMOTE': '1', 13:27:00 INFO - 'NO_EM_RESTART': '1', 13:27:00 INFO - 'PAGER': '/bin/cat', 13:27:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:00 INFO - 'PWD': '/builds/slave/test', 13:27:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:00 INFO - 'SHELL': '/bin/bash', 13:27:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:00 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:00 INFO - 'USER': 'cltbld', 13:27:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:00 INFO - 'XPC_FLAGS': '0x0', 13:27:00 INFO - 'XPC_SERVICE_NAME': '0', 13:27:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:00 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:27:00 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 13:27:00 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 13:27:00 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 13:27:00 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 13:27:00 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 13:27:00 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 13:27:02 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:27:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:27:02 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:27:02 INFO - Installing collected packages: mozsystemmonitor 13:27:02 INFO - Running setup.py install for mozsystemmonitor 13:27:03 INFO - Successfully installed mozsystemmonitor 13:27:03 INFO - Cleaning up... 13:27:03 INFO - Return code: 0 13:27:03 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:27:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:03 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 13:27:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 13:27:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:03 INFO - 'HOME': '/Users/cltbld', 13:27:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:03 INFO - 'LOGNAME': 'cltbld', 13:27:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:03 INFO - 'MOZ_NO_REMOTE': '1', 13:27:03 INFO - 'NO_EM_RESTART': '1', 13:27:03 INFO - 'PAGER': '/bin/cat', 13:27:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:03 INFO - 'PWD': '/builds/slave/test', 13:27:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:03 INFO - 'SHELL': '/bin/bash', 13:27:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:03 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:03 INFO - 'USER': 'cltbld', 13:27:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:03 INFO - 'XPC_FLAGS': '0x0', 13:27:03 INFO - 'XPC_SERVICE_NAME': '0', 13:27:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:03 INFO - Downloading/unpacking jsonschema==2.5.1 13:27:03 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 13:27:03 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 13:27:03 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 13:27:03 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 13:27:03 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 13:27:03 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 13:27:05 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:27:05 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 13:27:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 13:27:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 13:27:06 INFO - Downloading functools32-3.2.3-2.tar.gz 13:27:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:27:06 INFO - warning: no files found matching '*.txt' 13:27:06 INFO - no previously-included directories found matching 'build' 13:27:06 INFO - no previously-included directories found matching 'dist' 13:27:06 INFO - no previously-included directories found matching '.git*' 13:27:06 INFO - Installing collected packages: jsonschema, functools32 13:27:06 INFO - Running setup.py install for functools32 13:27:06 INFO - warning: no files found matching '*.txt' 13:27:06 INFO - no previously-included directories found matching 'build' 13:27:06 INFO - no previously-included directories found matching 'dist' 13:27:06 INFO - no previously-included directories found matching '.git*' 13:27:06 INFO - Successfully installed jsonschema functools32 13:27:06 INFO - Cleaning up... 13:27:06 INFO - Return code: 0 13:27:06 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:27:06 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')]} 13:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:06 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')]} 13:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:06 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:06 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 13:27:06 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 13:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:06 INFO - 'HOME': '/Users/cltbld', 13:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:06 INFO - 'LOGNAME': 'cltbld', 13:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:06 INFO - 'MOZ_NO_REMOTE': '1', 13:27:06 INFO - 'NO_EM_RESTART': '1', 13:27:06 INFO - 'PAGER': '/bin/cat', 13:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:06 INFO - 'PWD': '/builds/slave/test', 13:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:06 INFO - 'SHELL': '/bin/bash', 13:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:06 INFO - 'USER': 'cltbld', 13:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:06 INFO - 'XPC_FLAGS': '0x0', 13:27:06 INFO - 'XPC_SERVICE_NAME': '0', 13:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:06 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:27:06 INFO - Cleaning up... 13:27:06 INFO - Return code: 0 13:27:06 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:27:06 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')]} 13:27:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:06 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')]} 13:27:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:06 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:06 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 13:27:06 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 13:27:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:06 INFO - 'HOME': '/Users/cltbld', 13:27:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:06 INFO - 'LOGNAME': 'cltbld', 13:27:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:06 INFO - 'MOZ_NO_REMOTE': '1', 13:27:06 INFO - 'NO_EM_RESTART': '1', 13:27:06 INFO - 'PAGER': '/bin/cat', 13:27:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:06 INFO - 'PWD': '/builds/slave/test', 13:27:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:06 INFO - 'SHELL': '/bin/bash', 13:27:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:06 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:06 INFO - 'USER': 'cltbld', 13:27:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:06 INFO - 'XPC_FLAGS': '0x0', 13:27:06 INFO - 'XPC_SERVICE_NAME': '0', 13:27:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:07 INFO - Downloading/unpacking blobuploader==1.2.4 13:27:07 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 13:27:07 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 13:27:07 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 13:27:07 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 13:27:07 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 13:27:07 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 13:27:09 INFO - Downloading blobuploader-1.2.4.tar.gz 13:27:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:27:09 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:27:09 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 13:27:09 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 13:27:09 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 13:27:09 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 13:27:09 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 13:27:09 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 13:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:27:10 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:27:10 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 13:27:10 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 13:27:10 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 13:27:10 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 13:27:10 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 13:27:10 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 13:27:10 INFO - Downloading docopt-0.6.1.tar.gz 13:27:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:27:10 INFO - Installing collected packages: blobuploader, requests, docopt 13:27:10 INFO - Running setup.py install for blobuploader 13:27:11 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:27:11 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:27:11 INFO - Running setup.py install for requests 13:27:11 INFO - Running setup.py install for docopt 13:27:11 INFO - Successfully installed blobuploader requests docopt 13:27:11 INFO - Cleaning up... 13:27:11 INFO - Return code: 0 13:27:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27: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')]} 13:27:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27: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')]} 13:27:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:12 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:12 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 13:27:12 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 13:27:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:12 INFO - 'HOME': '/Users/cltbld', 13:27:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:12 INFO - 'LOGNAME': 'cltbld', 13:27:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:12 INFO - 'MOZ_NO_REMOTE': '1', 13:27:12 INFO - 'NO_EM_RESTART': '1', 13:27:12 INFO - 'PAGER': '/bin/cat', 13:27:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:12 INFO - 'PWD': '/builds/slave/test', 13:27:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:12 INFO - 'SHELL': '/bin/bash', 13:27:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:12 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:12 INFO - 'USER': 'cltbld', 13:27:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:12 INFO - 'XPC_FLAGS': '0x0', 13:27:12 INFO - 'XPC_SERVICE_NAME': '0', 13:27:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-hsiggM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-pAuSNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-y8ZW6C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-396Ynn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-rj_gdk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:12 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-xy50m4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-yxnDrf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-hmpMsz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-gskZKt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-9Iviuh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-N5rH0h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-78QHaU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-OK2SB7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-nBnQVJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-qPzykb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:13 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-TVLfKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:14 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-JlL2HL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:14 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:27:14 INFO - Running setup.py install for manifestparser 13:27:14 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:27:14 INFO - Running setup.py install for mozcrash 13:27:14 INFO - Running setup.py install for mozdebug 13:27:14 INFO - Running setup.py install for mozdevice 13:27:14 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:27:14 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:27:14 INFO - Running setup.py install for mozfile 13:27:14 INFO - Running setup.py install for mozhttpd 13:27:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for mozinfo 13:27:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for mozInstall 13:27:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for mozleak 13:27:15 INFO - Running setup.py install for mozlog 13:27:15 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for moznetwork 13:27:15 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:27:15 INFO - Running setup.py install for mozprocess 13:27:15 INFO - Running setup.py install for mozprofile 13:27:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozrunner 13:27:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Running setup.py install for mozscreenshot 13:27:16 INFO - Running setup.py install for moztest 13:27:16 INFO - Running setup.py install for mozversion 13:27:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:27:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:27:16 INFO - Cleaning up... 13:27:16 INFO - Return code: 0 13:27:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:16 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')]} 13:27:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:16 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')]} 13:27:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:16 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:16 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 13:27:16 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 13:27:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:16 INFO - 'HOME': '/Users/cltbld', 13:27:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:16 INFO - 'LOGNAME': 'cltbld', 13:27:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:16 INFO - 'MOZ_NO_REMOTE': '1', 13:27:16 INFO - 'NO_EM_RESTART': '1', 13:27:16 INFO - 'PAGER': '/bin/cat', 13:27:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:16 INFO - 'PWD': '/builds/slave/test', 13:27:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:16 INFO - 'SHELL': '/bin/bash', 13:27:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:16 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:16 INFO - 'USER': 'cltbld', 13:27:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:16 INFO - 'XPC_FLAGS': '0x0', 13:27:16 INFO - 'XPC_SERVICE_NAME': '0', 13:27:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-CXF2QP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-u1a8G0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-WUb_7t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-HbhT5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-945D75-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Qd_juE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Q_jlnG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-3PdNSE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-pBN_xu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:27:17 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)) 13:27:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:27:17 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-UrXkGb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-Tk0VMa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-GNwaCL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-DXfmiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-DIFbQP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-flnXgj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-4IHxHy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:27:18 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)) 13:27:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:27:18 INFO - Running setup.py (path:/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/pip-43c4bC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:27:18 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)) 13:27:18 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)) 13:27:18 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)) 13:27:18 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)) 13:27:18 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)) 13:27:18 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)) 13:27:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:27:18 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 13:27:18 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 13:27:18 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 13:27:18 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 13:27:18 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 13:27:18 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 13:27:21 INFO - Downloading blessings-1.6.tar.gz 13:27:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:27:21 INFO - Installing collected packages: blessings 13:27:21 INFO - Running setup.py install for blessings 13:27:21 INFO - Successfully installed blessings 13:27:21 INFO - Cleaning up... 13:27:21 INFO - Return code: 0 13:27:21 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:27:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:22 INFO - Reading from file tmpfile_stdout 13:27:22 INFO - Current package versions: 13:27:22 INFO - blessings == 1.6 13:27:22 INFO - blobuploader == 1.2.4 13:27:22 INFO - docopt == 0.6.1 13:27:22 INFO - functools32 == 3.2.3-2 13:27:22 INFO - jsonschema == 2.5.1 13:27:22 INFO - manifestparser == 1.1 13:27:22 INFO - mozInstall == 1.12 13:27:22 INFO - mozcrash == 0.17 13:27:22 INFO - mozdebug == 0.1 13:27:22 INFO - mozdevice == 0.48 13:27:22 INFO - mozfile == 1.2 13:27:22 INFO - mozhttpd == 0.7 13:27:22 INFO - mozinfo == 0.9 13:27:22 INFO - mozleak == 0.1 13:27:22 INFO - mozlog == 3.3 13:27:22 INFO - moznetwork == 0.27 13:27:22 INFO - mozprocess == 0.23 13:27:22 INFO - mozprofile == 0.28 13:27:22 INFO - mozrunner == 6.12 13:27:22 INFO - mozscreenshot == 0.1 13:27:22 INFO - mozsystemmonitor == 0.3 13:27:22 INFO - moztest == 0.7 13:27:22 INFO - mozversion == 1.4 13:27:22 INFO - psutil == 3.1.1 13:27:22 INFO - requests == 1.2.3 13:27:22 INFO - wsgiref == 0.1.2 13:27:22 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:27:22 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')]} 13:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:22 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')]} 13:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:22 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:22 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 13:27:22 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 13:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:22 INFO - 'HOME': '/Users/cltbld', 13:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:22 INFO - 'LOGNAME': 'cltbld', 13:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:22 INFO - 'MOZ_NO_REMOTE': '1', 13:27:22 INFO - 'NO_EM_RESTART': '1', 13:27:22 INFO - 'PAGER': '/bin/cat', 13:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:22 INFO - 'PWD': '/builds/slave/test', 13:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:22 INFO - 'SHELL': '/bin/bash', 13:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:22 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:22 INFO - 'USER': 'cltbld', 13:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:22 INFO - 'XPC_FLAGS': '0x0', 13:27:22 INFO - 'XPC_SERVICE_NAME': '0', 13:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 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)) 13:27:22 INFO - Cleaning up... 13:27:22 INFO - Return code: 0 13:27:22 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:27:22 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')]} 13:27:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:27:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:22 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')]} 13:27:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:27:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:27:22 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 0x102d5da30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102df2618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa29d8c0e0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, '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.hY9xTM4KC8/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.Z10ai29k98/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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 13:27:22 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 13:27:22 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 13:27:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:22 INFO - 'HOME': '/Users/cltbld', 13:27:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:22 INFO - 'LOGNAME': 'cltbld', 13:27:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:22 INFO - 'MOZ_NO_REMOTE': '1', 13:27:22 INFO - 'NO_EM_RESTART': '1', 13:27:22 INFO - 'PAGER': '/bin/cat', 13:27:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:22 INFO - 'PWD': '/builds/slave/test', 13:27:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:22 INFO - 'SHELL': '/bin/bash', 13:27:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:22 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:22 INFO - 'USER': 'cltbld', 13:27:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:22 INFO - 'XPC_FLAGS': '0x0', 13:27:22 INFO - 'XPC_SERVICE_NAME': '0', 13:27:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:27:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:27:22 INFO - Cleaning up... 13:27:22 INFO - Return code: 0 13:27:22 INFO - Running post-action listener: _resource_record_post_action 13:27:22 INFO - Running post-action listener: _start_resource_monitoring 13:27:22 INFO - Starting resource monitoring. 13:27:22 INFO - [mozharness: 2016-08-23 20:27:22.781120Z] Finished create-virtualenv step (success) 13:27:22 INFO - [mozharness: 2016-08-23 20:27:22.782125Z] Running install step. 13:27:22 INFO - Running pre-action listener: _resource_record_pre_action 13:27:22 INFO - Running main action method: install 13:27:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:27:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:27:23 INFO - Reading from file tmpfile_stdout 13:27:23 INFO - Detecting whether we're running mozinstall >=1.0... 13:27:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:27:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:27:23 INFO - Reading from file tmpfile_stdout 13:27:23 INFO - Output received: 13:27:23 INFO - Usage: mozinstall [options] installer 13:27:23 INFO - Options: 13:27:23 INFO - -h, --help show this help message and exit 13:27:23 INFO - -d DEST, --destination=DEST 13:27:23 INFO - Directory to install application into. [default: 13:27:23 INFO - "/builds/slave/test"] 13:27:23 INFO - --app=APP Application being installed. [default: firefox] 13:27:23 INFO - mkdir: /builds/slave/test/build/application 13:27:23 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'] 13:27:23 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 13:27:56 INFO - Reading from file tmpfile_stdout 13:27:56 INFO - Output received: 13:27:56 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 13:27:56 INFO - Running post-action listener: _resource_record_post_action 13:27:56 INFO - [mozharness: 2016-08-23 20:27:56.855537Z] Finished install step (success) 13:27:56 INFO - [mozharness: 2016-08-23 20:27:56.855695Z] Running run-tests step. 13:27:56 INFO - Running pre-action listener: _resource_record_pre_action 13:27:56 INFO - Running main action method: run_tests 13:27:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:27:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:27:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:27:56 INFO - 2016-08-23 13:27:56.870 screenresolution[1638:7946] starting screenresolution argv=screenresolution get 13:27:56 INFO - 2016-08-23 13:27:56.873 screenresolution[1638:7946] Display 0: 1600x1200x32@60 13:27:56 INFO - 2016-08-23 13:27:56.878 screenresolution[1639:7949] starting screenresolution argv=screenresolution list 13:27:56 INFO - Available Modes on Display 0 13:27:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:27:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:27:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:27:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:27:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:27:57 INFO - Intel Iris: 13:27:57 INFO - Chipset Model: Intel Iris 13:27:57 INFO - Type: GPU 13:27:57 INFO - Bus: Built-In 13:27:57 INFO - VRAM (Dynamic, Max): 1536 MB 13:27:57 INFO - Vendor: Intel (0x8086) 13:27:57 INFO - Device ID: 0x0a2e 13:27:57 INFO - Revision ID: 0x0009 13:27:57 INFO - Displays: 13:27:57 INFO - Display: 13:27:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:27:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:27:57 INFO - Display Serial Number: 1600x1200 60 13:27:57 INFO - Main Display: Yes 13:27:57 INFO - Mirror: Off 13:27:57 INFO - Online: Yes 13:27:57 INFO - Rotation: Supported 13:27:57 INFO - Return code: 0 13:27:57 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:27:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:27:57 INFO - Python 2.7.3 13:27:57 INFO - Return code: 0 13:27:57 INFO - grabbing minidump binary from tooltool 13:27:57 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')]} 13:27:57 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 0x102cc1d30>, '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 0x102a26db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102cb9b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:27:57 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 13:27:57 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 13:27:57 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 13:27:57 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:27:57 INFO - Return code: 0 13:27:57 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 13:27:57 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:27:57 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:27:57 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 13:27:57 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:27:57 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/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 13:27:57 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/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0059 --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 13:27:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.hY9xTM4KC8/Render', 13:27:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 13:27:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 13:27:57 INFO - 'HOME': '/Users/cltbld', 13:27:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 13:27:57 INFO - 'LOGNAME': 'cltbld', 13:27:57 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:27:57 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 13:27:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:27:57 INFO - 'MOZ_NO_REMOTE': '1', 13:27:57 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:27:57 INFO - 'NO_EM_RESTART': '1', 13:27:57 INFO - 'PAGER': '/bin/cat', 13:27:57 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 13:27:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:27:57 INFO - 'PWD': '/builds/slave/test', 13:27:57 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:27:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 13:27:57 INFO - 'SHELL': '/bin/bash', 13:27:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Z10ai29k98/Listeners', 13:27:57 INFO - 'TMPDIR': '/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/', 13:27:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 13:27:57 INFO - 'USER': 'cltbld', 13:27:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 13:27:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 13:27:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:27:57 INFO - 'XPC_FLAGS': '0x0', 13:27:57 INFO - 'XPC_SERVICE_NAME': '0', 13:27:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 13:27:57 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/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0059', '--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 13:27:57 INFO - mozversion application_buildid: 20160823104659 13:27:57 INFO - mozversion application_changeset: 6e17a469a1b137c535adee7e476f86ee9dfcc071 13:27:57 INFO - mozversion application_display_name: Nightly 13:27:57 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:27:57 INFO - mozversion application_name: Firefox 13:27:57 INFO - mozversion application_remotingname: firefox 13:27:57 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 13:27:57 INFO - mozversion application_vendor: Mozilla 13:27:57 INFO - mozversion application_version: 51.0a1 13:27:57 INFO - mozversion platform_buildid: 20160823104659 13:27:57 INFO - mozversion platform_changeset: 6e17a469a1b137c535adee7e476f86ee9dfcc071 13:27:57 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 13:27:57 INFO - mozversion platform_version: 51.0a1 13:27:57 INFO - using testdate: 1471984077 13:27:57 INFO - actual date: 1471984077 13:27:57 INFO - starting webserver on 'localhost:49267' 13:27:57 INFO - SUITE-START | Running 2 tests 13:27:57 INFO - TEST-START | dromaeo_css 13:27:57 INFO - operating with platform_type : mac_ 13:27:57 INFO - Initialising browser for dromaeo_css test... 13:27:57 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpzAxCVB/profile http://localhost:49267/getInfo.html) 13:28:00 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 13:28:00 INFO - PROCESS | 1649 | colorDepth:24 13:28:00 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 13:28:00 INFO - PROCESS | 1649 | __metrics 13:28:00 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" 13:28:00 INFO - PROCESS | 1649 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:28:00 INFO - TEST-INFO | 1649: exit 0 13:28:00 INFO - Browser initialized. 13:28:00 INFO - Running cycle 1/1 for dromaeo_css test... 13:28:00 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmpzAxCVB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:28:13 INFO - PROCESS | 1651 | RSS: Main: 234881024 13:28:13 INFO - PROCESS | 1651 | 13:29:04 INFO - PROCESS | 1651 | 1471984144311 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] 13:31:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 13:31:17 INFO - PROCESS | 1651 | RSS: Main: 389746688 13:31:17 INFO - PROCESS | 1651 | 13:34:20 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 13:34:21 INFO - PROCESS | 1651 | RSS: Main: 381181952 13:34:21 INFO - PROCESS | 1651 | 13:37:24 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 13:37:24 INFO - PROCESS | 1651 | RSS: Main: 340000768 13:37:24 INFO - PROCESS | 1651 | 13:40:27 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 13:40:27 INFO - PROCESS | 1651 | RSS: Main: 338411520 13:40:27 INFO - PROCESS | 1651 | 13:43:31 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 13:43:31 INFO - PROCESS | 1651 | RSS: Main: 339378176 13:43:31 INFO - PROCESS | 1651 | 13:46:34 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 13:46:35 INFO - PROCESS | 1651 | RSS: Main: 495681536 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | __start_tp_report 13:46:35 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 13:46:35 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 13:46:35 INFO - PROCESS | 1651 | |i|pagename|runs| 13:46:35 INFO - PROCESS | 1651 | |0;dojo.html;3341.997400779767;3342.097633638917;3378.1385746634605;3388.79667219967;3445.5865296164447;5614.859628509289;5658.971705141479;5738.913916291249;5785.884282314351;5792.420757924211;5688.715564221793;5773.971130144343;5813.360530341659;5844.152597873306;5891.793787217448;4335.978320108395;5184.922226166611;5227.08526007949;5268.367795864482;5362.329708786402;5377.11277639189;5381.461853814607;5414.242006119146;5438.265834112403;5459;5182.196759502283;5280.867978300535;5284.894302113936;5321.893562128756;5363;5204.505571970657;5279.70961597112;5286.62993590445;5290.55030322423;5297.073012222845;5177.352830896138;5204.583633309364;5225.869353266199;5255.76349064293;5265.578753699695;5763.4812866842185;5771;5774.769009239625;5776.740046697909;5777.422257774189;5092.261622064817;6120.41665836802;6152.630842149485;6177.66022868742;6193.504519638418;6092.634441933498;6204.255489341263;6206.6586337751405;6218.129461875341;6234.875302493925;5852.063669812852;5867.970660146757;5885.440883116183;5885.941140588539;5886;6144.77104579086;6145.784897528565;6169.660668663177;6170;6289.088082228142;6123.84690382744;6145.723442445101;6153.230846144232;6158.3841615838055;6170.753169873245;6192;6197.03945888388;6214.8135555933395;6220.657863817533;6222.62664240138;5211.765470553834;6041.914978148572;6111.8904774697;6133.645111970462;6158.137860699506;6244.656543890039;6259.906101408483;6261.186045814015;6268.310485846553;6277.686115694197;4986.476419975923;4988.700677959334;5016.749162541932;5024.522670346311;5040.193569029011;4314.115606300701;4406.515283318832;4442.955570444319;4491.371208030813;4493.0339976905125;5615.2700148980375;5702.059160238514;5707.514861236758;5730.627509211888;5773.740181691834;6198.597091189058;6224.751009959551;6237.625742455467;6239.968800156061;6248.843778905472;5679.716014199274;5758.765722486914;5759.395263497272;5770.6249093808765;5827.941720582825;5336.466353364633;5410.458954104637;5435.864103397446;5447.1829225616475;5450.727463626803;6426.3573642635365;6453.870922581616;6459.192600924885;6478.384553467413;6481.643509607017;6114;6190.319064902767;6198.690065496617;6214.223222097238;6214.8135555933395;6138.357744607992;6263.4362907338555;6271.31015588294;6416.294207637249;6433;6214.285357183981;6215.254169499508;6223.471004964629;6227.595206311576;6237.157983672146;6221.09794079865;6221.222347206599;6230.00319948806;6232.376762323913;6232.968835155795;5008.824691135793;5814.418558144152;6047.002244629587;6180.134781130735;6181.381861813783;6099.067674246629;6106.187656479639;6109.175261339662;6134.130457564626;6142.78500252489;5243.370795504411;5903.586748927534;5963.194968679346;5997.640141591344;6024.036154215304;6013.789517366871;6099.176611157437;6117.938820611737;6135.631862088289;6139.96930015365;6002.549808764447;6029.60807547508;6036.577439579188;6058.485028772604;6071.180390647206 13:46:35 INFO - PROCESS | 1651 | |1;ext.html;477.66563405616074;501.78674063523016;519.6102922807894;530.9123994540901;536.4876542901529;26897.865510672444;40814.795926020364;41162.79418602903;41284.38073428897;41405.17189656205;43262;43371.3494297585;43620.78189609056;43652.781736091274;43695.78152109235;8122.309603683694;9282.118198771106;9899.109080182781;9904.95047524765;9927.453990030546;8967.417117887317;9792.608295668164;9794.755131121743;9801.16690081344;9805.166560842337;8372.581370931459;8408.537530435824;9807.65673201438;9833.11501964823;9854.556544955458;9733.951330243373;9792.461414622228;9794;9803.068708472754;9814.754631134207;9642.373245739005;9744.707658770249;9748.025197480267;9749.902500974971;9758.072983066597;34550.7907223246;39081.804590977146;40778.59221407778;40793.1841363174;40804;3635.0971465532148;3650.580183278904;3660.304542137012;3743.2139250757373;4218.186179942623;30816.38367232643;32043.679563204536;32082.51876221835;32360.676393236;32422.513662295085;3187.10760986924;3314.950275745866;3320.1367644412385;3375.51055097012;3388.6272510023878;358700.20649896585;360906;361431.19284403673;362789;363013;59066.40933590609;59081.704591476766;59106;59129;59313;53588.73205633947;61447.6927615368;61652.458688533145;61776.07335889965;61853.762924741255;15281.770773438408;15590.532284031271;15720.528384148494;15821.841781582267;15848.366065357257;12280.89471947511;12341.568045118558;12370.938145309396;12375.071869609816;12376.690582735504;15859.762103568455;15896.284667190004;15908.92045539788;15910.920445397698;15976.360945562279;5810.593258471951;6247.562670613013;6272.905906411407;6311.538499985009;6355.3009168991375;831.8960129983752;891.4963045879058;971.3589031239349;1019.169376957777;1084.8175488717336;27553.586696199574;29235;29330;29995.550066749016;30161.396772064436;931.9906800931903;1039.9480025998669;1109.20691705431;1145.307089211032;1162.8488296521568;15199;15211.08733475995;15330.846691533165;15413.229338533029;15461.648290632224;561244;562024.1898790562;562640;564795;565643;56347.43652563422;56525.43474565284;56548.717256414275;56625;56701;1249.375312343828;1307.1372893890177;1376.1124074971387;1401.5234820161195;1431.2986636548017;29689;31182.844085779427;31197.376052479278;31785.682143178274;32028.51957220597;56071;57248;57339;60601;60605.39394606174;618358;627161;627446;627781;629648.703512959;23725.881370593037;26370.73629263683;26468.470630587664;26997.515136667673;26999.46001079928;46264.53735462602;47679.76160119316;47937.760311198224;48154.16531280434;48329;38656;38966.80516597399;39029.80485097556;40618.78143655695;40730.38904416496;38600;39014.41478377883;39191.02022449461;39983.800081000576;40465.39301910532;38315.425268620435;39188;39861.80069099636;39935.60064399319;41337.379939300336;30858.919937802068;34785.30429391449;35288.227012234456;35411.82294088513;35506.934791956286;25602;25616.615750764126;25706;25966.35084122912;26420 13:46:35 INFO - PROCESS | 1651 | |2;jquery.html;356.6184182924269;370.84980582863943;372.00859708875845;389.1594156621697;406.22816648368115;15771.211439428025;15930.522084337474;20529.794702053012;20967.895160524215;21036.003159399705;20179.495512612193;20486.487837804063;20552.691709624367;20724.63768115942;21082.789172108274;5692.402297758737;5820.708964551776;6020.277566692003;6089.81730548082;6098.115773212881;6050.304215015263;6050.42520960508;6105.297890742577;6105.419985101408;6111.266648002246;3881.5536213335404;5332.280142180794;6671.432928201109;6676.132102826626;6756;5832.941670583282;5946.048632218824;6595.802125936206;6710.563813352116;6715.597064176165;5882.264716910387;5924.081767326072;5940.61386009908;5943.791967281124;5948.821535353946;13334.266615336153;13396.928245740415;13428.261445620486;13490.460381584724;13719.1082579632;1770.530809335527;2178.836587255962;2183.224955140918;2183.530540933691;2210.8793067632055;9968.252381071448;9987.550560224807;10038.196944244442;10202.489875506195;10374.118199953013;1844.6771814932333;1882.4540883143873;1955.7261983322346;1971.5169783403014;1973.9013049347475;115203.42398288038;116845.99999999916;122205;128997.35501322434;133512.33243833817;10823.621173258898;11177.66467005991;11283.54865805375;11329.830052549218;11600.825987610193;14056.648583785487;14086.71826563463;14236.359363828653;14270.215138167292;14276.14343139415;8083;8361.832763344699;8368.456050356708;8458.87311690325;8473.152684731478;7216.5670059796585;8247.876281855777;8252.422330436812;8405.117462666454;8444.831103377897;4132.746446044163;4427.959429534057;4442.311441726538;4506.143832671782;4514.7291162530355;1885.70771530413;1925.768907731053;1927.5662975830605;1952.5899561092046;1970.1922211893054;713.614836827691;838.2455789789238;892.1665976418393;909.3907082254905;928.7353104365224;20144.59710805776;21204.25785097514;21229.575408491743;21264.043118059726;21284.574308513744;796.4464697035505;871.7079778274223;973.4937832327291;1014.2646581228697;1032.9586816527255;10639.840402393971;11034.779304414027;11093.89061093884;11169.72075698114;11203.383813890152;593546;611321;615173;627798;633447;88748.5562572183;119983.99999999825;123723.52552949071;128522.2579164759;143669;931.701855406277;954.0888451528634;963.4989805301344;967.8548217767391;969.3650658818485;15820.892537522263;18637.534061648566;19221.61556768885;19705;19781.802181977997;54580.727096364266;54791.45208548023;55148.448515514334;55959;56270.71864640651;79455.60272198603;79983.20016799758;81328.06544668239;82304.58847705723;82592.17407825845;6735.865282694416;6928.515003949877;6941.167059952838;7280.453965952469;7295.306945840137;9264.95367523158;12105.69735756613;12381.947534459672;12833.528051902782;13095.345232738135;10863.619773307897;11281.323120612462;11303.321800691985;11340.773184536429;11377.886221137682;10578.365298082139;10874.836877447004;11027.935910270407;11199.328040317281;11304.83042754343;8194.713185038496;11168.441577921234;11423.143264255048;11448.713782155513;11567.942160289482;7146.32109949554;7307.159676637255;7396.55620662762;7485.962570187115;7586.3930885527925;10575.62985295511;11031.172662050218;11378.715532111764;11408.486618102039;11469.827952580885 13:46:35 INFO - PROCESS | 1651 | |3;mootools.html;1517.1049081042186;1539.438105091641;1730.281932997806;1866.3561071430352;1888.9338873139443;8421.284190843779;8978.147076027783;8984.236339911115;9001.639934402632;9004.864927026092;7043.401310888581;7923.48497347672;8140.308073813718;8676.913230867689;8773.517456539888;2375.1330764271047;2404.1585445094165;2407.554602398553;2411.7347091819884;2413.420779013033;2358.3278765551822;2394.2577800881695;2396.5446565152565;2397.280815755275;2406.855588664686;2376.239603326936;2388.52229554089;2388.5461762265113;2398.1846172301416;2404.8196385271085;2367.9644805327935;2386.5823480890863;2395.197608801054;2397.47255603766;2399.256230568529;2347.3310106619533;2366.3610825077135;2368.4434157972864;2370.7392186859433;2375.1924345722537;7563.697452101909;8674.826503469896;8865.113488651148;8886.777830554287;8899.065598112169;5178.9900969311;5342.759575819097;5343.599230057761;5347.572194224492;5359.5712343012465;3673.687736542397;3686.4101743720876;3753.906152346186;3755.380362240227;3763.34141525232;932.5663566441569;1096.8409580610846;1110.1673744691482;1123.2867129372826;1130.372643183033;11836.230645008047;11926.224795388272;11937.40312984356;11939.701507462296;11956.521739130425;4481.731096134243;4606.286025666027;4771.332013518041;4798.880027999328;4816.084943860656;10012.749681258027;10014.749631259278;10034.64878729241;10043.246756493292;10044.799104017879;5360.276362691065;5364.436734142937;5367.194920761917;5372.247885296062;5385.138377859524;4320.071184695305;4463.933041004388;4790.161721698689;4910.631702622318;4916.459189489154;3838.6353296436796;3926.9607303926596;3996.8401263949117;3999.0802115513593;4014.558398576154;1271.7075072733323;1285.8135570342247;1286.0933042205222;1286.1832736212573;1307.5358247821887;923.8891333040078;927.939683920543;945.7351941456403;946.8816397950256;995.9053889880449;5800.854978625568;5931.673757943355;5991.2510936132985;6076;6106.511479081662;438.06254615123663;442.5419690620193;448.6097095526847;453.85249793817144;456.59819358964563;3972;4014.631100008931;4021.879343619696;4056.8782936511952;4113.4446849675505;10512.684619461428;10834.891651083546;10950.671479855617;10969.122470202525;11002.559897604166;10861.782764344827;10866.891331086747;10879.292845965145;10895.618653347254;10905.781884362268;8221.54781487012;8313.376496762647;8314.833703326021;8315.41792074561;8317;5633.324001119892;5801.288619857068;5972.104184372379;5993.580449368676;6006.429389208019;10056.19550435978;10101.89898101039;10169.186465082628;10174.694759157237;10182.643607474;10106.039926206999;10378.740531486774;10408.843867341844;10413.062824345845;10413.635522756667;5690;6933.930660693329;7174.067371241706;7604.5437273763755;7660.578668173081;8201.917980820115;8532.530710810965;8551;8554.57227138653;8563.700270490503;6409.4231519163495;7539.132999705104;7631.3131818136635;7632.80917977055;7795.064592248967;7383.889241661272;7541.509801862861;7612.505187162817;7617.771466856003;7851.489653172525;6753.966230169014;6943.79168624942;6944.340287672663;6992.860142797217;7008.404285635778;5075.87310317245;5086.940619408736;5247.475252474722;5306.938612277483;5323.281357016753;5155.2782610435315;5190.532852043334;5445.373782015119;5537.1140617501005;5551.528120109836 13:46:35 INFO - PROCESS | 1651 | |4;prototype.html;274.8103808372223;300.05333173836544;312.0653642341189;316.5552398879575;318.42842098433323;4323.178596066745;5179.922301165481;5205.557527610158;5219.973900130499;5248.685078895269;4500;5102.413222479416;5128.5895629054285;5241.344831896013;5248.239005344232;3453.7755045921936;3725.031491812121;3734.6825519830845;3975.423563583279;3980.2039592081555;3937.606239376068;3993.580674029214;3995.720299579031;3996.160806230695;4030.5087117766466;3241.1735007573034;3888.6500214980647;3915.629529664623;3938.251732170893;3940.7438516496336;3650.6349365063547;3703.6296370362747;3761.849526018956;3894.2600905827944;3904.921901561967;3428.9314213715834;3461.5153878457036;3474.617792042873;3481.3385456763135;3489.3544694231396;5039.521245481674;5046.823361182341;5049.848504544832;5060.9493905060845;5087.542121209072;4394.857337092347;4521.525239849801;4528.524504926968;4551.543506078056;4585.656075794329;5353.491418315292;5377.2896701275995;5384.496209262712;5411.21537377082;5417.241586177938;3692.8153592320546;3772.1512659651635;3775.9622403775884;3788.1666033472593;3793.867214647474;5104.800371912597;5219.5811743308195;5557.472040156179;5647.943520564824;5671.659700417947;3189.421236487953;3794.962050379516;3807.3908174691915;3838.808059597009;3849.9615003850163;3904.141088960424;3908.511436070491;3918.8432462701744;3920.941185882214;3939.7045221608496;3697.946085365658;4321.891952701145;4367.606915377632;4475.26158183896;4489.618382437464;4746.359241502353;4915.9508404916205;5396.64921780083;5481.3141560635;5509.228707980886;1297.280009594684;1333.6796571394443;1336.2028072242817;1336.652470357714;1336.926469044212;727.4616783580218;791.9881201781977;800.3957012455675;837.7947402886332;847.3348421489102;3689.7970611616083;3968.543617483969;4197.307444271661;4928.901421971541;5026.933570521978;4911.68074075184;5162.174052151713;5360.785568577213;5624.737652161736;5659.886802264014;4656.7194021643645;4699.859004229878;4742.383490146328;5103.851633382534;5112.565431938327;2744.643196384457;2875.0737006205472;2888.234617826278;2914.621099257083;2947.292649764084;5391.32608423947;5676.68778217202;5722.939741692987;5803.419658034247;5804.738786754522;4941.752912354368;5375.758090885842;5481.614708233477;5581.441855814386;5668.631538949955;5724.227229324071;5783.624064435799;5805.709714514257;5810.6513609183585;5826.271716035496;4616.79224434908;4694.460137084143;4887.43794463627;5479.945200547943;5562.582806289463;5274.287971123849;5436.966976274495;5455.536279416294;5491.917621235764;5507.834764957058;4823.734694591831;5656.925184214986;5778.855528611818;5783.884322313615;5788.681622510802;3094.659587445334;3492.109512074405;3839.5392552892713;3843.2889915365745;3869.59369266223;5041.2438134280155;5175.748549381031;5191.6884986900905;5281;5594.832155035193;4965.354503914468;5364.390682795224;5384.6769193848495;5423.7254245253125;5423.7830486780085;5390.919136213039;5411.567074634098;5428.173137937823;5433.239346491416;5474.479924407175;4466.620337271368;4931.926021109758;5284.9735751320995;5334.1732031535175;5370;5100.194892809014;5349.812756553502;5462;5477.561795056306;5490.917636235539;5166.793328266977;5327.627066105335;5352.1971704243115;5356.3572371315695;5370.677759334452 13:46:35 INFO - PROCESS | 1651 | |5;yui.html;737.8229224986005;775.4223103787677;782.6204290918903;788.4993029426313;839.3285371702636;15458.84541154588;16537.503874883743;16565.25456354464;16599.917000414996;16609.584760380996;14793;14960.551183464513;14968;14970.251487425612;15010.924945375285;679.6601699150425;700.264722041854;833.1585099049958;841.9410641255115;866.6013633728493;833.9833203335929;855.7732200966749;864.1962974433774;872.3849685971406;875.6191056890273;921.9078092190795;925.4817302310694;925.6019911438079;925.6898938855492;927.0034712683859;719.2879049740772;890.3544929925816;916.6058594804232;920.2821798996794;923.6997975657938;688.1501345837886;689.1454596300551;689.7172159414616;690.1614538335896;690.9170899492043;14808.185549794756;14810.185439800807;14810.703785924221;14834.554963351116;14835.629109272246;12197.390130493528;13572.389242484112;13832.446702131903;14038;14485.493007744784;11734.706632334175;11786.194196984006;11945.104117191246;12038.699032524257;12222.816657750052;4541.43232095988;4577.450705915278;4584.312353147055;4632.166210082184;4657;120368.79631203663;120799.3960030203;121265;122011;125492;11017.559297628166;11122.165837562216;11125.499352529156;11214;11576;21982.780172198072;22022;22076.88961555182;22108.336749897528;22135.114595416002;7995.560244186626;8072.798180045428;8169.223923727355;8175.5503447309775;8342.249197572248;3880.2821478026653;4533.38799262102;4565.292379681155;4615.515370886009;4623.953760462352;3339.599248090196;3442.3287458945765;3448.293094918032;3450.9654903450646;3458.7578869478893;583.5623282538096;594.3877805859971;596.5853731656457;597.6682940967718;597.8146774499832;607.7751232044084;609.1259043272918;633.1673848888696;636.462189449914;643.2340933983851;8974.596143173572;9110.362274640844;9125.726228213165;9213.170814626581;9272.860907086399;494.653742380328;527.3934974779035;612.3294991983771;612.4916319454842;629.0400194466663;3567.6253993330847;4335.501417337046;4351.12977404514;4411.897025743564;4507.549245075466;133798.3310083463;161907;163879;166826.16586916987;171687.28312716962;134640.32679836734;134713;139350;142906;143449.5655043457;108972.4551377254;111256;113240;113837;117234.41382793032;8599.570021498776;8638.611262493328;8665.566721664018;8671.34964877624;8719.12808719123;59515.809683805215;66117.66941165263;68000.63998719899;68301;69764.60470790454;95641.52179239059;102039;102366.881596126;106587;107785;11683.240589361665;11735.706607334885;11742.647720568397;11763.941180294043;12172.39138043112;12201.389930503261;12357.93821030889;12395.256284622952;12850.357482126043;12992.8051079232;10300.381977081399;10640.733981650521;11094.334339939629;12113.878861211628;13226.801597975846;13486.527971520949;14028.988981789795;14030.438782448588;14118.647033823825;14287.499937502138;13690.724516757184;13696.86303136956;13740.587782366545;13984.860151398356;14074.437022518985;10819.513121909691;10967.396793176453;11129.220954533104;11342.973244498084;11416.600418985296;9022.86465703028;10995.230333876552;11439.428028598702;11493.770124597628;11520.827187592025 13:46:35 INFO - PROCESS | 1651 | __end_tp_report 13:46:35 INFO - PROCESS | 1651 | __start_cc_report 13:46:35 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1536 13:46:35 INFO - PROCESS | 1651 | __end_cc_report 13:46:35 INFO - PROCESS | 1651 | __startTimestamp1471985195142__endTimestamp 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | ------- Summary: start ------- 13:46:35 INFO - PROCESS | 1651 | Number of tests: 6 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5744.97 Median:5997.64 stddev:635.49 (10.6%) stddev-sans-first:608.84 13:46:35 INFO - PROCESS | 1651 | Values: 3342.0 3342.1 3378.1 3388.8 3445.6 5614.9 5659.0 5738.9 5785.9 5792.4 5688.7 5774.0 5813.4 5844.2 5891.8 4336.0 5184.9 5227.1 5268.4 5362.3 5377.1 5381.5 5414.2 5438.3 5459.0 5182.2 5280.9 5284.9 5321.9 5363.0 5204.5 5279.7 5286.6 5290.6 5297.1 5177.4 5204.6 5225.9 5255.8 5265.6 5763.5 5771.0 5774.8 5776.7 5777.4 5092.3 6120.4 6152.6 6177.7 6193.5 6092.6 6204.3 6206.7 6218.1 6234.9 5852.1 5868.0 5885.4 5885.9 5886.0 6144.8 6145.8 6169.7 6170.0 6289.1 6123.8 6145.7 6153.2 6158.4 6170.8 6192.0 6197.0 6214.8 6220.7 6222.6 5211.8 6041.9 6111.9 6133.6 6158.1 6244.7 6259.9 6261.2 6268.3 6277.7 4986.5 4988.7 5016.7 5024.5 5040.2 4314.1 4406.5 4443.0 4491.4 4493.0 5615.3 5702.1 5707.5 5730.6 5773.7 6198.6 6224.8 6237.6 6240.0 6248.8 5679.7 5758.8 5759.4 5770.6 5827.9 5336.5 5410.5 5435.9 5447.2 5450.7 6426.4 6453.9 6459.2 6478.4 6481.6 6114.0 6190.3 6198.7 6214.2 6214.8 6138.4 6263.4 6271.3 6416.3 6433.0 6214.3 6215.3 6223.5 6227.6 6237.2 6221.1 6221.2 6230.0 6232.4 6233.0 5008.8 5814.4 6047.0 6180.1 6181.4 6099.1 6106.2 6109.2 6134.1 6142.8 5243.4 5903.6 5963.2 5997.6 6024.0 6013.8 6099.2 6117.9 6135.6 6140.0 6002.5 6029.6 6036.6 6058.5 6071.2 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:65981.08 Median:40674.59 stddev:141378.67 (347.6%) stddev-sans-first:141689.73 13:46:35 INFO - PROCESS | 1651 | Values: 477.7 501.8 519.6 530.9 536.5 26897.9 40814.8 41162.8 41284.4 41405.2 43262.0 43371.3 43620.8 43652.8 43695.8 8122.3 9282.1 9899.1 9905.0 9927.5 8967.4 9792.6 9794.8 9801.2 9805.2 8372.6 8408.5 9807.7 9833.1 9854.6 9734.0 9792.5 9794.0 9803.1 9814.8 9642.4 9744.7 9748.0 9749.9 9758.1 34550.8 39081.8 40778.6 40793.2 40804.0 3635.1 3650.6 3660.3 3743.2 4218.2 30816.4 32043.7 32082.5 32360.7 32422.5 3187.1 3315.0 3320.1 3375.5 3388.6 358700.2 360906.0 361431.2 362789.0 363013.0 59066.4 59081.7 59106.0 59129.0 59313.0 53588.7 61447.7 61652.5 61776.1 61853.8 15281.8 15590.5 15720.5 15821.8 15848.4 12280.9 12341.6 12370.9 12375.1 12376.7 15859.8 15896.3 15908.9 15910.9 15976.4 5810.6 6247.6 6272.9 6311.5 6355.3 831.9 891.5 971.4 1019.2 1084.8 27553.6 29235.0 29330.0 29995.6 30161.4 932.0 1039.9 1109.2 1145.3 1162.8 15199.0 15211.1 15330.8 15413.2 15461.6 561244.0 562024.2 562640.0 564795.0 565643.0 56347.4 56525.4 56548.7 56625.0 56701.0 1249.4 1307.1 1376.1 1401.5 1431.3 29689.0 31182.8 31197.4 31785.7 32028.5 56071.0 57248.0 57339.0 60601.0 60605.4 618358.0 627161.0 627446.0 627781.0 629648.7 23725.9 26370.7 26468.5 26997.5 26999.5 46264.5 47679.8 47937.8 48154.2 48329.0 38656.0 38966.8 39029.8 40618.8 40730.4 38600.0 39014.4 39191.0 39983.8 40465.4 38315.4 39188.0 39861.8 39935.6 41337.4 30858.9 34785.3 35288.2 35411.8 35506.9 25602.0 25616.6 25706.0 25966.4 26420.0 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:35190.00 Median:2197.20 stddev:102843.62 (4680.7%) stddev-sans-first:103098.86 13:46:35 INFO - PROCESS | 1651 | Values: 356.6 370.8 372.0 389.2 406.2 15771.2 15930.5 20529.8 20967.9 21036.0 20179.5 20486.5 20552.7 20724.6 21082.8 5692.4 5820.7 6020.3 6089.8 6098.1 6050.3 6050.4 6105.3 6105.4 6111.3 3881.6 5332.3 6671.4 6676.1 6756.0 5832.9 5946.0 6595.8 6710.6 6715.6 5882.3 5924.1 5940.6 5943.8 5948.8 13334.3 13396.9 13428.3 13490.5 13719.1 1770.5 2178.8 2183.2 2183.5 2210.9 9968.3 9987.6 10038.2 10202.5 10374.1 1844.7 1882.5 1955.7 1971.5 1973.9 115203.4 116846.0 122205.0 128997.4 133512.3 10823.6 11177.7 11283.5 11329.8 11600.8 14056.6 14086.7 14236.4 14270.2 14276.1 8083.0 8361.8 8368.5 8458.9 8473.2 7216.6 8247.9 8252.4 8405.1 8444.8 4132.7 4428.0 4442.3 4506.1 4514.7 1885.7 1925.8 1927.6 1952.6 1970.2 713.6 838.2 892.2 909.4 928.7 20144.6 21204.3 21229.6 21264.0 21284.6 796.4 871.7 973.5 1014.3 1033.0 10639.8 11034.8 11093.9 11169.7 11203.4 593546.0 611321.0 615173.0 627798.0 633447.0 88748.6 119984.0 123723.5 128522.3 143669.0 931.7 954.1 963.5 967.9 969.4 15820.9 18637.5 19221.6 19705.0 19781.8 54580.7 54791.5 55148.4 55959.0 56270.7 79455.6 79983.2 81328.1 82304.6 82592.2 6735.9 6928.5 6941.2 7280.5 7295.3 9265.0 12105.7 12381.9 12833.5 13095.3 10863.6 11281.3 11303.3 11340.8 11377.9 10578.4 10874.8 11027.9 11199.3 11304.8 8194.7 11168.4 11423.1 11448.7 11567.9 7146.3 7307.2 7396.6 7486.0 7586.4 10575.6 11031.2 11378.7 11408.5 11469.8 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5752.46 Median:455.23 stddev:3242.65 (712.3%) stddev-sans-first:3236.13 13:46:35 INFO - PROCESS | 1651 | Values: 1517.1 1539.4 1730.3 1866.4 1888.9 8421.3 8978.1 8984.2 9001.6 9004.9 7043.4 7923.5 8140.3 8676.9 8773.5 2375.1 2404.2 2407.6 2411.7 2413.4 2358.3 2394.3 2396.5 2397.3 2406.9 2376.2 2388.5 2388.5 2398.2 2404.8 2368.0 2386.6 2395.2 2397.5 2399.3 2347.3 2366.4 2368.4 2370.7 2375.2 7563.7 8674.8 8865.1 8886.8 8899.1 5179.0 5342.8 5343.6 5347.6 5359.6 3673.7 3686.4 3753.9 3755.4 3763.3 932.6 1096.8 1110.2 1123.3 1130.4 11836.2 11926.2 11937.4 11939.7 11956.5 4481.7 4606.3 4771.3 4798.9 4816.1 10012.7 10014.7 10034.6 10043.2 10044.8 5360.3 5364.4 5367.2 5372.2 5385.1 4320.1 4463.9 4790.2 4910.6 4916.5 3838.6 3927.0 3996.8 3999.1 4014.6 1271.7 1285.8 1286.1 1286.2 1307.5 923.9 927.9 945.7 946.9 995.9 5800.9 5931.7 5991.3 6076.0 6106.5 438.1 442.5 448.6 453.9 456.6 3972.0 4014.6 4021.9 4056.9 4113.4 10512.7 10834.9 10950.7 10969.1 11002.6 10861.8 10866.9 10879.3 10895.6 10905.8 8221.5 8313.4 8314.8 8315.4 8317.0 5633.3 5801.3 5972.1 5993.6 6006.4 10056.2 10101.9 10169.2 10174.7 10182.6 10106.0 10378.7 10408.8 10413.1 10413.6 5690.0 6933.9 7174.1 7604.5 7660.6 8201.9 8532.5 8551.0 8554.6 8563.7 6409.4 7539.1 7631.3 7632.8 7795.1 7383.9 7541.5 7612.5 7617.8 7851.5 6754.0 6943.8 6944.3 6992.9 7008.4 5075.9 5086.9 5247.5 5306.9 5323.3 5155.3 5190.5 5445.4 5537.1 5551.5 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4398.30 Median:5033.23 stddev:1342.92 (26.7%) stddev-sans-first:1310.54 13:46:35 INFO - PROCESS | 1651 | Values: 274.8 300.1 312.1 316.6 318.4 4323.2 5179.9 5205.6 5220.0 5248.7 4500.0 5102.4 5128.6 5241.3 5248.2 3453.8 3725.0 3734.7 3975.4 3980.2 3937.6 3993.6 3995.7 3996.2 4030.5 3241.2 3888.7 3915.6 3938.3 3940.7 3650.6 3703.6 3761.8 3894.3 3904.9 3428.9 3461.5 3474.6 3481.3 3489.4 5039.5 5046.8 5049.8 5060.9 5087.5 4394.9 4521.5 4528.5 4551.5 4585.7 5353.5 5377.3 5384.5 5411.2 5417.2 3692.8 3772.2 3776.0 3788.2 3793.9 5104.8 5219.6 5557.5 5647.9 5671.7 3189.4 3795.0 3807.4 3838.8 3850.0 3904.1 3908.5 3918.8 3920.9 3939.7 3697.9 4321.9 4367.6 4475.3 4489.6 4746.4 4916.0 5396.6 5481.3 5509.2 1297.3 1333.7 1336.2 1336.7 1336.9 727.5 792.0 800.4 837.8 847.3 3689.8 3968.5 4197.3 4928.9 5026.9 4911.7 5162.2 5360.8 5624.7 5659.9 4656.7 4699.9 4742.4 5103.9 5112.6 2744.6 2875.1 2888.2 2914.6 2947.3 5391.3 5676.7 5722.9 5803.4 5804.7 4941.8 5375.8 5481.6 5581.4 5668.6 5724.2 5783.6 5805.7 5810.7 5826.3 4616.8 4694.5 4887.4 5479.9 5562.6 5274.3 5437.0 5455.5 5491.9 5507.8 4823.7 5656.9 5778.9 5783.9 5788.7 3094.7 3492.1 3839.5 3843.3 3869.6 5041.2 5175.7 5191.7 5281.0 5594.8 4965.4 5364.4 5384.7 5423.7 5423.8 5390.9 5411.6 5428.2 5433.2 5474.5 4466.6 4931.9 5285.0 5334.2 5370.0 5100.2 5349.8 5462.0 5477.6 5490.9 5166.8 5327.6 5352.2 5356.4 5370.7 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:26188.64 Median:22092.61 stddev:42929.06 (194.3%) stddev-sans-first:43006.96 13:46:35 INFO - PROCESS | 1651 | Values: 737.8 775.4 782.6 788.5 839.3 15458.8 16537.5 16565.3 16599.9 16609.6 14793.0 14960.6 14968.0 14970.3 15010.9 679.7 700.3 833.2 841.9 866.6 834.0 855.8 864.2 872.4 875.6 921.9 925.5 925.6 925.7 927.0 719.3 890.4 916.6 920.3 923.7 688.2 689.1 689.7 690.2 690.9 14808.2 14810.2 14810.7 14834.6 14835.6 12197.4 13572.4 13832.4 14038.0 14485.5 11734.7 11786.2 11945.1 12038.7 12222.8 4541.4 4577.5 4584.3 4632.2 4657.0 120368.8 120799.4 121265.0 122011.0 125492.0 11017.6 11122.2 11125.5 11214.0 11576.0 21982.8 22022.0 22076.9 22108.3 22135.1 7995.6 8072.8 8169.2 8175.6 8342.2 3880.3 4533.4 4565.3 4615.5 4624.0 3339.6 3442.3 3448.3 3451.0 3458.8 583.6 594.4 596.6 597.7 597.8 607.8 609.1 633.2 636.5 643.2 8974.6 9110.4 9125.7 9213.2 9272.9 494.7 527.4 612.3 612.5 629.0 3567.6 4335.5 4351.1 4411.9 4507.5 133798.3 161907.0 163879.0 166826.2 171687.3 134640.3 134713.0 139350.0 142906.0 143449.6 108972.5 111256.0 113240.0 113837.0 117234.4 8599.6 8638.6 8665.6 8671.3 8719.1 59515.8 66117.7 68000.6 68301.0 69764.6 95641.5 102039.0 102366.9 106587.0 107785.0 11683.2 11735.7 11742.6 11763.9 12172.4 12201.4 12357.9 12395.3 12850.4 12992.8 10300.4 10640.7 11094.3 12113.9 13226.8 13486.5 14029.0 14030.4 14118.6 14287.5 13690.7 13696.9 13740.6 13984.9 14074.4 10819.5 10967.4 11129.2 11343.0 11416.6 9022.9 10995.2 11439.4 11493.8 11520.8 13:46:35 INFO - PROCESS | 1651 | -------- Summary: end -------- 13:46:35 INFO - PROCESS | 1651 | 13:46:35 INFO - TEST-INFO | 1651: exit 0 13:46:35 INFO - TEST-OK | dromaeo_css | took 1118311ms 13:46:35 INFO - TEST-START | kraken 13:46:35 INFO - operating with platform_type : mac_ 13:46:35 INFO - Initialising browser for kraken test... 13:46:35 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmphph5sb/profile http://localhost:49267/getInfo.html) 13:46:38 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 13:46:38 INFO - PROCESS | 1659 | colorDepth:24 13:46:38 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 13:46:38 INFO - PROCESS | 1659 | __metrics 13:46:38 INFO - PROCESS | 1659 | 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" 13:46:38 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:46:38 INFO - TEST-INFO | 1659: exit 0 13:46:38 INFO - Browser initialized. 13:46:38 INFO - Running cycle 1/1 for kraken test... 13:46:38 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/T/tmphph5sb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:46:50 INFO - PROCESS | 1660 | RSS: Main: 234848256 13:46:50 INFO - PROCESS | 1660 | 13:46:53 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 13:46:54 INFO - PROCESS | 1660 | RSS: Main: 293916672 13:46:54 INFO - PROCESS | 1660 | 13:46:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 13:46:57 INFO - PROCESS | 1660 | RSS: Main: 291610624 13:46:57 INFO - PROCESS | 1660 | 13:47:01 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 13:47:01 INFO - PROCESS | 1660 | RSS: Main: 377860096 13:47:01 INFO - PROCESS | 1660 | 13:47:03 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 13:47:04 INFO - PROCESS | 1660 | RSS: Main: 281100288 13:47:04 INFO - PROCESS | 1660 | 13:47:06 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:47:06 INFO - PROCESS | 1660 | RSS: Main: 285749248 13:47:06 INFO - PROCESS | 1660 | 13:47:09 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 13:47:10 INFO - PROCESS | 1660 | RSS: Main: 324591616 13:47:10 INFO - PROCESS | 1660 | 13:47:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 13:47:13 INFO - PROCESS | 1660 | RSS: Main: 326770688 13:47:13 INFO - PROCESS | 1660 | 13:47:16 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 13:47:17 INFO - PROCESS | 1660 | RSS: Main: 323461120 13:47:17 INFO - PROCESS | 1660 | 13:47:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:47:19 INFO - PROCESS | 1660 | RSS: Main: 299925504 13:47:19 INFO - PROCESS | 1660 | 13:47:21 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:47:22 INFO - PROCESS | 1660 | RSS: Main: 305332224 13:47:22 INFO - PROCESS | 1660 | 13:47:24 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:47:24 INFO - PROCESS | 1660 | RSS: Main: 303202304 13:47:24 INFO - PROCESS | 1660 | 13:47:27 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:47:28 INFO - PROCESS | 1660 | RSS: Main: 314798080 13:47:28 INFO - PROCESS | 1660 | 13:47:31 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:47:32 INFO - PROCESS | 1660 | RSS: Main: 317493248 13:47:32 INFO - PROCESS | 1660 | 13:47:33 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 13:47:34 INFO - PROCESS | 1660 | RSS: Main: 361058304 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | __start_tp_report 13:47:34 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 13:47:34 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 13:47:34 INFO - PROCESS | 1660 | |i|pagename|runs| 13:47:34 INFO - PROCESS | 1660 | |0;ai-astar;112;111;113;111;111;110;111;113;111;121 13:47:34 INFO - PROCESS | 1660 | |1;audio-beat-detection;143;106;130;160;106;132;155;134;158;106 13:47:34 INFO - PROCESS | 1660 | |2;audio-dft;185;184;180;182;181;182;183;185;179;187 13:47:34 INFO - PROCESS | 1660 | |3;audio-fft;74;80;73;148;57;56;57;69;140;56 13:47:34 INFO - PROCESS | 1660 | |4;audio-oscillator;82;81;131;70;71;72;119;70;69;117 13:47:34 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;86;84;87;85;87;83;87;84;87;84 13:47:34 INFO - PROCESS | 1660 | |6;imaging-darkroom;101;92;92;94;93;92;95;94;93;93 13:47:34 INFO - PROCESS | 1660 | |7;imaging-desaturate;86;83;85;86;83;87;84;83;106;135 13:47:34 INFO - PROCESS | 1660 | |8;json-parse-financial;54;52;54;54;54;55;52;53;66;69 13:47:34 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;65;65;73;74;74;72;69;71;70;70 13:47:34 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;85;84;85;85;84;81;83;87;85;84 13:47:34 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;137;139;135;137;135;135;135;141;219;156 13:47:34 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;169;166;230;217;168;172;166;167;165;167 13:47:34 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;68;67;70;67;68;66;67;68;68;68 13:47:34 INFO - PROCESS | 1660 | __end_tp_report 13:47:34 INFO - PROCESS | 1660 | __start_cc_report 13:47:34 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,2143 13:47:34 INFO - PROCESS | 1660 | __end_cc_report 13:47:34 INFO - PROCESS | 1660 | __startTimestamp1471985254074__endTimestamp 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | ------- Summary: start ------- 13:47:34 INFO - PROCESS | 1660 | Number of tests: 14 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:112.40 Median:111.50 stddev:3.17 (2.8%) stddev-sans-first:3.36 13:47:34 INFO - PROCESS | 1660 | Values: 112.0 111.0 113.0 111.0 111.0 110.0 111.0 113.0 111.0 121.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:133.00 Median:138.50 stddev:21.44 (15.5%) stddev-sans-first:22.43 13:47:34 INFO - PROCESS | 1660 | Values: 143.0 106.0 130.0 160.0 106.0 132.0 155.0 134.0 158.0 106.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:182.80 Median:183.50 stddev:2.49 (1.4%) stddev-sans-first:2.51 13:47:34 INFO - PROCESS | 1660 | Values: 185.0 184.0 180.0 182.0 181.0 182.0 183.0 185.0 179.0 187.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:81.00 Median:63.00 stddev:34.37 (54.6%) stddev-sans-first:36.36 13:47:34 INFO - PROCESS | 1660 | Values: 74.0 80.0 73.0 148.0 57.0 56.0 57.0 69.0 140.0 56.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:88.20 Median:70.50 stddev:24.24 (34.4%) stddev-sans-first:25.61 13:47:34 INFO - PROCESS | 1660 | Values: 82.0 81.0 131.0 70.0 71.0 72.0 119.0 70.0 69.0 117.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:85.40 Median:86.50 stddev:1.58 (1.8%) stddev-sans-first:1.66 13:47:34 INFO - PROCESS | 1660 | Values: 86.0 84.0 87.0 85.0 87.0 83.0 87.0 84.0 87.0 84.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:93.90 Median:93.00 stddev:2.69 (2.9%) stddev-sans-first:1.05 13:47:34 INFO - PROCESS | 1660 | Values: 101.0 92.0 92.0 94.0 93.0 92.0 95.0 94.0 93.0 93.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:91.80 Median:84.50 stddev:16.66 (19.7%) stddev-sans-first:17.54 13:47:34 INFO - PROCESS | 1660 | Values: 86.0 83.0 85.0 86.0 83.0 87.0 84.0 83.0 106.0 135.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:56.30 Median:54.00 stddev:6.02 (11.1%) stddev-sans-first:6.33 13:47:34 INFO - PROCESS | 1660 | Values: 54.0 52.0 54.0 54.0 54.0 55.0 52.0 53.0 66.0 69.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:70.30 Median:71.50 stddev:3.27 (4.6%) stddev-sans-first:2.85 13:47:34 INFO - PROCESS | 1660 | Values: 65.0 65.0 73.0 74.0 74.0 72.0 69.0 71.0 70.0 70.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:84.30 Median:85.00 stddev:1.57 (1.8%) stddev-sans-first:1.64 13:47:34 INFO - PROCESS | 1660 | Values: 85.0 84.0 85.0 85.0 84.0 81.0 83.0 87.0 85.0 84.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:146.90 Median:138.00 stddev:26.12 (18.9%) stddev-sans-first:27.46 13:47:34 INFO - PROCESS | 1660 | Values: 137.0 139.0 135.0 137.0 135.0 135.0 135.0 141.0 219.0 156.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:178.70 Median:168.50 stddev:23.89 (14.2%) stddev-sans-first:25.08 13:47:34 INFO - PROCESS | 1660 | Values: 169.0 166.0 230.0 217.0 168.0 172.0 166.0 167.0 165.0 167.0 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:1.06 (1.6%) stddev-sans-first:1.12 13:47:34 INFO - PROCESS | 1660 | Values: 68.0 67.0 70.0 67.0 68.0 66.0 67.0 68.0 68.0 68.0 13:47:34 INFO - PROCESS | 1660 | -------- Summary: end -------- 13:47:34 INFO - PROCESS | 1660 | 13:47:34 INFO - TEST-INFO | 1660: exit 0 13:47:35 INFO - TEST-OK | kraken | took 59253ms 13:47:35 INFO - SUITE-END | took 1177s 13:47:35 INFO - Completed test suite (00:19:37) 13:47:35 INFO - javascript benchmark 13:47:35 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3341.997400779767, 3342.097633638917, 3378.1385746634605, 3388.79667219967, 3445.5865296164447, 5614.859628509289, 5658.971705141479, 5738.913916291249, 5785.884282314351, 5792.420757924211, 5688.715564221793, 5773.971130144343, 5813.360530341659, 5844.152597873306, 5891.793787217448, 4335.978320108395, 5184.922226166611, 5227.08526007949, 5268.367795864482, 5362.329708786402, 5377.11277639189, 5381.461853814607, 5414.242006119146, 5438.265834112403, 5459.0, 5182.196759502283, 5280.867978300535, 5284.894302113936, 5321.893562128756, 5363.0, 5204.505571970657, 5279.70961597112, 5286.62993590445, 5290.55030322423, 5297.073012222845, 5177.352830896138, 5204.583633309364, 5225.869353266199, 5255.76349064293, 5265.578753699695, 5763.4812866842185, 5771.0, 5774.769009239625, 5776.740046697909, 5777.422257774189, 5092.261622064817, 6120.41665836802, 6152.630842149485, 6177.66022868742, 6193.504519638418, 6092.634441933498, 6204.255489341263, 6206.6586337751405, 6218.129461875341, 6234.875302493925, 5852.063669812852, 5867.970660146757, 5885.440883116183, 5885.941140588539, 5886.0, 6144.77104579086, 6145.784897528565, 6169.660668663177, 6170.0, 6289.088082228142, 6123.84690382744, 6145.723442445101, 6153.230846144232, 6158.3841615838055, 6170.753169873245, 6192.0, 6197.03945888388, 6214.8135555933395, 6220.657863817533, 6222.62664240138, 5211.765470553834, 6041.914978148572, 6111.8904774697, 6133.645111970462, 6158.137860699506, 6244.656543890039, 6259.906101408483, 6261.186045814015, 6268.310485846553, 6277.686115694197, 4986.476419975923, 4988.700677959334, 5016.749162541932, 5024.522670346311, 5040.193569029011, 4314.115606300701, 4406.515283318832, 4442.955570444319, 4491.371208030813, 4493.0339976905125, 5615.2700148980375, 5702.059160238514, 5707.514861236758, 5730.627509211888, 5773.740181691834, 6198.597091189058, 6224.751009959551, 6237.625742455467, 6239.968800156061, 6248.843778905472, 5679.716014199274, 5758.765722486914, 5759.395263497272, 5770.6249093808765, 5827.941720582825, 5336.466353364633, 5410.458954104637, 5435.864103397446, 5447.1829225616475, 5450.727463626803, 6426.3573642635365, 6453.870922581616, 6459.192600924885, 6478.384553467413, 6481.643509607017, 6114.0, 6190.319064902767, 6198.690065496617, 6214.223222097238, 6214.8135555933395, 6138.357744607992, 6263.4362907338555, 6271.31015588294, 6416.294207637249, 6433.0, 6214.285357183981, 6215.254169499508, 6223.471004964629, 6227.595206311576, 6237.157983672146, 6221.09794079865, 6221.222347206599, 6230.00319948806, 6232.376762323913, 6232.968835155795, 5008.824691135793, 5814.418558144152, 6047.002244629587, 6180.134781130735, 6181.381861813783, 6099.067674246629, 6106.187656479639, 6109.175261339662, 6134.130457564626, 6142.78500252489, 5243.370795504411, 5903.586748927534, 5963.194968679346, 5997.640141591344, 6024.036154215304, 6013.789517366871, 6099.176611157437, 6117.938820611737, 6135.631862088289, 6139.96930015365, 6002.549808764447, 6029.60807547508, 6036.577439579188, 6058.485028772604, 6071.180390647206], "value": 5705.562586982979, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [477.66563405616074, 501.78674063523016, 519.6102922807894, 530.9123994540901, 536.4876542901529, 26897.865510672444, 40814.795926020364, 41162.79418602903, 41284.38073428897, 41405.17189656205, 43262.0, 43371.3494297585, 43620.78189609056, 43652.781736091274, 43695.78152109235, 8122.309603683694, 9282.118198771106, 9899.109080182781, 9904.95047524765, 9927.453990030546, 8967.417117887317, 9792.608295668164, 9794.755131121743, 9801.16690081344, 9805.166560842337, 8372.581370931459, 8408.537530435824, 9807.65673201438, 9833.11501964823, 9854.556544955458, 9733.951330243373, 9792.461414622228, 9794.0, 9803.068708472754, 9814.754631134207, 9642.373245739005, 9744.707658770249, 9748.025197480267, 9749.902500974971, 9758.072983066597, 34550.7907223246, 39081.804590977146, 40778.59221407778, 40793.1841363174, 40804.0, 3635.0971465532148, 3650.580183278904, 3660.304542137012, 3743.2139250757373, 4218.186179942623, 30816.38367232643, 32043.679563204536, 32082.51876221835, 32360.676393236, 32422.513662295085, 3187.10760986924, 3314.950275745866, 3320.1367644412385, 3375.51055097012, 3388.6272510023878, 358700.20649896585, 360906.0, 361431.19284403673, 362789.0, 363013.0, 59066.40933590609, 59081.704591476766, 59106.0, 59129.0, 59313.0, 53588.73205633947, 61447.6927615368, 61652.458688533145, 61776.07335889965, 61853.762924741255, 15281.770773438408, 15590.532284031271, 15720.528384148494, 15821.841781582267, 15848.366065357257, 12280.89471947511, 12341.568045118558, 12370.938145309396, 12375.071869609816, 12376.690582735504, 15859.762103568455, 15896.284667190004, 15908.92045539788, 15910.920445397698, 15976.360945562279, 5810.593258471951, 6247.562670613013, 6272.905906411407, 6311.538499985009, 6355.3009168991375, 831.8960129983752, 891.4963045879058, 971.3589031239349, 1019.169376957777, 1084.8175488717336, 27553.586696199574, 29235.0, 29330.0, 29995.550066749016, 30161.396772064436, 931.9906800931903, 1039.9480025998669, 1109.20691705431, 1145.307089211032, 1162.8488296521568, 15199.0, 15211.08733475995, 15330.846691533165, 15413.229338533029, 15461.648290632224, 561244.0, 562024.1898790562, 562640.0, 564795.0, 565643.0, 56347.43652563422, 56525.43474565284, 56548.717256414275, 56625.0, 56701.0, 1249.375312343828, 1307.1372893890177, 1376.1124074971387, 1401.5234820161195, 1431.2986636548017, 29689.0, 31182.844085779427, 31197.376052479278, 31785.682143178274, 32028.51957220597, 56071.0, 57248.0, 57339.0, 60601.0, 60605.39394606174, 618358.0, 627161.0, 627446.0, 627781.0, 629648.703512959, 23725.881370593037, 26370.73629263683, 26468.470630587664, 26997.515136667673, 26999.46001079928, 46264.53735462602, 47679.76160119316, 47937.760311198224, 48154.16531280434, 48329.0, 38656.0, 38966.80516597399, 39029.80485097556, 40618.78143655695, 40730.38904416496, 38600.0, 39014.41478377883, 39191.02022449461, 39983.800081000576, 40465.39301910532, 38315.425268620435, 39188.0, 39861.80069099636, 39935.60064399319, 41337.379939300336, 30858.919937802068, 34785.30429391449, 35288.227012234456, 35411.82294088513, 35506.934791956286, 25602.0, 25616.615750764126, 25706.0, 25966.35084122912, 26420.0], "value": 19720.190509265147, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.6184182924269, 370.84980582863943, 372.00859708875845, 389.1594156621697, 406.22816648368115, 15771.211439428025, 15930.522084337474, 20529.794702053012, 20967.895160524215, 21036.003159399705, 20179.495512612193, 20486.487837804063, 20552.691709624367, 20724.63768115942, 21082.789172108274, 5692.402297758737, 5820.708964551776, 6020.277566692003, 6089.81730548082, 6098.115773212881, 6050.304215015263, 6050.42520960508, 6105.297890742577, 6105.419985101408, 6111.266648002246, 3881.5536213335404, 5332.280142180794, 6671.432928201109, 6676.132102826626, 6756.0, 5832.941670583282, 5946.048632218824, 6595.802125936206, 6710.563813352116, 6715.597064176165, 5882.264716910387, 5924.081767326072, 5940.61386009908, 5943.791967281124, 5948.821535353946, 13334.266615336153, 13396.928245740415, 13428.261445620486, 13490.460381584724, 13719.1082579632, 1770.530809335527, 2178.836587255962, 2183.224955140918, 2183.530540933691, 2210.8793067632055, 9968.252381071448, 9987.550560224807, 10038.196944244442, 10202.489875506195, 10374.118199953013, 1844.6771814932333, 1882.4540883143873, 1955.7261983322346, 1971.5169783403014, 1973.9013049347475, 115203.42398288038, 116845.99999999916, 122205.0, 128997.35501322434, 133512.33243833817, 10823.621173258898, 11177.66467005991, 11283.54865805375, 11329.830052549218, 11600.825987610193, 14056.648583785487, 14086.71826563463, 14236.359363828653, 14270.215138167292, 14276.14343139415, 8083.0, 8361.832763344699, 8368.456050356708, 8458.87311690325, 8473.152684731478, 7216.5670059796585, 8247.876281855777, 8252.422330436812, 8405.117462666454, 8444.831103377897, 4132.746446044163, 4427.959429534057, 4442.311441726538, 4506.143832671782, 4514.7291162530355, 1885.70771530413, 1925.768907731053, 1927.5662975830605, 1952.5899561092046, 1970.1922211893054, 713.614836827691, 838.2455789789238, 892.1665976418393, 909.3907082254905, 928.7353104365224, 20144.59710805776, 21204.25785097514, 21229.575408491743, 21264.043118059726, 21284.574308513744, 796.4464697035505, 871.7079778274223, 973.4937832327291, 1014.2646581228697, 1032.9586816527255, 10639.840402393971, 11034.779304414027, 11093.89061093884, 11169.72075698114, 11203.383813890152, 593546.0, 611321.0, 615173.0, 627798.0, 633447.0, 88748.5562572183, 119983.99999999825, 123723.52552949071, 128522.2579164759, 143669.0, 931.701855406277, 954.0888451528634, 963.4989805301344, 967.8548217767391, 969.3650658818485, 15820.892537522263, 18637.534061648566, 19221.61556768885, 19705.0, 19781.802181977997, 54580.727096364266, 54791.45208548023, 55148.448515514334, 55959.0, 56270.71864640651, 79455.60272198603, 79983.20016799758, 81328.06544668239, 82304.58847705723, 82592.17407825845, 6735.865282694416, 6928.515003949877, 6941.167059952838, 7280.453965952469, 7295.306945840137, 9264.95367523158, 12105.69735756613, 12381.947534459672, 12833.528051902782, 13095.345232738135, 10863.619773307897, 11281.323120612462, 11303.321800691985, 11340.773184536429, 11377.886221137682, 10578.365298082139, 10874.836877447004, 11027.935910270407, 11199.328040317281, 11304.83042754343, 8194.713185038496, 11168.441577921234, 11423.143264255048, 11448.713782155513, 11567.942160289482, 7146.32109949554, 7307.159676637255, 7396.55620662762, 7485.962570187115, 7586.3930885527925, 10575.62985295511, 11031.172662050218, 11378.715532111764, 11408.486618102039, 11469.827952580885], "value": 9309.15057834985, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1517.1049081042186, 1539.438105091641, 1730.281932997806, 1866.3561071430352, 1888.9338873139443, 8421.284190843779, 8978.147076027783, 8984.236339911115, 9001.639934402632, 9004.864927026092, 7043.401310888581, 7923.48497347672, 8140.308073813718, 8676.913230867689, 8773.517456539888, 2375.1330764271047, 2404.1585445094165, 2407.554602398553, 2411.7347091819884, 2413.420779013033, 2358.3278765551822, 2394.2577800881695, 2396.5446565152565, 2397.280815755275, 2406.855588664686, 2376.239603326936, 2388.52229554089, 2388.5461762265113, 2398.1846172301416, 2404.8196385271085, 2367.9644805327935, 2386.5823480890863, 2395.197608801054, 2397.47255603766, 2399.256230568529, 2347.3310106619533, 2366.3610825077135, 2368.4434157972864, 2370.7392186859433, 2375.1924345722537, 7563.697452101909, 8674.826503469896, 8865.113488651148, 8886.777830554287, 8899.065598112169, 5178.9900969311, 5342.759575819097, 5343.599230057761, 5347.572194224492, 5359.5712343012465, 3673.687736542397, 3686.4101743720876, 3753.906152346186, 3755.380362240227, 3763.34141525232, 932.5663566441569, 1096.8409580610846, 1110.1673744691482, 1123.2867129372826, 1130.372643183033, 11836.230645008047, 11926.224795388272, 11937.40312984356, 11939.701507462296, 11956.521739130425, 4481.731096134243, 4606.286025666027, 4771.332013518041, 4798.880027999328, 4816.084943860656, 10012.749681258027, 10014.749631259278, 10034.64878729241, 10043.246756493292, 10044.799104017879, 5360.276362691065, 5364.436734142937, 5367.194920761917, 5372.247885296062, 5385.138377859524, 4320.071184695305, 4463.933041004388, 4790.161721698689, 4910.631702622318, 4916.459189489154, 3838.6353296436796, 3926.9607303926596, 3996.8401263949117, 3999.0802115513593, 4014.558398576154, 1271.7075072733323, 1285.8135570342247, 1286.0933042205222, 1286.1832736212573, 1307.5358247821887, 923.8891333040078, 927.939683920543, 945.7351941456403, 946.8816397950256, 995.9053889880449, 5800.854978625568, 5931.673757943355, 5991.2510936132985, 6076.0, 6106.511479081662, 438.06254615123663, 442.5419690620193, 448.6097095526847, 453.85249793817144, 456.59819358964563, 3972.0, 4014.631100008931, 4021.879343619696, 4056.8782936511952, 4113.4446849675505, 10512.684619461428, 10834.891651083546, 10950.671479855617, 10969.122470202525, 11002.559897604166, 10861.782764344827, 10866.891331086747, 10879.292845965145, 10895.618653347254, 10905.781884362268, 8221.54781487012, 8313.376496762647, 8314.833703326021, 8315.41792074561, 8317.0, 5633.324001119892, 5801.288619857068, 5972.104184372379, 5993.580449368676, 6006.429389208019, 10056.19550435978, 10101.89898101039, 10169.186465082628, 10174.694759157237, 10182.643607474, 10106.039926206999, 10378.740531486774, 10408.843867341844, 10413.062824345845, 10413.635522756667, 5690.0, 6933.930660693329, 7174.067371241706, 7604.5437273763755, 7660.578668173081, 8201.917980820115, 8532.530710810965, 8551.0, 8554.57227138653, 8563.700270490503, 6409.4231519163495, 7539.132999705104, 7631.3131818136635, 7632.80917977055, 7795.064592248967, 7383.889241661272, 7541.509801862861, 7612.505187162817, 7617.771466856003, 7851.489653172525, 6753.966230169014, 6943.79168624942, 6944.340287672663, 6992.860142797217, 7008.404285635778, 5075.87310317245, 5086.940619408736, 5247.475252474722, 5306.938612277483, 5323.281357016753, 5155.2782610435315, 5190.532852043334, 5445.373782015119, 5537.1140617501005, 5551.528120109836], "value": 4554.928965431457, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.8103808372223, 300.05333173836544, 312.0653642341189, 316.5552398879575, 318.42842098433323, 4323.178596066745, 5179.922301165481, 5205.557527610158, 5219.973900130499, 5248.685078895269, 4500.0, 5102.413222479416, 5128.5895629054285, 5241.344831896013, 5248.239005344232, 3453.7755045921936, 3725.031491812121, 3734.6825519830845, 3975.423563583279, 3980.2039592081555, 3937.606239376068, 3993.580674029214, 3995.720299579031, 3996.160806230695, 4030.5087117766466, 3241.1735007573034, 3888.6500214980647, 3915.629529664623, 3938.251732170893, 3940.7438516496336, 3650.6349365063547, 3703.6296370362747, 3761.849526018956, 3894.2600905827944, 3904.921901561967, 3428.9314213715834, 3461.5153878457036, 3474.617792042873, 3481.3385456763135, 3489.3544694231396, 5039.521245481674, 5046.823361182341, 5049.848504544832, 5060.9493905060845, 5087.542121209072, 4394.857337092347, 4521.525239849801, 4528.524504926968, 4551.543506078056, 4585.656075794329, 5353.491418315292, 5377.2896701275995, 5384.496209262712, 5411.21537377082, 5417.241586177938, 3692.8153592320546, 3772.1512659651635, 3775.9622403775884, 3788.1666033472593, 3793.867214647474, 5104.800371912597, 5219.5811743308195, 5557.472040156179, 5647.943520564824, 5671.659700417947, 3189.421236487953, 3794.962050379516, 3807.3908174691915, 3838.808059597009, 3849.9615003850163, 3904.141088960424, 3908.511436070491, 3918.8432462701744, 3920.941185882214, 3939.7045221608496, 3697.946085365658, 4321.891952701145, 4367.606915377632, 4475.26158183896, 4489.618382437464, 4746.359241502353, 4915.9508404916205, 5396.64921780083, 5481.3141560635, 5509.228707980886, 1297.280009594684, 1333.6796571394443, 1336.2028072242817, 1336.652470357714, 1336.926469044212, 727.4616783580218, 791.9881201781977, 800.3957012455675, 837.7947402886332, 847.3348421489102, 3689.7970611616083, 3968.543617483969, 4197.307444271661, 4928.901421971541, 5026.933570521978, 4911.68074075184, 5162.174052151713, 5360.785568577213, 5624.737652161736, 5659.886802264014, 4656.7194021643645, 4699.859004229878, 4742.383490146328, 5103.851633382534, 5112.565431938327, 2744.643196384457, 2875.0737006205472, 2888.234617826278, 2914.621099257083, 2947.292649764084, 5391.32608423947, 5676.68778217202, 5722.939741692987, 5803.419658034247, 5804.738786754522, 4941.752912354368, 5375.758090885842, 5481.614708233477, 5581.441855814386, 5668.631538949955, 5724.227229324071, 5783.624064435799, 5805.709714514257, 5810.6513609183585, 5826.271716035496, 4616.79224434908, 4694.460137084143, 4887.43794463627, 5479.945200547943, 5562.582806289463, 5274.287971123849, 5436.966976274495, 5455.536279416294, 5491.917621235764, 5507.834764957058, 4823.734694591831, 5656.925184214986, 5778.855528611818, 5783.884322313615, 5788.681622510802, 3094.659587445334, 3492.109512074405, 3839.5392552892713, 3843.2889915365745, 3869.59369266223, 5041.2438134280155, 5175.748549381031, 5191.6884986900905, 5281.0, 5594.832155035193, 4965.354503914468, 5364.390682795224, 5384.6769193848495, 5423.7254245253125, 5423.7830486780085, 5390.919136213039, 5411.567074634098, 5428.173137937823, 5433.239346491416, 5474.479924407175, 4466.620337271368, 4931.926021109758, 5284.9735751320995, 5334.1732031535175, 5370.0, 5100.194892809014, 5349.812756553502, 5462.0, 5477.561795056306, 5490.917636235539, 5166.793328266977, 5327.627066105335, 5352.1971704243115, 5356.3572371315695, 5370.677759334452], "value": 3968.0583426940516, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [737.8229224986005, 775.4223103787677, 782.6204290918903, 788.4993029426313, 839.3285371702636, 15458.84541154588, 16537.503874883743, 16565.25456354464, 16599.917000414996, 16609.584760380996, 14793.0, 14960.551183464513, 14968.0, 14970.251487425612, 15010.924945375285, 679.6601699150425, 700.264722041854, 833.1585099049958, 841.9410641255115, 866.6013633728493, 833.9833203335929, 855.7732200966749, 864.1962974433774, 872.3849685971406, 875.6191056890273, 921.9078092190795, 925.4817302310694, 925.6019911438079, 925.6898938855492, 927.0034712683859, 719.2879049740772, 890.3544929925816, 916.6058594804232, 920.2821798996794, 923.6997975657938, 688.1501345837886, 689.1454596300551, 689.7172159414616, 690.1614538335896, 690.9170899492043, 14808.185549794756, 14810.185439800807, 14810.703785924221, 14834.554963351116, 14835.629109272246, 12197.390130493528, 13572.389242484112, 13832.446702131903, 14038.0, 14485.493007744784, 11734.706632334175, 11786.194196984006, 11945.104117191246, 12038.699032524257, 12222.816657750052, 4541.43232095988, 4577.450705915278, 4584.312353147055, 4632.166210082184, 4657.0, 120368.79631203663, 120799.3960030203, 121265.0, 122011.0, 125492.0, 11017.559297628166, 11122.165837562216, 11125.499352529156, 11214.0, 11576.0, 21982.780172198072, 22022.0, 22076.88961555182, 22108.336749897528, 22135.114595416002, 7995.560244186626, 8072.798180045428, 8169.223923727355, 8175.5503447309775, 8342.249197572248, 3880.2821478026653, 4533.38799262102, 4565.292379681155, 4615.515370886009, 4623.953760462352, 3339.599248090196, 3442.3287458945765, 3448.293094918032, 3450.9654903450646, 3458.7578869478893, 583.5623282538096, 594.3877805859971, 596.5853731656457, 597.6682940967718, 597.8146774499832, 607.7751232044084, 609.1259043272918, 633.1673848888696, 636.462189449914, 643.2340933983851, 8974.596143173572, 9110.362274640844, 9125.726228213165, 9213.170814626581, 9272.860907086399, 494.653742380328, 527.3934974779035, 612.3294991983771, 612.4916319454842, 629.0400194466663, 3567.6253993330847, 4335.501417337046, 4351.12977404514, 4411.897025743564, 4507.549245075466, 133798.3310083463, 161907.0, 163879.0, 166826.16586916987, 171687.28312716962, 134640.32679836734, 134713.0, 139350.0, 142906.0, 143449.5655043457, 108972.4551377254, 111256.0, 113240.0, 113837.0, 117234.41382793032, 8599.570021498776, 8638.611262493328, 8665.566721664018, 8671.34964877624, 8719.12808719123, 59515.809683805215, 66117.66941165263, 68000.63998719899, 68301.0, 69764.60470790454, 95641.52179239059, 102039.0, 102366.881596126, 106587.0, 107785.0, 11683.240589361665, 11735.706607334885, 11742.647720568397, 11763.941180294043, 12172.39138043112, 12201.389930503261, 12357.93821030889, 12395.256284622952, 12850.357482126043, 12992.8051079232, 10300.381977081399, 10640.733981650521, 11094.334339939629, 12113.878861211628, 13226.801597975846, 13486.527971520949, 14028.988981789795, 14030.438782448588, 14118.647033823825, 14287.499937502138, 13690.724516757184, 13696.86303136956, 13740.587782366545, 13984.860151398356, 14074.437022518985, 10819.513121909691, 10967.396793176453, 11129.220954533104, 11342.973244498084, 11416.600418985296, 9022.86465703028, 10995.230333876552, 11439.428028598702, 11493.770124597628, 11520.827187592025], "value": 7828.196567628901, "unit": "score"}], "name": "dromaeo_css", "value": 7274.692577586157, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 111.0, 113.0, 111.0, 111.0, 110.0, 111.0, 113.0, 111.0, 121.0], "value": 112.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 106.0, 130.0, 160.0, 106.0, 132.0, 155.0, 134.0, 158.0, 106.0], "value": 133.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 180.0, 182.0, 181.0, 182.0, 183.0, 185.0, 179.0, 187.0], "value": 182.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 73.0, 148.0, 57.0, 56.0, 57.0, 69.0, 140.0, 56.0], "value": 81.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 81.0, 131.0, 70.0, 71.0, 72.0, 119.0, 70.0, 69.0, 117.0], "value": 88.2, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 87.0, 85.0, 87.0, 83.0, 87.0, 84.0, 87.0, 84.0], "value": 85.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [101.0, 92.0, 92.0, 94.0, 93.0, 92.0, 95.0, 94.0, 93.0, 93.0], "value": 93.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 83.0, 85.0, 86.0, 83.0, 87.0, 84.0, 83.0, 106.0, 135.0], "value": 91.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 54.0, 54.0, 54.0, 55.0, 52.0, 53.0, 66.0, 69.0], "value": 56.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 73.0, 74.0, 74.0, 72.0, 69.0, 71.0, 70.0, 70.0], "value": 70.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 84.0, 85.0, 85.0, 84.0, 81.0, 83.0, 87.0, 85.0, 84.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [137.0, 139.0, 135.0, 137.0, 135.0, 135.0, 135.0, 141.0, 219.0, 156.0], "value": 146.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 166.0, 230.0, 217.0, 168.0, 172.0, 166.0, 167.0, 165.0, 167.0], "value": 178.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 67.0, 70.0, 67.0, 68.0, 66.0, 67.0, 68.0, 68.0, 68.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1472.7, "alertThreshold": 2.0}]} 13:47:35 INFO - Return code: 0 13:47:35 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:47:35 INFO - # TBPL SUCCESS # 13:47:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:47:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:47:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:47:35 INFO - 2016-08-23 13:47:35.401 screenresolution[1664:10093] starting screenresolution argv=screenresolution get 13:47:35 INFO - 2016-08-23 13:47:35.404 screenresolution[1664:10093] Display 0: 1600x1200x32@60 13:47:35 INFO - 2016-08-23 13:47:35.410 screenresolution[1665:10096] starting screenresolution argv=screenresolution list 13:47:35 INFO - Available Modes on Display 0 13:47:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:47:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:47:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:47:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:47:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:47:35 INFO - Intel Iris: 13:47:35 INFO - Chipset Model: Intel Iris 13:47:35 INFO - Type: GPU 13:47:35 INFO - Bus: Built-In 13:47:35 INFO - VRAM (Dynamic, Max): 1536 MB 13:47:35 INFO - Vendor: Intel (0x8086) 13:47:35 INFO - Device ID: 0x0a2e 13:47:35 INFO - Revision ID: 0x0009 13:47:35 INFO - Displays: 13:47:35 INFO - Display: 13:47:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:47:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:47:35 INFO - Display Serial Number: 1600x1200 60 13:47:35 INFO - Main Display: Yes 13:47:35 INFO - Mirror: Off 13:47:35 INFO - Online: Yes 13:47:35 INFO - Rotation: Supported 13:47:35 INFO - Return code: 0 13:47:35 INFO - Running post-action listener: _resource_record_post_action 13:47:35 INFO - [mozharness: 2016-08-23 20:47:35.648466Z] Finished run-tests step (success) 13:47:35 INFO - Running post-run listener: _resource_record_post_run 13:47:35 INFO - Total resource usage - Wall time: 1212s; CPU: 53.0%; Read bytes: 38654976; Write bytes: 705879552; Read time: 393; Write time: 1954 13:47:35 INFO - TinderboxPrint: CPU usage
53.5% 13:47:35 INFO - TinderboxPrint: I/O read bytes / time
38,654,976 / 393 13:47:35 INFO - TinderboxPrint: I/O write bytes / time
705,879,552 / 1,954 13:47:35 INFO - TinderboxPrint: CPU idle
2,256.0 (46.5%) 13:47:35 INFO - TinderboxPrint: CPU system
968.6 (20.0%) 13:47:35 INFO - TinderboxPrint: CPU user
1,625.0 (33.5%) 13:47:35 INFO - TinderboxPrint: Swap in / out
443,641,856 / 0 13:47:35 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 411189248; Write bytes: 408987136; Read time: 29941; Write time: 793 13:47:35 INFO - run-tests - Wall time: 1179s; CPU: 53.0%; Read bytes: 29428736; Write bytes: 295323648; Read time: 297; Write time: 1151 13:47:36 INFO - Running post-run listener: _upload_blobber_files 13:47:36 INFO - Blob upload gear active. 13:47:36 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:47:36 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 13:47:36 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'] 13:47:36 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 13:47:36 INFO - (blobuploader) - INFO - Open directory for files ... 13:47:36 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 13:47:36 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:36 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:37 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 13:47:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:37 INFO - (blobuploader) - INFO - Done attempting. 13:47:37 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 13:47:37 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:37 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:39 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 13:47:39 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:39 INFO - (blobuploader) - INFO - Done attempting. 13:47:39 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:47:39 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:47:39 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:47:41 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:47:41 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:47:41 INFO - (blobuploader) - INFO - Done attempting. 13:47:41 INFO - (blobuploader) - INFO - Iteration through files over. 13:47:41 INFO - Return code: 0 13:47:41 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:47:41 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:47:41 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be55255ab02f02b9d1e4fde5865fef1980f6c5d40340c5036c1a34ef90935ae62c1f4403a081507f29c66716c547c276d60e96b6c8f764401441c93b808c8181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc797cda76bf67dbe4d2b7f25630352e86fe9a4ff6f154f1f8804fcddf39a820fd1bd57395e0780b9143d9ada5f0ae6cc3fa7d9776fe1e39e966b45c45ff434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b5707974a0233eb09dddc347a5c5aaeb395c28e4ac48facc92ce891298fb106be98338923d3e1b83fe1278d2d6076495cfc485ee38b734ad4eec69eb85578f"} 13:47:41 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:47:41 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:47:41 INFO - Contents: 13:47:41 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/be55255ab02f02b9d1e4fde5865fef1980f6c5d40340c5036c1a34ef90935ae62c1f4403a081507f29c66716c547c276d60e96b6c8f764401441c93b808c8181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc797cda76bf67dbe4d2b7f25630352e86fe9a4ff6f154f1f8804fcddf39a820fd1bd57395e0780b9143d9ada5f0ae6cc3fa7d9776fe1e39e966b45c45ff434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b5707974a0233eb09dddc347a5c5aaeb395c28e4ac48facc92ce891298fb106be98338923d3e1b83fe1278d2d6076495cfc485ee38b734ad4eec69eb85578f"} 13:47:41 INFO - Running post-run listener: copy_logs_to_upload_dir 13:47:41 INFO - Copying logs to upload dir... 13:47:41 INFO - mkdir: /builds/slave/test/build/upload/logs 13:47:41 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.858175 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-08-23 13:47:41.424678) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 13:47:41.427817) ========= 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.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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/be55255ab02f02b9d1e4fde5865fef1980f6c5d40340c5036c1a34ef90935ae62c1f4403a081507f29c66716c547c276d60e96b6c8f764401441c93b808c8181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc797cda76bf67dbe4d2b7f25630352e86fe9a4ff6f154f1f8804fcddf39a820fd1bd57395e0780b9143d9ada5f0ae6cc3fa7d9776fe1e39e966b45c45ff434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b5707974a0233eb09dddc347a5c5aaeb395c28e4ac48facc92ce891298fb106be98338923d3e1b83fe1278d2d6076495cfc485ee38b734ad4eec69eb85578f"} build_url:https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011346 build_url: 'https://queue.taskcluster.net/v1/task/LpfbblFLSnC6tBcUeM33tw/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/be55255ab02f02b9d1e4fde5865fef1980f6c5d40340c5036c1a34ef90935ae62c1f4403a081507f29c66716c547c276d60e96b6c8f764401441c93b808c8181", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/adc797cda76bf67dbe4d2b7f25630352e86fe9a4ff6f154f1f8804fcddf39a820fd1bd57395e0780b9143d9ada5f0ae6cc3fa7d9776fe1e39e966b45c45ff434", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c3b5707974a0233eb09dddc347a5c5aaeb395c28e4ac48facc92ce891298fb106be98338923d3e1b83fe1278d2d6076495cfc485ee38b734ad4eec69eb85578f"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 13:47:41.461516) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:47:41.461864) ========= 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.hY9xTM4KC8/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.Z10ai29k98/Listeners TMPDIR=/var/folders/5h/cmwhq37s15q4p6v6d2gyhw5w00000w/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.005223 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 13:47:41.510340) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 13:47:41.510688) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 13:47:41.511052) ========= ========= Total master_lag: 0.17 =========