builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0353 starttime: 1472548548.53 results: success (0) buildid: 20160830010820 builduid: 7e2903cb159945c898d40f58c0c1aeb0 revision: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:48.532338) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:48.532759) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:48.533143) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:48.900721) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:48.901130) ========= 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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-30 02:15:48-- 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% 119M=0s 2016-08-30 02:15:49 (119 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.155573 ========= master_lag: 0.05 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:49.101996) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:49.102313) ========= 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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.032465 ========= master_lag: 0.05 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:49.179819) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 02:15:49.180186) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev bc60cb47882ac16cd4bade1e2d0e2623130f2e9b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev bc60cb47882ac16cd4bade1e2d0e2623130f2e9b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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-30 02:15:49,310 truncating revision to first 12 chars 2016-08-30 02:15:49,311 Setting DEBUG logging. 2016-08-30 02:15:49,311 attempt 1/10 2016-08-30 02:15:49,311 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/bc60cb47882a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-30 02:15:50,621 unpacking tar archive at: larch-bc60cb47882a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.570030 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-30 02:15:50.818390) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:50.818719) ========= script_repo_revision: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:50.819123) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:50.819423) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-30 02:15:50.840697) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-30 02:15:50.841112) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 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', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:15:51 INFO - MultiFileLogger online at 20160830 02:15:51 in /builds/slave/test 02:15:51 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 02:15:51 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:15:51 INFO - {'append_to_log': False, 02:15:51 INFO - 'base_work_dir': '/builds/slave/test', 02:15:51 INFO - 'blob_upload_branch': 'Larch', 02:15:51 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:15:51 INFO - 'branch': 'Larch', 02:15:51 INFO - 'buildbot_json_path': 'buildprops.json', 02:15:51 INFO - 'config_files': ('talos/mac_config.py',), 02:15:51 INFO - 'default_actions': ('clobber', 02:15:51 INFO - 'read-buildbot-config', 02:15:51 INFO - 'download-and-extract', 02:15:51 INFO - 'populate-webroot', 02:15:51 INFO - 'create-virtualenv', 02:15:51 INFO - 'install', 02:15:51 INFO - 'run-tests'), 02:15:51 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:15:51 INFO - 'download_minidump_stackwalk': True, 02:15:51 INFO - 'download_symbols': 'ondemand', 02:15:51 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:15:51 INFO - 'tooltool.py': '/tools/tooltool.py', 02:15:51 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:15:51 INFO - '/tools/misc-python/virtualenv.py')}, 02:15:51 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:15:51 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:15:51 INFO - 'installer_path': 'installer.exe', 02:15:51 INFO - 'log_level': 'info', 02:15:51 INFO - 'log_name': 'talos', 02:15:51 INFO - 'log_to_console': True, 02:15:51 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:15:51 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:15:51 INFO - 'opt_config_files': (), 02:15:51 INFO - 'pip_index': False, 02:15:51 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:51 INFO - 'cmd': ('bash', 02:15:51 INFO - '-c', 02:15:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:51 INFO - 'enabled': True, 02:15:51 INFO - 'halt_on_failure': False, 02:15:51 INFO - 'name': 'check_screen_resolution'},), 02:15:51 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:15:51 INFO - 'cmd': ('bash', 02:15:51 INFO - '-c', 02:15:51 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:15:51 INFO - 'enabled': True, 02:15:51 INFO - 'halt_on_failure': False, 02:15:51 INFO - 'name': 'check_screen_resolution'},), 02:15:51 INFO - 'run_cmd_checks_enabled': True, 02:15:51 INFO - 'sps_profile': False, 02:15:51 INFO - 'sps_profile_interval': 0, 02:15:51 INFO - 'suite': 'dromaeojs', 02:15:51 INFO - 'system_bits': '32', 02:15:51 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:15:51 INFO - 'title': 't-yosemite-r7-0353', 02:15:51 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:15:51 INFO - 'use_talos_json': True, 02:15:51 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:15:51 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:15:51 INFO - 'work_dir': 'build'} 02:15:51 INFO - [mozharness: 2016-08-30 09:15:51.039715Z] Running clobber step. 02:15:51 INFO - Running pre-action listener: _resource_record_pre_action 02:15:51 INFO - Running main action method: clobber 02:15:51 INFO - rmtree: /builds/slave/test/build 02:15:51 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:15:53 INFO - Running post-action listener: _resource_record_post_action 02:15:53 INFO - [mozharness: 2016-08-30 09:15:53.261443Z] Finished clobber step (success) 02:15:53 INFO - [mozharness: 2016-08-30 09:15:53.261754Z] Running read-buildbot-config step. 02:15:53 INFO - Running pre-action listener: _resource_record_pre_action 02:15:53 INFO - Running main action method: read_buildbot_config 02:15:53 INFO - Using buildbot properties: 02:15:53 INFO - { 02:15:53 INFO - "project": "", 02:15:53 INFO - "product": "firefox", 02:15:53 INFO - "script_repo_revision": "production", 02:15:53 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 02:15:53 INFO - "repository": "", 02:15:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 02:15:53 INFO - "buildid": "20160830010820", 02:15:53 INFO - "pgo_build": "False", 02:15:53 INFO - "basedir": "/builds/slave/test", 02:15:53 INFO - "buildnumber": 2, 02:15:53 INFO - "slavename": "t-yosemite-r7-0353", 02:15:53 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:15:53 INFO - "platform": "yosemite_r7", 02:15:53 INFO - "scheduler": "tests-larch-macosx64-talos", 02:15:53 INFO - "branch": "larch", 02:15:53 INFO - "repo_path": "projects/larch", 02:15:53 INFO - "revision": "bc60cb47882ac16cd4bade1e2d0e2623130f2e9b", 02:15:53 INFO - "stage_platform": "macosx64", 02:15:53 INFO - "builduid": "7e2903cb159945c898d40f58c0c1aeb0", 02:15:53 INFO - "slavebuilddir": "test" 02:15:53 INFO - } 02:15:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:15:53 INFO - Running post-action listener: _resource_record_post_action 02:15:53 INFO - [mozharness: 2016-08-30 09:15:53.267887Z] Finished read-buildbot-config step (success) 02:15:53 INFO - [mozharness: 2016-08-30 09:15:53.268109Z] Running download-and-extract step. 02:15:53 INFO - Running pre-action listener: _resource_record_pre_action 02:15:53 INFO - Running main action method: download_and_extract 02:15:53 INFO - mkdir: /builds/slave/test/build/tests 02:15:53 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')]} 02:15:53 INFO - trying https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:15:53 INFO - Downloading https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:15:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:15:54 INFO - Downloaded 1199 bytes. 02:15:54 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:15:54 INFO - Using the following test package requirements: 02:15:54 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:15:54 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:15:54 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:15:54 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'jsshell-mac.zip'], 02:15:54 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:15:54 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:15:54 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:15:54 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:15:54 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:15:54 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:15:54 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:15:54 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:15:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:15:54 INFO - trying https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:15:54 INFO - Downloading https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:15:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:15:55 INFO - Downloaded 16369843 bytes. 02:15:55 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 02:16:02 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 02:16:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:02 INFO - trying https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:16:02 INFO - Downloading https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:16:02 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:16:03 INFO - Downloaded 16369843 bytes. 02:16:03 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 02:16:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:10 INFO - trying https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:16:10 INFO - Downloading https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:16:10 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:16:11 INFO - Downloaded 14413998 bytes. 02:16:11 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 02:16: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')]} 02:16:11 INFO - trying https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:16:11 INFO - Downloading https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:16:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/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 02:16:15 INFO - Downloaded 111604398 bytes. 02:16:15 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:16:15 INFO - mkdir: /builds/slave/test/properties 02:16:15 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:16:15 INFO - Writing to file /builds/slave/test/properties/build_url 02:16:15 INFO - Contents: 02:16:15 INFO - build_url:https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:16:16 INFO - Running post-action listener: _resource_record_post_action 02:16:16 INFO - Running post-action listener: set_extra_try_arguments 02:16:16 INFO - [mozharness: 2016-08-30 09:16:16.026755Z] Finished download-and-extract step (success) 02:16:16 INFO - [mozharness: 2016-08-30 09:16:16.026883Z] Running populate-webroot step. 02:16:16 INFO - Running pre-action listener: _resource_record_pre_action 02:16:16 INFO - Running main action method: populate_webroot 02:16:16 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:16:16 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:16:16 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:16:16 INFO - '--tppagecycles', 02:16:16 INFO - '7'], 02:16:16 INFO - 'tests': ['tsvgm']}}, 02:16:16 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:16:16 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:16:16 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:16:16 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:16:16 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:16 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:16:16 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['damp', 'tps']}, 02:16:16 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'tests': ['damp', 'tps']}, 02:16:16 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['dromaeo_dom']}, 02:16:16 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:16:16 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['basic_compositor_video']}, 02:16:16 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:16:16 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['a11yr', 02:16:16 INFO - 'ts_paint', 02:16:16 INFO - 'tpaint', 02:16:16 INFO - 'sessionrestore', 02:16:16 INFO - 'sessionrestore_no_auto_restore', 02:16:16 INFO - 'tabpaint']}, 02:16:16 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tsvgx', 02:16:16 INFO - 'tsvgr_opacity', 02:16:16 INFO - 'tart', 02:16:16 INFO - 'tscrollx', 02:16:16 INFO - 'cart']}, 02:16:16 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:16:16 INFO - 'tsvgr_opacity', 02:16:16 INFO - 'tart', 02:16:16 INFO - 'tscrollx', 02:16:16 INFO - 'cart']}, 02:16:16 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s'], 02:16:16 INFO - 'tests': ['tp5o']}, 02:16:16 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'tests': ['tp5o']}, 02:16:16 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'talos_options': ['--disable-e10s', 02:16:16 INFO - '--xperf_path', 02:16:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:16 INFO - 'tests': ['tp5n']}, 02:16:16 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:16:16 INFO - 'talos_options': ['--xperf_path', 02:16:16 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:16:16 INFO - 'tests': ['tp5n']}}, 02:16:16 INFO - 'talos.zip': {'path': '', 02:16:16 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:16:16 INFO - Running post-action listener: _resource_record_post_action 02:16:16 INFO - [mozharness: 2016-08-30 09:16:16.045223Z] Finished populate-webroot step (success) 02:16:16 INFO - [mozharness: 2016-08-30 09:16:16.045326Z] Running create-virtualenv step. 02:16:16 INFO - Running pre-action listener: _resource_record_pre_action 02:16:16 INFO - Running main action method: create_virtualenv 02:16:16 INFO - Creating virtualenv /builds/slave/test/build/venv 02:16:16 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 02:16:16 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:16:16 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:16:16 INFO - Using real prefix '/tools/python27' 02:16:16 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:16:16 INFO - Installing distribute.............................................................................................................................................................................................done. 02:16:19 INFO - Installing pip.................done. 02:16:19 INFO - Return code: 0 02:16:19 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:16:19 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')]} 02:16:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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')]} 02:16:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:19 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:19 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 02:16:19 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 02:16:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:19 INFO - 'HOME': '/Users/cltbld', 02:16:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:19 INFO - 'LOGNAME': 'cltbld', 02:16:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:19 INFO - 'MOZ_NO_REMOTE': '1', 02:16:19 INFO - 'NO_EM_RESTART': '1', 02:16:19 INFO - 'PAGER': '/bin/cat', 02:16:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:19 INFO - 'PWD': '/builds/slave/test', 02:16:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:19 INFO - 'SHELL': '/bin/bash', 02:16:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:19 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:19 INFO - 'USER': 'cltbld', 02:16:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:19 INFO - 'XPC_FLAGS': '0x0', 02:16:19 INFO - 'XPC_SERVICE_NAME': '0', 02:16:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:19 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 02:16:19 INFO - Cleaning up... 02:16:20 INFO - Return code: 0 02:16:20 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:16:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:16:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:20 INFO - 'HOME': '/Users/cltbld', 02:16:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:20 INFO - 'LOGNAME': 'cltbld', 02:16:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:20 INFO - 'MOZ_NO_REMOTE': '1', 02:16:20 INFO - 'NO_EM_RESTART': '1', 02:16:20 INFO - 'PAGER': '/bin/cat', 02:16:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:20 INFO - 'PWD': '/builds/slave/test', 02:16:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:20 INFO - 'SHELL': '/bin/bash', 02:16:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:20 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:20 INFO - 'USER': 'cltbld', 02:16:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:20 INFO - 'XPC_FLAGS': '0x0', 02:16:20 INFO - 'XPC_SERVICE_NAME': '0', 02:16:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:20 INFO - Downloading/unpacking psutil>=3.1.1 02:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:16:24 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:24 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:24 INFO - Installing collected packages: psutil 02:16:24 INFO - Running setup.py install for psutil 02:16:24 INFO - building 'psutil._psutil_osx' extension 02:16:24 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 02:16:24 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 02:16:24 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 02:16:24 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 02:16:25 INFO - building 'psutil._psutil_posix' extension 02:16:25 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 02:16:25 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:16:25 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:16:25 INFO - ^ 02:16:25 INFO - 1 warning generated. 02:16:25 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 02:16:25 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:16:25 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:16:25 INFO - Successfully installed psutil 02:16:25 INFO - Cleaning up... 02:16:25 INFO - Return code: 0 02:16:25 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:25 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:16:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:16:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:25 INFO - 'HOME': '/Users/cltbld', 02:16:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:25 INFO - 'LOGNAME': 'cltbld', 02:16:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:25 INFO - 'MOZ_NO_REMOTE': '1', 02:16:25 INFO - 'NO_EM_RESTART': '1', 02:16:25 INFO - 'PAGER': '/bin/cat', 02:16:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:25 INFO - 'PWD': '/builds/slave/test', 02:16:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:25 INFO - 'SHELL': '/bin/bash', 02:16:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:25 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:25 INFO - 'USER': 'cltbld', 02:16:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:25 INFO - 'XPC_FLAGS': '0x0', 02:16:25 INFO - 'XPC_SERVICE_NAME': '0', 02:16:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:25 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:28 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:16:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:16:28 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:16:28 INFO - Installing collected packages: mozsystemmonitor 02:16:28 INFO - Running setup.py install for mozsystemmonitor 02:16:28 INFO - Successfully installed mozsystemmonitor 02:16:28 INFO - Cleaning up... 02:16:28 INFO - Return code: 0 02:16:28 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:28 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:28 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 02:16:28 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 02:16:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:28 INFO - 'HOME': '/Users/cltbld', 02:16:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:28 INFO - 'LOGNAME': 'cltbld', 02:16:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:28 INFO - 'MOZ_NO_REMOTE': '1', 02:16:28 INFO - 'NO_EM_RESTART': '1', 02:16:28 INFO - 'PAGER': '/bin/cat', 02:16:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:28 INFO - 'PWD': '/builds/slave/test', 02:16:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:28 INFO - 'SHELL': '/bin/bash', 02:16:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:28 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:28 INFO - 'USER': 'cltbld', 02:16:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:28 INFO - 'XPC_FLAGS': '0x0', 02:16:28 INFO - 'XPC_SERVICE_NAME': '0', 02:16:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:28 INFO - Downloading/unpacking jsonschema==2.5.1 02:16:28 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 02:16:28 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 02:16:28 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 02:16:28 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 02:16:28 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 02:16:28 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 02:16:31 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:16:31 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:16:31 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 02:16:31 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 02:16:31 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 02:16:31 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 02:16:31 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 02:16:31 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 02:16:31 INFO - Downloading functools32-3.2.3-2.tar.gz 02:16:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:16:31 INFO - warning: no files found matching '*.txt' 02:16:31 INFO - no previously-included directories found matching 'build' 02:16:31 INFO - no previously-included directories found matching 'dist' 02:16:31 INFO - no previously-included directories found matching '.git*' 02:16:31 INFO - Installing collected packages: jsonschema, functools32 02:16:31 INFO - Running setup.py install for functools32 02:16:31 INFO - warning: no files found matching '*.txt' 02:16:31 INFO - no previously-included directories found matching 'build' 02:16:31 INFO - no previously-included directories found matching 'dist' 02:16:31 INFO - no previously-included directories found matching '.git*' 02:16:31 INFO - Successfully installed jsonschema functools32 02:16:31 INFO - Cleaning up... 02:16:31 INFO - Return code: 0 02:16:31 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:16:31 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')]} 02:16:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:31 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')]} 02:16:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:31 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:31 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 02:16:31 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 02:16:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:31 INFO - 'HOME': '/Users/cltbld', 02:16:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:31 INFO - 'LOGNAME': 'cltbld', 02:16:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:31 INFO - 'MOZ_NO_REMOTE': '1', 02:16:31 INFO - 'NO_EM_RESTART': '1', 02:16:31 INFO - 'PAGER': '/bin/cat', 02:16:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:31 INFO - 'PWD': '/builds/slave/test', 02:16:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:31 INFO - 'SHELL': '/bin/bash', 02:16:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:31 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:31 INFO - 'USER': 'cltbld', 02:16:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:31 INFO - 'XPC_FLAGS': '0x0', 02:16:31 INFO - 'XPC_SERVICE_NAME': '0', 02:16:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:32 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:16:32 INFO - Cleaning up... 02:16:32 INFO - Return code: 0 02:16:32 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:16:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 02:16:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:32 INFO - 'HOME': '/Users/cltbld', 02:16:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:32 INFO - 'LOGNAME': 'cltbld', 02:16:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:32 INFO - 'MOZ_NO_REMOTE': '1', 02:16:32 INFO - 'NO_EM_RESTART': '1', 02:16:32 INFO - 'PAGER': '/bin/cat', 02:16:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:32 INFO - 'PWD': '/builds/slave/test', 02:16:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:32 INFO - 'SHELL': '/bin/bash', 02:16:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:32 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:32 INFO - 'USER': 'cltbld', 02:16:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:32 INFO - 'XPC_FLAGS': '0x0', 02:16:32 INFO - 'XPC_SERVICE_NAME': '0', 02:16:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:32 INFO - Downloading/unpacking blobuploader==1.2.4 02:16:32 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 02:16:32 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 02:16:32 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 02:16:32 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 02:16:32 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 02:16:32 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 02:16:34 INFO - Downloading blobuploader-1.2.4.tar.gz 02:16:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:16:35 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:16:35 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:35 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:35 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:36 INFO - Downloading docopt-0.6.1.tar.gz 02:16:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:16:36 INFO - Installing collected packages: blobuploader, requests, docopt 02:16:36 INFO - Running setup.py install for blobuploader 02:16:36 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:16:36 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:16:36 INFO - Running setup.py install for requests 02:16:36 INFO - Running setup.py install for docopt 02:16:37 INFO - Successfully installed blobuploader requests docopt 02:16:37 INFO - Cleaning up... 02:16:37 INFO - Return code: 0 02:16:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:37 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:37 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 02:16:37 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 02:16:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:37 INFO - 'HOME': '/Users/cltbld', 02:16:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:37 INFO - 'LOGNAME': 'cltbld', 02:16:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:37 INFO - 'MOZ_NO_REMOTE': '1', 02:16:37 INFO - 'NO_EM_RESTART': '1', 02:16:37 INFO - 'PAGER': '/bin/cat', 02:16:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:37 INFO - 'PWD': '/builds/slave/test', 02:16:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:37 INFO - 'SHELL': '/bin/bash', 02:16:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:37 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:37 INFO - 'USER': 'cltbld', 02:16:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:37 INFO - 'XPC_FLAGS': '0x0', 02:16:37 INFO - 'XPC_SERVICE_NAME': '0', 02:16:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-r8XbHO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-_n5q6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cE25Rr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-UNET99-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:37 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-vpgS6g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-gphfNZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-nocsY1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-J7UCUS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-sZeV0V-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mAAXju-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-2blhfD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-QnUNmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-7bduKH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-CBsLl9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:38 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-Qr3WAO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:39 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-9EDCx4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:39 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-cgOY2_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:39 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:16:39 INFO - Running setup.py install for manifestparser 02:16:39 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:16:39 INFO - Running setup.py install for mozcrash 02:16:39 INFO - Running setup.py install for mozdebug 02:16:39 INFO - Running setup.py install for mozdevice 02:16:39 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:16:39 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:16:39 INFO - Running setup.py install for mozfile 02:16:39 INFO - Running setup.py install for mozhttpd 02:16:40 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Running setup.py install for mozinfo 02:16:40 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Running setup.py install for mozInstall 02:16:40 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Running setup.py install for mozleak 02:16:40 INFO - Running setup.py install for mozlog 02:16:40 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Running setup.py install for moznetwork 02:16:40 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:16:40 INFO - Running setup.py install for mozprocess 02:16:41 INFO - Running setup.py install for mozprofile 02:16:41 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:16:41 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:16:41 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:16:41 INFO - Running setup.py install for mozrunner 02:16:41 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:16:41 INFO - Running setup.py install for mozscreenshot 02:16:41 INFO - Running setup.py install for moztest 02:16:41 INFO - Running setup.py install for mozversion 02:16:41 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:16:41 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:16:41 INFO - Cleaning up... 02:16:41 INFO - Return code: 0 02:16:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:16:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:41 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:41 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 02:16:41 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 02:16:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:41 INFO - 'HOME': '/Users/cltbld', 02:16:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:41 INFO - 'LOGNAME': 'cltbld', 02:16:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:41 INFO - 'MOZ_NO_REMOTE': '1', 02:16:41 INFO - 'NO_EM_RESTART': '1', 02:16:41 INFO - 'PAGER': '/bin/cat', 02:16:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:41 INFO - 'PWD': '/builds/slave/test', 02:16:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:41 INFO - 'SHELL': '/bin/bash', 02:16:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:41 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:41 INFO - 'USER': 'cltbld', 02:16:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:41 INFO - 'XPC_FLAGS': '0x0', 02:16:41 INFO - 'XPC_SERVICE_NAME': '0', 02:16:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-NhY4K5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-V24bSZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-PwAibh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-rVgugk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-3OP9Oa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-mMAl8N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ItbBF4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-nmSihD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:16:42 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)) 02:16:42 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:16:42 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ikVhLq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-_MOYvp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-pOolq4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-px9Do7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-ojpRAM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-kNWQte-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-9Qk9nn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-WATiq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:16:43 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)) 02:16:43 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:16:43 INFO - Running setup.py (path:/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/pip-wEXnuC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:16:43 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)) 02:16:43 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)) 02:16:43 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)) 02:16:43 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)) 02:16:43 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)) 02:16:43 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)) 02:16:43 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:16:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:16:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:16:46 INFO - Downloading blessings-1.6.tar.gz 02:16:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:16:46 INFO - Installing collected packages: blessings 02:16:46 INFO - Running setup.py install for blessings 02:16:46 INFO - Successfully installed blessings 02:16:46 INFO - Cleaning up... 02:16:46 INFO - Return code: 0 02:16:46 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:16:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:47 INFO - Reading from file tmpfile_stdout 02:16:47 INFO - Current package versions: 02:16:47 INFO - blessings == 1.6 02:16:47 INFO - blobuploader == 1.2.4 02:16:47 INFO - docopt == 0.6.1 02:16:47 INFO - functools32 == 3.2.3-2 02:16:47 INFO - jsonschema == 2.5.1 02:16:47 INFO - manifestparser == 1.1 02:16:47 INFO - mozInstall == 1.12 02:16:47 INFO - mozcrash == 0.17 02:16:47 INFO - mozdebug == 0.1 02:16:47 INFO - mozdevice == 0.48 02:16:47 INFO - mozfile == 1.2 02:16:47 INFO - mozhttpd == 0.7 02:16:47 INFO - mozinfo == 0.9 02:16:47 INFO - mozleak == 0.1 02:16:47 INFO - mozlog == 3.3 02:16:47 INFO - moznetwork == 0.27 02:16:47 INFO - mozprocess == 0.23 02:16:47 INFO - mozprofile == 0.28 02:16:47 INFO - mozrunner == 6.12 02:16:47 INFO - mozscreenshot == 0.1 02:16:47 INFO - mozsystemmonitor == 0.3 02:16:47 INFO - moztest == 0.7 02:16:47 INFO - mozversion == 1.4 02:16:47 INFO - psutil == 3.1.1 02:16:47 INFO - requests == 1.2.3 02:16:47 INFO - wsgiref == 0.1.2 02:16:47 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:47 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 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:47 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 02:16:47 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 02:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:47 INFO - 'HOME': '/Users/cltbld', 02:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:47 INFO - 'LOGNAME': 'cltbld', 02:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:47 INFO - 'MOZ_NO_REMOTE': '1', 02:16:47 INFO - 'NO_EM_RESTART': '1', 02:16:47 INFO - 'PAGER': '/bin/cat', 02:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:47 INFO - 'PWD': '/builds/slave/test', 02:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:47 INFO - 'SHELL': '/bin/bash', 02:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:47 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:47 INFO - 'USER': 'cltbld', 02:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:47 INFO - 'XPC_FLAGS': '0x0', 02:16:47 INFO - 'XPC_SERVICE_NAME': '0', 02:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 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)) 02:16:47 INFO - Cleaning up... 02:16:47 INFO - Return code: 0 02:16:47 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:16: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')]} 02:16:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:16:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16: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')]} 02:16:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:16:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:16:47 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a95ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a9f1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa38a574850>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, '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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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 02:16:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:16:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:16:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:16:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:16:47 INFO - 'HOME': '/Users/cltbld', 02:16:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:16:47 INFO - 'LOGNAME': 'cltbld', 02:16:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:16:47 INFO - 'MOZ_NO_REMOTE': '1', 02:16:47 INFO - 'NO_EM_RESTART': '1', 02:16:47 INFO - 'PAGER': '/bin/cat', 02:16:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:16:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:16:47 INFO - 'PWD': '/builds/slave/test', 02:16:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:16:47 INFO - 'SHELL': '/bin/bash', 02:16:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:16:47 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:16:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:16:47 INFO - 'USER': 'cltbld', 02:16:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:16:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:16:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:16:47 INFO - 'XPC_FLAGS': '0x0', 02:16:47 INFO - 'XPC_SERVICE_NAME': '0', 02:16:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:16:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:16:47 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:16:47 INFO - Cleaning up... 02:16:47 INFO - Return code: 0 02:16:47 INFO - Running post-action listener: _resource_record_post_action 02:16:47 INFO - Running post-action listener: _start_resource_monitoring 02:16:47 INFO - Starting resource monitoring. 02:16:47 INFO - [mozharness: 2016-08-30 09:16:47.648733Z] Finished create-virtualenv step (success) 02:16:47 INFO - [mozharness: 2016-08-30 09:16:47.649691Z] Running install step. 02:16:47 INFO - Running pre-action listener: _resource_record_pre_action 02:16:47 INFO - Running main action method: install 02:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:16:47 INFO - Reading from file tmpfile_stdout 02:16:47 INFO - Detecting whether we're running mozinstall >=1.0... 02:16:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:16:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:16:48 INFO - Reading from file tmpfile_stdout 02:16:48 INFO - Output received: 02:16:48 INFO - Usage: mozinstall [options] installer 02:16:48 INFO - Options: 02:16:48 INFO - -h, --help show this help message and exit 02:16:48 INFO - -d DEST, --destination=DEST 02:16:48 INFO - Directory to install application into. [default: 02:16:48 INFO - "/builds/slave/test"] 02:16:48 INFO - --app=APP Application being installed. [default: firefox] 02:16:48 INFO - mkdir: /builds/slave/test/build/application 02:16:48 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'] 02:16:48 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 02:17:22 INFO - Reading from file tmpfile_stdout 02:17:22 INFO - Output received: 02:17:22 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:17:22 INFO - Running post-action listener: _resource_record_post_action 02:17:22 INFO - [mozharness: 2016-08-30 09:17:22.148912Z] Finished install step (success) 02:17:22 INFO - [mozharness: 2016-08-30 09:17:22.149017Z] Running run-tests step. 02:17:22 INFO - Running pre-action listener: _resource_record_pre_action 02:17:22 INFO - Running main action method: run_tests 02:17:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:17:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:17:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:17:22 INFO - 2016-08-30 02:17:22.163 screenresolution[1646:8474] starting screenresolution argv=screenresolution get 02:17:22 INFO - 2016-08-30 02:17:22.166 screenresolution[1646:8474] Display 0: 1600x1200x32@60 02:17:22 INFO - 2016-08-30 02:17:22.171 screenresolution[1647:8476] starting screenresolution argv=screenresolution list 02:17:22 INFO - Available Modes on Display 0 02:17:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:17:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:17:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:17:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:17:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:17:22 INFO - Intel Iris: 02:17:22 INFO - Chipset Model: Intel Iris 02:17:22 INFO - Type: GPU 02:17:22 INFO - Bus: Built-In 02:17:22 INFO - VRAM (Dynamic, Max): 1536 MB 02:17:22 INFO - Vendor: Intel (0x8086) 02:17:22 INFO - Device ID: 0x0a2e 02:17:22 INFO - Revision ID: 0x0009 02:17:22 INFO - Displays: 02:17:22 INFO - Display: 02:17:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:17:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:17:22 INFO - Display Serial Number: 1600x1200 60 02:17:22 INFO - Main Display: Yes 02:17:22 INFO - Mirror: Off 02:17:22 INFO - Online: Yes 02:17:22 INFO - Rotation: Supported 02:17:22 INFO - Return code: 0 02:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:17:22 INFO - Python 2.7.3 02:17:22 INFO - Return code: 0 02:17:22 INFO - grabbing minidump binary from tooltool 02:17: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')]} 02:17:22 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 0x10a8c0d30>, '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 0x10a625db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a8b8b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:17:22 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 02:17:22 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 02:17:22 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 02:17:22 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:17:22 INFO - Return code: 0 02:17:22 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:17:22 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:17:22 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:17:22 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:17:22 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:17:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 02:17:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0353 --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 02:17:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dKbGCQ3pBL/Render', 02:17:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:17:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:17:22 INFO - 'HOME': '/Users/cltbld', 02:17:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:17:22 INFO - 'LOGNAME': 'cltbld', 02:17:22 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:17:22 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:17:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:17:22 INFO - 'MOZ_NO_REMOTE': '1', 02:17:22 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:17:22 INFO - 'NO_EM_RESTART': '1', 02:17:22 INFO - 'PAGER': '/bin/cat', 02:17:22 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:17:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:17:22 INFO - 'PWD': '/builds/slave/test', 02:17:22 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:17:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:17:22 INFO - 'SHELL': '/bin/bash', 02:17:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.3F4z1RLEJA/Listeners', 02:17:22 INFO - 'TMPDIR': '/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/', 02:17:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:17:22 INFO - 'USER': 'cltbld', 02:17:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:17:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:17:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:17:22 INFO - 'XPC_FLAGS': '0x0', 02:17:22 INFO - 'XPC_SERVICE_NAME': '0', 02:17:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:17:22 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0353', '--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 02:17:22 INFO - mozversion application_buildid: 20160830010820 02:17:22 INFO - mozversion application_changeset: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b 02:17:22 INFO - mozversion application_display_name: Nightly 02:17:22 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:17:22 INFO - mozversion application_name: Firefox 02:17:22 INFO - mozversion application_remotingname: firefox 02:17:22 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 02:17:22 INFO - mozversion application_vendor: Mozilla 02:17:22 INFO - mozversion application_version: 51.0a1 02:17:22 INFO - mozversion platform_buildid: 20160830010820 02:17:22 INFO - mozversion platform_changeset: bc60cb47882ac16cd4bade1e2d0e2623130f2e9b 02:17:22 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 02:17:22 INFO - mozversion platform_version: 51.0a1 02:17:22 INFO - using testdate: 1472548642 02:17:22 INFO - actual date: 1472548642 02:17:22 INFO - starting webserver on 'localhost:49263' 02:17:22 INFO - SUITE-START | Running 2 tests 02:17:22 INFO - TEST-START | dromaeo_css 02:17:22 INFO - operating with platform_type : mac_ 02:17:22 INFO - Initialising browser for dromaeo_css test... 02:17:22 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpQsBT1z/profile http://localhost:49263/getInfo.html) 02:17:25 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 02:17:25 INFO - PROCESS | 1657 | colorDepth:24 02:17:25 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 02:17:25 INFO - PROCESS | 1657 | __metrics 02:17:25 INFO - PROCESS | 1657 | 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" 02:17:25 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:17:25 INFO - TEST-INFO | 1657: exit 0 02:17:25 INFO - Browser initialized. 02:17:25 INFO - Running cycle 1/1 for dromaeo_css test... 02:17:25 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpQsBT1z/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:17:37 INFO - PROCESS | 1660 | RSS: Main: 246067200 02:17:37 INFO - PROCESS | 1660 | 02:18:28 INFO - PROCESS | 1660 | 1472548708899 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] 02:20:40 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49263/tests/dromaeo/cssquery-ext.html) 02:20:41 INFO - PROCESS | 1660 | RSS: Main: 368615424 02:20:41 INFO - PROCESS | 1660 | 02:23:44 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-ext.html (next: http://localhost:49263/tests/dromaeo/cssquery-jquery.html) 02:23:45 INFO - PROCESS | 1660 | RSS: Main: 370552832 02:23:45 INFO - PROCESS | 1660 | 02:26:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49263/tests/dromaeo/cssquery-mootools.html) 02:26:48 INFO - PROCESS | 1660 | RSS: Main: 345567232 02:26:48 INFO - PROCESS | 1660 | 02:29:50 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49263/tests/dromaeo/cssquery-prototype.html) 02:29:51 INFO - PROCESS | 1660 | RSS: Main: 365850624 02:29:51 INFO - PROCESS | 1660 | 02:32:54 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49263/tests/dromaeo/cssquery-yui.html) 02:32:54 INFO - PROCESS | 1660 | RSS: Main: 333524992 02:32:54 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49263/tests/dromaeo/cssquery-yui.html (next: http://localhost:49263/tests/dromaeo/cssquery-dojo.html) 02:35:58 INFO - PROCESS | 1660 | RSS: Main: 511860736 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | __start_tp_report 02:35:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 02:35:58 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 02:35:58 INFO - PROCESS | 1660 | |i|pagename|runs| 02:35:58 INFO - PROCESS | 1660 | |0;dojo.html;3282.9343413131737;3293.9505907411376;3334.6994672077894;3372.5952885653724;3399.6260411354747;4327.459067616548;5509.283793106901;5561.91657125143;5631.352394474631;5648.802291919778;5296.443873393296;5649.604527683064;5686.402927692605;5691.544676425887;5728.398518155585;5033.798648054092;5081.237814327844;5118.692878427288;5122.8207012754565;5298.841034768963;5198.636095473318;5220.556252718524;5253.553447956928;5254.500822421883;5285.339332583427;5056.519630635084;5095.159298715745;5115.488451154892;5121.641485096045;5199;3968.444417781513;5114.309568208281;5120.257562653432;5131.358580177478;5178.4562620924635;5027.522385373383;5118.385793704742;5144.048351055029;5166.302549155854;5173.17229243323;5524.088525393306;5552.139418390156;5645.463680950304;5648.378678345379;5697.173909783059;6176.938230617681;6183.567150299481;6210.347913469066;6220.253569571681;6221.440070393641;4893.8776530586665;6227.314995350508;6256.374362563753;6270.372962703693;6301.52738544611;5581.3302403711;5649.717514124277;5690.402507736628;5767.741999930081;5769.048107062372;6279.529035322369;6285.277193122758;6289.371062893765;6290.4653104485715;6308.179936608211;6082.2701275847185;6099.054646529795;6111.052786818051;6121.785737499166;6138.14066030756;5973.671448070398;6028.758849646053;6136.294326152461;6255.46828519581;6273.65494897785;4706.976465117653;6104.216637118914;6112.327643959161;6148.569600128038;6210.627362358183;6178.474829639531;6188.597741146811;6192.287886892975;6200.131981522591;6208.50331973441;4826.734529600905;4961.6526843121355;4969.925451118236;4979.825706100269;5004.749762511859;4318.093200427946;4453.465584129925;4469.10617876426;4473.105378924228;4480.686351955332;5576.7211639417865;5588;5588.077967135378;5594.300712410949;5594.468525490072;6106.39999602577;6128.411459112376;6133.172021777003;6135.324535308531;6136.4578081083455;5533.667979921218;5546.972265138648;5561.221428999943;5602.215689803431;5664.801731939362;5352.036633405946;5365.892682146336;5369.167778994262;5374.274472946102;5379.273798037293;5241.711705856214;6234.189555357774;6341.873162536724;6350.396712312323;6355.459785918156;5108.7445627717725;6131.529590167291;6170.413810687977;6255.40573645503;6270.184875966095;6272.874542509216;6287.30839607652;6287.528435367274;6299.937000629935;6306.936930630635;5120.278330019881;6030.547708921761;6124.38756124384;6145.201123854049;6147.600405973598;6102.761931713857;6106.46066718015;6150.630962142286;6230.937690623217;6243.43809057187;4976;5965.627905581654;5986.55100867428;6003.699815009145;6068.8786224276155;5804.1293805929445;5942.257217847769;5974.522038236843;5975.521958243243;5990.071538911476;5694.430556944272;5732.656040637575;5774.2782152230975;5791.478766910998;5805;5964.433378829004;5981.641101533922;6002.969985150046;6009.669468179117;6034.09488576717;5811.883762324645;5838.854028649148;5857.824265272048;5859.589828711949;5865.090910908816 02:35:58 INFO - PROCESS | 1660 | |1;ext.html;488.94132704075514;505.44401158725407;509.09889495592824;522.0993785719634;529.6848375216746;26496.470070598578;32472.67527324729;42667.14665706688;42688;44509.109817803626;43945.560544394546;44293.33559996603;44298.778506107425;44367.77816110915;44409.333859992126;9774.902250977488;9949.95025024874;9967.10296073357;9975.950120249388;9994.150497207746;9666.709998700051;9779.266555008368;9797.412155270671;9812.067853553901;9818.607255709763;9815.362001469917;9834.9508252459;9856.063673950963;9874.1113299803;9876.061774131445;9754;9794.853077203848;9795.804083918352;9805.80388392228;9813.950930245339;9192.816143677155;9890.20878329739;9892.604295828158;9896;9896.307258491908;41562;41929.58070419318;41953.37069943953;42069.94825129366;42150.367744483854;3356.7482438817187;3380.5267262583257;3399.83000849959;3404.3872103021445;3419.128122328815;31370;31738.841305793554;31770.523442148387;31922.6807731922;32214.355712885845;3175.3173067790535;3274.2469232076755;3284.490903909898;3296.47256438971;3353.9161520961925;437077;439716;439768;443414;443590;61872.690636546526;62335;62347.688261559306;62458;62469.06296405467;34741.47887781635;35316.823415882754;35327;65339;65508;16442.83557164437;16552.255148518103;16562.668746625;16576.6684666306;16592.751108733377;12618.17981831178;12754.48982040708;12757.936210318889;12794;12796;16849.747253791204;19095.80817732098;19540.120978801304;19544.357332375606;19580.314688986102;6775.356341147682;8011.439199255996;8096.959515202387;8196.098429172785;8199.877001844978;838.5513750143643;859.7549698335944;958.6021800952526;1068;1115.1413411672966;29211.26971825679;29819.552706709415;29829.254268643457;30629.84685076605;30701;815.229608020415;1033.4395063454238;1108.6563165418745;1119.2165484160957;1196.1208511743862;14348.713025739427;16557.91721041387;16674.33302667904;16995.66008679844;17036.57408564771;640011;641934.1775695855;642800;645209;647639;59666.40333596608;60060.99999999913;60088.098678519855;60234.39765602376;60289.69855150696;1231.6982339326921;1275.387813849339;1350.2978451205317;1358.517726207202;1377.7313423882247;30981.225469363446;31280.68719312778;31402.842985784926;31695.841520793172;32264.83867580647;43781.78109109434;51856.22215666844;52610.47389526209;52665;52693;527303.7269627255;531717;533537;533723;533963;26791.866040669673;26893.596596051466;27461.03886363968;27475.450490990468;28436.881361355154;47504.524954751396;48393.03213935631;48426;48634;49129;37819;38641.227175456894;39553.406698900115;39937.40093898536;41282;39274.80362598169;39314.606853931095;39617;40302.39546406865;41004.58995410127;38211;38966.6103338963;39153.804230979804;39470.01324966784;39553.406698898965;28173.71826281711;31291.84354078215;33368.66631333656;34375.31249375048;34926.47610285797;24839.633820140065;25497.61753573735;25699.486010279317;26026.479470410864;26063.050948752127 02:35:58 INFO - PROCESS | 1660 | |2;jquery.html;326.2203334031664;353.6622525488159;380.2622911551591;383.29856362855975;406.32144318987287;15754.606134846634;16854.91572542137;20578.794212057874;21155.68266476004;21205.787942120594;20648.793512064876;20890.791092089075;20983.580328393462;21181.623194492346;21649.56700865982;5890.057590785475;5937.762489500415;6052.617125281495;6056;6120.938790612081;6036.305824830157;6122.510199184055;6134.110553969672;6139.2325959255095;6140.570160088796;4182.309918863384;5190.740462976855;6738.561993470409;6771.8984215236815;6831.146106736658;6472.320406357312;6648.301928297507;6757.054012438262;6761.7633382831855;6768.086308348384;5811.128330750379;5813;5817.447342502455;5820.1269809528485;5829;23405.53188936212;26254.6061809073;26851.462970740475;27277.590836137475;27707;2271.4434963434064;2293.9541209175723;2305.654151877215;2335.7547457517057;2707.91876243713;15020.849791502163;15247.390104395921;15265.931384803069;16024.519264422086;16050.956687815255;1772.858171346302;1786.59951220479;1816.5142163059888;1847.4827048426462;1884.9350117183885;103724;116705;119573.40213299052;120296;120589.79410205873;8811.427257228259;9313.674021409219;10589.735256618646;11046.668599942015;11270.773459952517;14021.719565608631;14050.297485125908;14074.648133796736;14076.225807580682;14087.15477071379;8356.623951922056;8640;8646.567671616394;8792.648294068165;8823;8644.178803013703;8657.740267791976;8662.350323725746;8853.51305678194;8875.112488751203;6591.89059500955;6732.865342693119;6759.121314229119;6781.4574834013165;6887.483438742114;1647.3328302037694;1760.8818400315893;1839.6872531669649;1842.824931631493;1854.1007611308505;886.5079880666163;906.1889608800087;928.8003079337975;940.4216406909838;1005.0954141272913;21087.472813179793;22417;22509.212177573707;22534.436639083826;22547.88726056359;955.9139677428926;974.9171320437842;976.0873583199589;977.9902200978041;988.7478692933256;10245.129164020993;11516.884831151749;11712.238704484182;11963.581274655346;11970.40147992597;556383.4520684915;567392;570337;584410.1558984441;585371;79481;115405.4229728846;119741;121494.77578363316;123710.38144809219;865.4547634990042;983.8081574093127;1009.3943633819649;1026.9332493387906;1028.8456731490337;19213.807861921203;19972.90013549923;19983.900080499505;19991.40025799228;20018.499537511678;47275.818104546284;49385.012299754526;49834.75082624709;50348.741281468254;50709.74645126751;74453;75374;75570.62214688891;76181.85727214206;77229.22770772221;7717.2668596483245;7932.960340154023;7965.203479651988;8398.244158025807;8405.915940840514;13406.93296533511;13496.595102146952;13534.526291579747;13810.861891380957;13899.652508687364;11604.245724027911;11678.532858685558;11746.76506469883;11853.940730296294;11944.581939632419;10593.735156621146;10710.464476776286;11518.884811152117;11623.070154387799;11694.88305116972;11674.774148714603;11912;11989.82015269789;11991.280523168638;12024.699382515508;7113.6443177839865;8033.598320084089;8112.878306825284;8232.876506852283;8246.505209687439;10454.843177352193;11753.177277590486;11834.988052031347;11928.284302941851;11965.461554229907 02:35:58 INFO - PROCESS | 1660 | |3;mootools.html;1356.7829147336415;1467.8018467506886;1486.620911667525;1699.1843914920837;1907.4563749331444;7762.805929851749;8549;8775.166359195866;8786.12138786121;8917.732468025953;8695.521746303952;8742.038375778659;8762.430442021265;8764.386492945481;8776;2374.3233178544074;2400.747921468238;2401.399650087478;2403.038784486202;2405.194259922919;2019.2629690163058;2349.130821596012;2367.562001029815;2395.4490467192554;2397.496525729599;2292.1748170658548;2337.4857531343077;2361.409647588103;2366.9171578994738;2376.1327115602767;2035.4300795777206;2064.9984757392654;2273.158931195468;2317.8145748340094;2335.2877372401413;2337.637666161748;2339.4619237575453;2340.1458467659354;2345.5543446745064;2345.8944347504234;8658.783530411727;8688.435251708683;8749.518776467292;8775.210231079234;8781.692640757543;5184.7407629618365;5380.623356365017;5384.67691938481;5387.218853266255;5404.5946554008215;3497.45789402643;3522.3131489359585;3584.982075089608;3586.4620306953907;3589.353916295066;1089.7548051688386;1100.2023532938636;1104.127739086121;1123.5112725964232;1127.7518945831903;10170.542325595366;11403.942980285046;11464.19750617457;11563.942180289128;11574.842515748442;3930.4890364252465;4334.263175260214;4423.933640995388;4569.017661202857;4604.355390245369;9048.864267036;9051.502167380857;9061.501617410973;9062.682806101755;9090.136437038604;5201.505856943584;5211.296474975829;5214.504622060898;5226.660267082628;5232.136697444879;4362.149380870763;4413.514513403523;4522.615577675934;4710.175719249186;4908.558229759339;3696.1868388954385;3783.659470647655;3847.96152038476;3858.9421158682644;3917.745346552522;1147.6270212181075;1169.8888605582456;1170.0464121740754;1177.6879127031343;1182.9940850295693;742.7548908860064;847.9194476524721;890.5814267294361;902.9232515236279;945.8297506448768;5674.574406919498;5740.253767010262;5920.585559010914;5968.701564921824;6163.167972323679;897.2463130969886;917.3303488453466;928.4077806561779;959.4770849886828;961.427950369529;3310.354480876206;3617.94573081404;3624.7462677612316;3963.8216280267457;4072.511298644182;10337.74155646115;10683.09193718527;10691.037806597444;10696.251262411557;10704.411257380918;10574.2598018138;10673.679789606324;10679.199060070527;10682.1988350874;10694;7502.887456688154;8045.316148127357;8118.188181181953;8167.305779008732;8183.754487365389;5561.277033985556;5724.141378793214;5773.624714393551;5837.883242335045;5921.822345329647;9789.85315220258;9977.102060814272;10032.097111260022;10045.69862904114;10127.442990635585;9808.901910981085;10003.949980250052;10117.342372745748;10170.694879153638;10171;6463.15978922737;6672.332766723289;6948.235694073749;7056.294370562902;7069.469789765685;7636.541807491568;8121.878171827546;8236.711715090183;8380.329573634217;8392.118827523267;7294.161171465349;7388.335049845539;7396.223396543501;7492.288232617892;7565.167831538416;7271.563706177646;7271.927280727125;7305.086864141982;7338.119425668954;7387.556746595222;6825.52221344521;6835.760748373783;6888.758893438706;6901.344372284625;6904.240533541407;4900.191468407673;4959.628027897994;5035.723035232951;5047.167217409086;5127.3847138343635;4248.171606536695;4774;4849.6904294203105;5171.663841850268;5210.348706411699 02:35:58 INFO - PROCESS | 1660 | |4;prototype.html;286.46860074561675;295.34138870319185;305.54168746879685;307.97998130121556;310.37614395065924;4412.6541404871505;5341.893162136755;5352.57179425646;5359.820839415323;5398.811041613544;4562.224421848286;5156.994386094713;5185.637005409623;5211;5331.453878375267;3280.901572952809;3382.019214427813;3938.684905207591;4011.4785077939973;4031.778252196128;3026.576279320897;4016.9957510622344;4022.879313620596;4048.4534587830562;4058.533268674111;3275.737940964729;3887.028242939265;3919.510061242345;4001.9399709004388;4011.277969965405;3958.4062390641348;3964.8810535683974;3976.920461590752;3988.5470435682137;4004.2992476316526;2848.430313937221;2863.541833306661;3381.9661803381896;3430.622631510557;3436.8453419595926;3937.4684417603544;4037.9394309085387;4445.53321901199;5163.432022477525;5178.488433109938;4419.690801468574;4525.230710779176;4552.317152427129;4588.311753237042;4603.83886563972;5571.526420254283;5578.776848926038;5586.525145362649;5610.4670056344585;5612.719364031823;3588.784672919633;3857.4792403025795;3860.1893602343544;3863.4784304118866;3983.203359328117;4215.43091682622;5248.606354523426;5550.833474995757;5736.311642602915;5929.14027466015;3647.8477698157685;3664.157243833933;3669.6513831185994;3672.559292884871;3692.8892133236045;3722.571904230994;3895.2793733159456;3907.492026036597;3917.5886531914307;3922.5292964844402;3802;4301.09676967834;4518.548145185455;4532.864014079517;4540.72755634663;5117.8464646060675;5485.790812374726;5537.363203231599;5538.806141785019;5616;1319.221659221064;1347.7196663170027;1348.3797453171455;1354.1942544186193;1356.9009102626903;680.3604611665019;747.9715391336912;781.3202513813019;799.2336935372891;839.3998291221806;3740.756850804689;3998.300297447935;4307.440032795779;4354.412154359121;4365;4567.721038109335;4754.9286760698615;4769.356136921471;4784.832530861403;4784.851635607429;3586.318599466093;3741.4761933329355;4253;4323.502797178302;4335.371371151228;2516.232549072514;2784.359597292634;3013.819170849756;3039.878404863781;3048.0187877879557;4970;5509.614326997152;5968.46283834457;6022.849428764228;6036.517078633787;5386.676799392049;5430.402655707869;5865.677387743715;5875.764969401262;5944.613600115978;5340.679559226459;5965.7628389353995;5984.940150598438;5995.790147344822;6019.548533859978;5207.895842083061;5397.353807088719;5432.266644003009;5595.63628364155;5830.038043722739;4473.865784026484;4773.641976851681;5396.676199428047;5690.520464679131;5742.913856292243;4940.604751619933;5317.314756048171;5576.77692892296;5648.4184428358485;5840.912386314124;3288.9013329599193;3615.891523254307;3627.927441451209;3741.914844695008;3752;4985.0029994000615;5414.485623865727;5575.303087114111;5677.77288908439;5690.857728556819;5330.120530112488;5418.674879507243;5507.421720719267;5523.530499907552;5549;5250.396204436386;5291.285676433632;5500.697461639648;5539.418361072029;5543.473370029841;5428.402875683592;5435.972820135874;5437.308532276548;5437.4018857926385;5477;5316.660669305493;5468.535174510212;5524.585656075891;5558.638688485235;5566.749496272597;5368.3557973041925;5375.586502570817;5397.568194544348;5408.242846001484;5422.48486393792 02:35:58 INFO - PROCESS | 1660 | |5;yui.html;692.6536731634183;706.3219309462916;749.8687729647312;772.2779201446648;778.9610519474024;15280.159591222478;15973.361065557392;15979.680406391864;15997.360105595777;16013.919930400361;14415.06302090366;14547.418103275857;14549.418023279057;14558.854411455908;15013.699726005474;690.1993687322706;712.0600806934848;846.3863698818369;853.7694822397933;869.3697069624503;660.6036378173105;862.8144948835999;869.0657543141149;871.6556960000796;874.0167311774254;931.1991687149081;931.7297983584753;932.39394393644;932.9346945713803;933.7712260496188;734.3611058379191;917.8256131335091;921.5346250143668;922.3036607361432;925.4077390470104;563.338077758636;565.4486875296594;565.9575531835088;670.7048898484651;681.4412182010753;14680.632984175481;14683.55949321522;14735.336909838976;14737.410503579844;14737.557873263819;13701.109427887155;14131.505397311148;14202.786958195635;14238.572842814732;14288.57134285973;12862.614121576367;12907.67730806728;13020.414081366267;13057.477700891954;13121.278329691862;4353.064091220371;4355.281378572532;4439.178751930871;4535.795889184995;4550.294704320836;95370.56944145843;98125;116768;118051;120524;10939.617113400993;11076.833847492295;11368.94315528417;11445.141614378956;11549.711257218636;21867.562648747255;21867.890660546596;21887.890560547414;21909.452263693536;21912.603546450326;7862.253085956825;8184.345252379795;8191.631376588068;8262.049864265686;8278.048024477142;4282.593153650399;4460.375547423363;4465.888352791206;4594.793234304464;4636.9536304637195;3550.1479644885044;3662.414013757786;3701.574318953301;3702.6667599916136;3707.017640325316;654.4404534123352;664.8398544539809;676.5264314979534;676.908617336663;688.3873352716087;576.6828244465527;631.7978246960928;653.8300041989118;664.1731044849193;667.3860048755157;7979.48133371329;8100.918990810134;9194.080591940752;9303.720888373224;9401.24790016797;647.9449246813979;649.1658219188415;670.9597424154566;674.8076798112514;677.9423748981293;3829.195868867569;3941.8249263338184;3968.7420317679257;3990.2817492850986;4113.85601503946;123022.76977230293;124477;127169;136861;171260;76310;111965;115608;117656.41171794086;118503.40748296204;101523;103955.99999999849;103987;105377;105471.94528054775;9479.952600237231;9691.563879625575;9883.406995580288;9893.752656183653;9914;48235;58640.827183456946;58871.70564147152;61202.69398652978;62069.75860482855;70143;80299;84036.15963840528;88939.11060889308;89768.65347019669;12827.230366178059;13072.346382680638;13162.47350105985;13607.86392136066;13678.316084195949;14709.852901470847;14746.196043388856;14797.408103675732;14919.179445130621;15058.623534411288;13643.522476713268;14081.788773168615;14091;14165.716685666022;14175.64560885986;9531.571079301586;13866.4453421862;13882.791758123434;14031.859681403055;14129.6467588307;13578.38897249646;13579.456821727415;13636.996796211202;13947.721045578828;14161.858381416054;11334.60328888485;11445.381330200687;11487.13846461502;11507.539698411969;11745.236559623598;9489.335746497678;11167.82737812518;11171.608993685182;11440.084793216356;11617.6514704559 02:35:58 INFO - PROCESS | 1660 | __end_tp_report 02:35:58 INFO - PROCESS | 1660 | __start_cc_report 02:35:58 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1240 02:35:58 INFO - PROCESS | 1660 | __end_cc_report 02:35:58 INFO - PROCESS | 1660 | __startTimestamp1472549758494__endTimestamp 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | ------- Summary: start ------- 02:35:58 INFO - PROCESS | 1660 | Number of tests: 6 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5628.61 Median:5769.05 stddev:656.48 (11.4%) stddev-sans-first:632.18 02:35:58 INFO - PROCESS | 1660 | Values: 3282.9 3294.0 3334.7 3372.6 3399.6 4327.5 5509.3 5561.9 5631.4 5648.8 5296.4 5649.6 5686.4 5691.5 5728.4 5033.8 5081.2 5118.7 5122.8 5298.8 5198.6 5220.6 5253.6 5254.5 5285.3 5056.5 5095.2 5115.5 5121.6 5199.0 3968.4 5114.3 5120.3 5131.4 5178.5 5027.5 5118.4 5144.0 5166.3 5173.2 5524.1 5552.1 5645.5 5648.4 5697.2 6176.9 6183.6 6210.3 6220.3 6221.4 4893.9 6227.3 6256.4 6270.4 6301.5 5581.3 5649.7 5690.4 5767.7 5769.0 6279.5 6285.3 6289.4 6290.5 6308.2 6082.3 6099.1 6111.1 6121.8 6138.1 5973.7 6028.8 6136.3 6255.5 6273.7 4707.0 6104.2 6112.3 6148.6 6210.6 6178.5 6188.6 6192.3 6200.1 6208.5 4826.7 4961.7 4969.9 4979.8 5004.7 4318.1 4453.5 4469.1 4473.1 4480.7 5576.7 5588.0 5588.1 5594.3 5594.5 6106.4 6128.4 6133.2 6135.3 6136.5 5533.7 5547.0 5561.2 5602.2 5664.8 5352.0 5365.9 5369.2 5374.3 5379.3 5241.7 6234.2 6341.9 6350.4 6355.5 5108.7 6131.5 6170.4 6255.4 6270.2 6272.9 6287.3 6287.5 6299.9 6306.9 5120.3 6030.5 6124.4 6145.2 6147.6 6102.8 6106.5 6150.6 6230.9 6243.4 4976.0 5965.6 5986.6 6003.7 6068.9 5804.1 5942.3 5974.5 5975.5 5990.1 5694.4 5732.7 5774.3 5791.5 5805.0 5964.4 5981.6 6003.0 6009.7 6034.1 5811.9 5838.9 5857.8 5859.6 5865.1 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:67729.45 Median:41422.00 stddev:145514.58 (351.3%) stddev-sans-first:145835.21 02:35:58 INFO - PROCESS | 1660 | Values: 488.9 505.4 509.1 522.1 529.7 26496.5 32472.7 42667.1 42688.0 44509.1 43945.6 44293.3 44298.8 44367.8 44409.3 9774.9 9950.0 9967.1 9976.0 9994.2 9666.7 9779.3 9797.4 9812.1 9818.6 9815.4 9835.0 9856.1 9874.1 9876.1 9754.0 9794.9 9795.8 9805.8 9814.0 9192.8 9890.2 9892.6 9896.0 9896.3 41562.0 41929.6 41953.4 42069.9 42150.4 3356.7 3380.5 3399.8 3404.4 3419.1 31370.0 31738.8 31770.5 31922.7 32214.4 3175.3 3274.2 3284.5 3296.5 3353.9 437077.0 439716.0 439768.0 443414.0 443590.0 61872.7 62335.0 62347.7 62458.0 62469.1 34741.5 35316.8 35327.0 65339.0 65508.0 16442.8 16552.3 16562.7 16576.7 16592.8 12618.2 12754.5 12757.9 12794.0 12796.0 16849.7 19095.8 19540.1 19544.4 19580.3 6775.4 8011.4 8097.0 8196.1 8199.9 838.6 859.8 958.6 1068.0 1115.1 29211.3 29819.6 29829.3 30629.8 30701.0 815.2 1033.4 1108.7 1119.2 1196.1 14348.7 16557.9 16674.3 16995.7 17036.6 640011.0 641934.2 642800.0 645209.0 647639.0 59666.4 60061.0 60088.1 60234.4 60289.7 1231.7 1275.4 1350.3 1358.5 1377.7 30981.2 31280.7 31402.8 31695.8 32264.8 43781.8 51856.2 52610.5 52665.0 52693.0 527303.7 531717.0 533537.0 533723.0 533963.0 26791.9 26893.6 27461.0 27475.5 28436.9 47504.5 48393.0 48426.0 48634.0 49129.0 37819.0 38641.2 39553.4 39937.4 41282.0 39274.8 39314.6 39617.0 40302.4 41004.6 38211.0 38966.6 39153.8 39470.0 39553.4 28173.7 31291.8 33368.7 34375.3 34926.5 24839.6 25497.6 25699.5 26026.5 26063.1 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34059.11 Median:24830.07 stddev:95323.95 (383.9%) stddev-sans-first:95557.70 02:35:58 INFO - PROCESS | 1660 | Values: 326.2 353.7 380.3 383.3 406.3 15754.6 16854.9 20578.8 21155.7 21205.8 20648.8 20890.8 20983.6 21181.6 21649.6 5890.1 5937.8 6052.6 6056.0 6120.9 6036.3 6122.5 6134.1 6139.2 6140.6 4182.3 5190.7 6738.6 6771.9 6831.1 6472.3 6648.3 6757.1 6761.8 6768.1 5811.1 5813.0 5817.4 5820.1 5829.0 23405.5 26254.6 26851.5 27277.6 27707.0 2271.4 2294.0 2305.7 2335.8 2707.9 15020.8 15247.4 15265.9 16024.5 16051.0 1772.9 1786.6 1816.5 1847.5 1884.9 103724.0 116705.0 119573.4 120296.0 120589.8 8811.4 9313.7 10589.7 11046.7 11270.8 14021.7 14050.3 14074.6 14076.2 14087.2 8356.6 8640.0 8646.6 8792.6 8823.0 8644.2 8657.7 8662.4 8853.5 8875.1 6591.9 6732.9 6759.1 6781.5 6887.5 1647.3 1760.9 1839.7 1842.8 1854.1 886.5 906.2 928.8 940.4 1005.1 21087.5 22417.0 22509.2 22534.4 22547.9 955.9 974.9 976.1 978.0 988.7 10245.1 11516.9 11712.2 11963.6 11970.4 556383.5 567392.0 570337.0 584410.2 585371.0 79481.0 115405.4 119741.0 121494.8 123710.4 865.5 983.8 1009.4 1026.9 1028.8 19213.8 19972.9 19983.9 19991.4 20018.5 47275.8 49385.0 49834.8 50348.7 50709.7 74453.0 75374.0 75570.6 76181.9 77229.2 7717.3 7933.0 7965.2 8398.2 8405.9 13406.9 13496.6 13534.5 13810.9 13899.7 11604.2 11678.5 11746.8 11853.9 11944.6 10593.7 10710.5 11518.9 11623.1 11694.9 11674.8 11912.0 11989.8 11991.3 12024.7 7113.6 8033.6 8112.9 8232.9 8246.5 10454.8 11753.2 11835.0 11928.3 11965.5 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5602.10 Median:4934.09 stddev:3143.29 (63.7%) stddev-sans-first:3135.91 02:35:58 INFO - PROCESS | 1660 | Values: 1356.8 1467.8 1486.6 1699.2 1907.5 7762.8 8549.0 8775.2 8786.1 8917.7 8695.5 8742.0 8762.4 8764.4 8776.0 2374.3 2400.7 2401.4 2403.0 2405.2 2019.3 2349.1 2367.6 2395.4 2397.5 2292.2 2337.5 2361.4 2366.9 2376.1 2035.4 2065.0 2273.2 2317.8 2335.3 2337.6 2339.5 2340.1 2345.6 2345.9 8658.8 8688.4 8749.5 8775.2 8781.7 5184.7 5380.6 5384.7 5387.2 5404.6 3497.5 3522.3 3585.0 3586.5 3589.4 1089.8 1100.2 1104.1 1123.5 1127.8 10170.5 11403.9 11464.2 11563.9 11574.8 3930.5 4334.3 4423.9 4569.0 4604.4 9048.9 9051.5 9061.5 9062.7 9090.1 5201.5 5211.3 5214.5 5226.7 5232.1 4362.1 4413.5 4522.6 4710.2 4908.6 3696.2 3783.7 3848.0 3858.9 3917.7 1147.6 1169.9 1170.0 1177.7 1183.0 742.8 847.9 890.6 902.9 945.8 5674.6 5740.3 5920.6 5968.7 6163.2 897.2 917.3 928.4 959.5 961.4 3310.4 3617.9 3624.7 3963.8 4072.5 10337.7 10683.1 10691.0 10696.3 10704.4 10574.3 10673.7 10679.2 10682.2 10694.0 7502.9 8045.3 8118.2 8167.3 8183.8 5561.3 5724.1 5773.6 5837.9 5921.8 9789.9 9977.1 10032.1 10045.7 10127.4 9808.9 10003.9 10117.3 10170.7 10171.0 6463.2 6672.3 6948.2 7056.3 7069.5 7636.5 8121.9 8236.7 8380.3 8392.1 7294.2 7388.3 7396.2 7492.3 7565.2 7271.6 7271.9 7305.1 7338.1 7387.6 6825.5 6835.8 6888.8 6901.3 6904.2 4900.2 4959.6 5035.7 5047.2 5127.4 4248.2 4774.0 4849.7 5171.7 5210.3 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4387.84 Median:4779.24 stddev:1383.52 (28.9%) stddev-sans-first:1352.72 02:35:58 INFO - PROCESS | 1660 | Values: 286.5 295.3 305.5 308.0 310.4 4412.7 5341.9 5352.6 5359.8 5398.8 4562.2 5157.0 5185.6 5211.0 5331.5 3280.9 3382.0 3938.7 4011.5 4031.8 3026.6 4017.0 4022.9 4048.5 4058.5 3275.7 3887.0 3919.5 4001.9 4011.3 3958.4 3964.9 3976.9 3988.5 4004.3 2848.4 2863.5 3382.0 3430.6 3436.8 3937.5 4037.9 4445.5 5163.4 5178.5 4419.7 4525.2 4552.3 4588.3 4603.8 5571.5 5578.8 5586.5 5610.5 5612.7 3588.8 3857.5 3860.2 3863.5 3983.2 4215.4 5248.6 5550.8 5736.3 5929.1 3647.8 3664.2 3669.7 3672.6 3692.9 3722.6 3895.3 3907.5 3917.6 3922.5 3802.0 4301.1 4518.5 4532.9 4540.7 5117.8 5485.8 5537.4 5538.8 5616.0 1319.2 1347.7 1348.4 1354.2 1356.9 680.4 748.0 781.3 799.2 839.4 3740.8 3998.3 4307.4 4354.4 4365.0 4567.7 4754.9 4769.4 4784.8 4784.9 3586.3 3741.5 4253.0 4323.5 4335.4 2516.2 2784.4 3013.8 3039.9 3048.0 4970.0 5509.6 5968.5 6022.8 6036.5 5386.7 5430.4 5865.7 5875.8 5944.6 5340.7 5965.8 5984.9 5995.8 6019.5 5207.9 5397.4 5432.3 5595.6 5830.0 4473.9 4773.6 5396.7 5690.5 5742.9 4940.6 5317.3 5576.8 5648.4 5840.9 3288.9 3615.9 3627.9 3741.9 3752.0 4985.0 5414.5 5575.3 5677.8 5690.9 5330.1 5418.7 5507.4 5523.5 5549.0 5250.4 5291.3 5500.7 5539.4 5543.5 5428.4 5436.0 5437.3 5437.4 5477.0 5316.7 5468.5 5524.6 5558.6 5566.7 5368.4 5375.6 5397.6 5408.2 5422.5 02:35:58 INFO - PROCESS | 1660 | 02:35:58 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:23495.68 Median:3885.51 stddev:36403.74 (936.9%) stddev-sans-first:36465.60 02:35:58 INFO - PROCESS | 1660 | Values: 692.7 706.3 749.9 772.3 779.0 15280.2 15973.4 15979.7 15997.4 16013.9 14415.1 14547.4 14549.4 14558.9 15013.7 690.2 712.1 846.4 853.8 869.4 660.6 862.8 869.1 871.7 874.0 931.2 931.7 932.4 932.9 933.8 734.4 917.8 921.5 922.3 925.4 563.3 565.4 566.0 670.7 681.4 14680.6 14683.6 14735.3 14737.4 14737.6 13701.1 14131.5 14202.8 14238.6 14288.6 12862.6 12907.7 13020.4 13057.5 13121.3 4353.1 4355.3 4439.2 4535.8 4550.3 95370.6 98125.0 116768.0 118051.0 120524.0 10939.6 11076.8 11368.9 11445.1 11549.7 21867.6 21867.9 21887.9 21909.5 21912.6 7862.3 8184.3 8191.6 8262.0 8278.0 4282.6 4460.4 4465.9 4594.8 4637.0 3550.1 3662.4 3701.6 3702.7 3707.0 654.4 664.8 676.5 676.9 688.4 576.7 631.8 653.8 664.2 667.4 7979.5 8100.9 9194.1 9303.7 9401.2 647.9 649.2 671.0 674.8 677.9 3829.2 3941.8 3968.7 3990.3 4113.9 123022.8 124477.0 127169.0 136861.0 171260.0 76310.0 111965.0 115608.0 117656.4 118503.4 101523.0 103956.0 103987.0 105377.0 105471.9 9480.0 9691.6 9883.4 9893.8 9914.0 48235.0 58640.8 58871.7 61202.7 62069.8 70143.0 80299.0 84036.2 88939.1 89768.7 12827.2 13072.3 13162.5 13607.9 13678.3 14709.9 14746.2 14797.4 14919.2 15058.6 13643.5 14081.8 14091.0 14165.7 14175.6 9531.6 13866.4 13882.8 14031.9 14129.6 13578.4 13579.5 13637.0 13947.7 14161.9 11334.6 11445.4 11487.1 11507.5 11745.2 9489.3 11167.8 11171.6 11440.1 11617.7 02:35:58 INFO - PROCESS | 1660 | -------- Summary: end -------- 02:35:58 INFO - PROCESS | 1660 | 02:35:59 INFO - TEST-INFO | 1660: exit 0 02:35:59 INFO - TEST-OK | dromaeo_css | took 1116359ms 02:35:59 INFO - TEST-START | kraken 02:35:59 INFO - operating with platform_type : mac_ 02:35:59 INFO - Initialising browser for kraken test... 02:35:59 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpYLNlTf/profile http://localhost:49263/getInfo.html) 02:36:01 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 02:36:01 INFO - PROCESS | 1667 | colorDepth:24 02:36:01 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 02:36:01 INFO - PROCESS | 1667 | __metrics 02:36:01 INFO - PROCESS | 1667 | 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" 02:36:01 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:36:02 INFO - TEST-INFO | 1667: exit 0 02:36:02 INFO - Browser initialized. 02:36:02 INFO - Running cycle 1/1 for kraken test... 02:36:02 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/T/tmpYLNlTf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:36:14 INFO - PROCESS | 1668 | RSS: Main: 244576256 02:36:14 INFO - PROCESS | 1668 | 02:36:16 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection) 02:36:17 INFO - PROCESS | 1668 | RSS: Main: 294318080 02:36:17 INFO - PROCESS | 1668 | 02:36:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-dft) 02:36:20 INFO - PROCESS | 1668 | RSS: Main: 289288192 02:36:20 INFO - PROCESS | 1668 | 02:36:24 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-fft) 02:36:24 INFO - PROCESS | 1668 | RSS: Main: 377798656 02:36:24 INFO - PROCESS | 1668 | 02:36:27 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator) 02:36:27 INFO - PROCESS | 1668 | RSS: Main: 279408640 02:36:27 INFO - PROCESS | 1668 | 02:36:29 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:36:30 INFO - PROCESS | 1668 | RSS: Main: 282951680 02:36:30 INFO - PROCESS | 1668 | 02:36:33 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom) 02:36:33 INFO - PROCESS | 1668 | RSS: Main: 321486848 02:36:33 INFO - PROCESS | 1668 | 02:36:36 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate) 02:36:37 INFO - PROCESS | 1668 | RSS: Main: 323420160 02:36:37 INFO - PROCESS | 1668 | 02:36:40 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial) 02:36:40 INFO - PROCESS | 1668 | RSS: Main: 322203648 02:36:40 INFO - PROCESS | 1668 | 02:36:42 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:36:42 INFO - PROCESS | 1668 | RSS: Main: 298762240 02:36:42 INFO - PROCESS | 1668 | 02:36:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:36:45 INFO - PROCESS | 1668 | RSS: Main: 305815552 02:36:45 INFO - PROCESS | 1668 | 02:36:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:36:48 INFO - PROCESS | 1668 | RSS: Main: 308367360 02:36:48 INFO - PROCESS | 1668 | 02:36:51 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:36:51 INFO - PROCESS | 1668 | RSS: Main: 321703936 02:36:51 INFO - PROCESS | 1668 | 02:36:54 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:36:55 INFO - PROCESS | 1668 | RSS: Main: 317726720 02:36:55 INFO - PROCESS | 1668 | 02:36:56 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49263/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49263/tests/kraken/driver.html?testName=ai-astar) 02:36:57 INFO - PROCESS | 1668 | RSS: Main: 360730624 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | __start_tp_report 02:36:57 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 02:36:57 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 02:36:57 INFO - PROCESS | 1668 | |i|pagename|runs| 02:36:57 INFO - PROCESS | 1668 | |0;ai-astar;111;113;113;114;113;110;125;113;113;114 02:36:57 INFO - PROCESS | 1668 | |1;audio-beat-detection;146;110;131;180;109;128;130;107;158;107 02:36:57 INFO - PROCESS | 1668 | |2;audio-dft;184;183;185;179;185;182;183;180;182;182 02:36:57 INFO - PROCESS | 1668 | |3;audio-fft;76;75;77;152;58;60;81;91;161;58 02:36:57 INFO - PROCESS | 1668 | |4;audio-oscillator;81;82;134;69;76;80;114;67;69;116 02:36:57 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;86;84;86;83;86;84;86;83;87;83 02:36:57 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;95;95;95;95;94;96;95;93;94 02:36:57 INFO - PROCESS | 1668 | |7;imaging-desaturate;86;87;83;84;95;142;141;83;83;84 02:36:57 INFO - PROCESS | 1668 | |8;json-parse-financial;54;54;54;55;54;54;54;54;53;55 02:36:57 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;68;68;76;77;74;71;73;74;69;72 02:36:57 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;87;88;86;87;86;87;86;90;90;86 02:36:57 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;155;157;137;138;139;138;143;127;181;139 02:36:57 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;172;170;169;167;168;166;169;166;169;169 02:36:57 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;68;68;67;68;69;66;68;67;67;67 02:36:57 INFO - PROCESS | 1668 | __end_tp_report 02:36:57 INFO - PROCESS | 1668 | __start_cc_report 02:36:57 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2033 02:36:57 INFO - PROCESS | 1668 | __end_cc_report 02:36:57 INFO - PROCESS | 1668 | __startTimestamp1472549817196__endTimestamp 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | ------- Summary: start ------- 02:36:57 INFO - PROCESS | 1668 | Number of tests: 14 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.90 Median:113.00 stddev:4.09 (3.6%) stddev-sans-first:4.21 02:36:57 INFO - PROCESS | 1668 | Values: 111.0 113.0 113.0 114.0 113.0 110.0 125.0 113.0 113.0 114.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:130.60 Median:130.50 stddev:24.59 (18.8%) stddev-sans-first:25.44 02:36:57 INFO - PROCESS | 1668 | Values: 146.0 110.0 131.0 180.0 109.0 128.0 130.0 107.0 158.0 107.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:182.50 Median:183.00 stddev:1.96 (1.1%) stddev-sans-first:2.00 02:36:57 INFO - PROCESS | 1668 | Values: 184.0 183.0 185.0 179.0 185.0 182.0 183.0 180.0 182.0 182.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:88.90 Median:75.50 stddev:37.26 (49.3%) stddev-sans-first:39.22 02:36:57 INFO - PROCESS | 1668 | Values: 76.0 75.0 77.0 152.0 58.0 60.0 81.0 91.0 161.0 58.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:88.80 Median:72.50 stddev:23.63 (32.6%) stddev-sans-first:24.89 02:36:57 INFO - PROCESS | 1668 | Values: 81.0 82.0 134.0 69.0 76.0 80.0 114.0 67.0 69.0 116.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:84.80 Median:86.00 stddev:1.55 (1.8%) stddev-sans-first:1.58 02:36:57 INFO - PROCESS | 1668 | Values: 86.0 84.0 86.0 83.0 86.0 84.0 86.0 83.0 87.0 83.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:94.70 Median:95.00 stddev:0.82 (0.9%) stddev-sans-first:0.87 02:36:57 INFO - PROCESS | 1668 | Values: 95.0 95.0 95.0 95.0 95.0 94.0 96.0 95.0 93.0 94.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:96.80 Median:84.00 stddev:23.83 (28.4%) stddev-sans-first:24.95 02:36:57 INFO - PROCESS | 1668 | Values: 86.0 87.0 83.0 84.0 95.0 142.0 141.0 83.0 83.0 84.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:54.10 Median:54.00 stddev:0.57 (1.1%) stddev-sans-first:0.60 02:36:57 INFO - PROCESS | 1668 | Values: 54.0 54.0 54.0 55.0 54.0 54.0 54.0 54.0 53.0 55.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:72.20 Median:73.50 stddev:3.19 (4.3%) stddev-sans-first:3.00 02:36:57 INFO - PROCESS | 1668 | Values: 68.0 68.0 76.0 77.0 74.0 71.0 73.0 74.0 69.0 72.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:87.00 stddev:1.57 (1.8%) stddev-sans-first:1.66 02:36:57 INFO - PROCESS | 1668 | Values: 87.0 88.0 86.0 87.0 86.0 87.0 86.0 90.0 90.0 86.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:145.40 Median:141.00 stddev:15.28 (10.8%) stddev-sans-first:15.80 02:36:57 INFO - PROCESS | 1668 | Values: 155.0 157.0 137.0 138.0 139.0 138.0 143.0 127.0 181.0 139.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.50 Median:169.00 stddev:1.84 (1.1%) stddev-sans-first:1.45 02:36:57 INFO - PROCESS | 1668 | Values: 172.0 170.0 169.0 167.0 168.0 166.0 169.0 166.0 169.0 169.0 02:36:57 INFO - PROCESS | 1668 | 02:36:57 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.50 Median:68.00 stddev:0.85 (1.2%) stddev-sans-first:0.88 02:36:57 INFO - PROCESS | 1668 | Values: 68.0 68.0 67.0 68.0 69.0 66.0 68.0 67.0 67.0 67.0 02:36:57 INFO - PROCESS | 1668 | -------- Summary: end -------- 02:36:57 INFO - PROCESS | 1668 | 02:36:58 INFO - TEST-INFO | 1668: exit 0 02:36:58 INFO - TEST-OK | kraken | took 58929ms 02:36:58 INFO - SUITE-END | took 1175s 02:36:58 INFO - Completed test suite (00:19:35) 02:36:58 INFO - javascript benchmark 02:36:58 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3282.9343413131737, 3293.9505907411376, 3334.6994672077894, 3372.5952885653724, 3399.6260411354747, 4327.459067616548, 5509.283793106901, 5561.91657125143, 5631.352394474631, 5648.802291919778, 5296.443873393296, 5649.604527683064, 5686.402927692605, 5691.544676425887, 5728.398518155585, 5033.798648054092, 5081.237814327844, 5118.692878427288, 5122.8207012754565, 5298.841034768963, 5198.636095473318, 5220.556252718524, 5253.553447956928, 5254.500822421883, 5285.339332583427, 5056.519630635084, 5095.159298715745, 5115.488451154892, 5121.641485096045, 5199.0, 3968.444417781513, 5114.309568208281, 5120.257562653432, 5131.358580177478, 5178.4562620924635, 5027.522385373383, 5118.385793704742, 5144.048351055029, 5166.302549155854, 5173.17229243323, 5524.088525393306, 5552.139418390156, 5645.463680950304, 5648.378678345379, 5697.173909783059, 6176.938230617681, 6183.567150299481, 6210.347913469066, 6220.253569571681, 6221.440070393641, 4893.8776530586665, 6227.314995350508, 6256.374362563753, 6270.372962703693, 6301.52738544611, 5581.3302403711, 5649.717514124277, 5690.402507736628, 5767.741999930081, 5769.048107062372, 6279.529035322369, 6285.277193122758, 6289.371062893765, 6290.4653104485715, 6308.179936608211, 6082.2701275847185, 6099.054646529795, 6111.052786818051, 6121.785737499166, 6138.14066030756, 5973.671448070398, 6028.758849646053, 6136.294326152461, 6255.46828519581, 6273.65494897785, 4706.976465117653, 6104.216637118914, 6112.327643959161, 6148.569600128038, 6210.627362358183, 6178.474829639531, 6188.597741146811, 6192.287886892975, 6200.131981522591, 6208.50331973441, 4826.734529600905, 4961.6526843121355, 4969.925451118236, 4979.825706100269, 5004.749762511859, 4318.093200427946, 4453.465584129925, 4469.10617876426, 4473.105378924228, 4480.686351955332, 5576.7211639417865, 5588.0, 5588.077967135378, 5594.300712410949, 5594.468525490072, 6106.39999602577, 6128.411459112376, 6133.172021777003, 6135.324535308531, 6136.4578081083455, 5533.667979921218, 5546.972265138648, 5561.221428999943, 5602.215689803431, 5664.801731939362, 5352.036633405946, 5365.892682146336, 5369.167778994262, 5374.274472946102, 5379.273798037293, 5241.711705856214, 6234.189555357774, 6341.873162536724, 6350.396712312323, 6355.459785918156, 5108.7445627717725, 6131.529590167291, 6170.413810687977, 6255.40573645503, 6270.184875966095, 6272.874542509216, 6287.30839607652, 6287.528435367274, 6299.937000629935, 6306.936930630635, 5120.278330019881, 6030.547708921761, 6124.38756124384, 6145.201123854049, 6147.600405973598, 6102.761931713857, 6106.46066718015, 6150.630962142286, 6230.937690623217, 6243.43809057187, 4976.0, 5965.627905581654, 5986.55100867428, 6003.699815009145, 6068.8786224276155, 5804.1293805929445, 5942.257217847769, 5974.522038236843, 5975.521958243243, 5990.071538911476, 5694.430556944272, 5732.656040637575, 5774.2782152230975, 5791.478766910998, 5805.0, 5964.433378829004, 5981.641101533922, 6002.969985150046, 6009.669468179117, 6034.09488576717, 5811.883762324645, 5838.854028649148, 5857.824265272048, 5859.589828711949, 5865.090910908816], "value": 5589.732939702812, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [488.94132704075514, 505.44401158725407, 509.09889495592824, 522.0993785719634, 529.6848375216746, 26496.470070598578, 32472.67527324729, 42667.14665706688, 42688.0, 44509.109817803626, 43945.560544394546, 44293.33559996603, 44298.778506107425, 44367.77816110915, 44409.333859992126, 9774.902250977488, 9949.95025024874, 9967.10296073357, 9975.950120249388, 9994.150497207746, 9666.709998700051, 9779.266555008368, 9797.412155270671, 9812.067853553901, 9818.607255709763, 9815.362001469917, 9834.9508252459, 9856.063673950963, 9874.1113299803, 9876.061774131445, 9754.0, 9794.853077203848, 9795.804083918352, 9805.80388392228, 9813.950930245339, 9192.816143677155, 9890.20878329739, 9892.604295828158, 9896.0, 9896.307258491908, 41562.0, 41929.58070419318, 41953.37069943953, 42069.94825129366, 42150.367744483854, 3356.7482438817187, 3380.5267262583257, 3399.83000849959, 3404.3872103021445, 3419.128122328815, 31370.0, 31738.841305793554, 31770.523442148387, 31922.6807731922, 32214.355712885845, 3175.3173067790535, 3274.2469232076755, 3284.490903909898, 3296.47256438971, 3353.9161520961925, 437077.0, 439716.0, 439768.0, 443414.0, 443590.0, 61872.690636546526, 62335.0, 62347.688261559306, 62458.0, 62469.06296405467, 34741.47887781635, 35316.823415882754, 35327.0, 65339.0, 65508.0, 16442.83557164437, 16552.255148518103, 16562.668746625, 16576.6684666306, 16592.751108733377, 12618.17981831178, 12754.48982040708, 12757.936210318889, 12794.0, 12796.0, 16849.747253791204, 19095.80817732098, 19540.120978801304, 19544.357332375606, 19580.314688986102, 6775.356341147682, 8011.439199255996, 8096.959515202387, 8196.098429172785, 8199.877001844978, 838.5513750143643, 859.7549698335944, 958.6021800952526, 1068.0, 1115.1413411672966, 29211.26971825679, 29819.552706709415, 29829.254268643457, 30629.84685076605, 30701.0, 815.229608020415, 1033.4395063454238, 1108.6563165418745, 1119.2165484160957, 1196.1208511743862, 14348.713025739427, 16557.91721041387, 16674.33302667904, 16995.66008679844, 17036.57408564771, 640011.0, 641934.1775695855, 642800.0, 645209.0, 647639.0, 59666.40333596608, 60060.99999999913, 60088.098678519855, 60234.39765602376, 60289.69855150696, 1231.6982339326921, 1275.387813849339, 1350.2978451205317, 1358.517726207202, 1377.7313423882247, 30981.225469363446, 31280.68719312778, 31402.842985784926, 31695.841520793172, 32264.83867580647, 43781.78109109434, 51856.22215666844, 52610.47389526209, 52665.0, 52693.0, 527303.7269627255, 531717.0, 533537.0, 533723.0, 533963.0, 26791.866040669673, 26893.596596051466, 27461.03886363968, 27475.450490990468, 28436.881361355154, 47504.524954751396, 48393.03213935631, 48426.0, 48634.0, 49129.0, 37819.0, 38641.227175456894, 39553.406698900115, 39937.40093898536, 41282.0, 39274.80362598169, 39314.606853931095, 39617.0, 40302.39546406865, 41004.58995410127, 38211.0, 38966.6103338963, 39153.804230979804, 39470.01324966784, 39553.406698898965, 28173.71826281711, 31291.84354078215, 33368.66631333656, 34375.31249375048, 34926.47610285797, 24839.633820140065, 25497.61753573735, 25699.486010279317, 26026.479470410864, 26063.050948752127], "value": 20006.92831340599, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [326.2203334031664, 353.6622525488159, 380.2622911551591, 383.29856362855975, 406.32144318987287, 15754.606134846634, 16854.91572542137, 20578.794212057874, 21155.68266476004, 21205.787942120594, 20648.793512064876, 20890.791092089075, 20983.580328393462, 21181.623194492346, 21649.56700865982, 5890.057590785475, 5937.762489500415, 6052.617125281495, 6056.0, 6120.938790612081, 6036.305824830157, 6122.510199184055, 6134.110553969672, 6139.2325959255095, 6140.570160088796, 4182.309918863384, 5190.740462976855, 6738.561993470409, 6771.8984215236815, 6831.146106736658, 6472.320406357312, 6648.301928297507, 6757.054012438262, 6761.7633382831855, 6768.086308348384, 5811.128330750379, 5813.0, 5817.447342502455, 5820.1269809528485, 5829.0, 23405.53188936212, 26254.6061809073, 26851.462970740475, 27277.590836137475, 27707.0, 2271.4434963434064, 2293.9541209175723, 2305.654151877215, 2335.7547457517057, 2707.91876243713, 15020.849791502163, 15247.390104395921, 15265.931384803069, 16024.519264422086, 16050.956687815255, 1772.858171346302, 1786.59951220479, 1816.5142163059888, 1847.4827048426462, 1884.9350117183885, 103724.0, 116705.0, 119573.40213299052, 120296.0, 120589.79410205873, 8811.427257228259, 9313.674021409219, 10589.735256618646, 11046.668599942015, 11270.773459952517, 14021.719565608631, 14050.297485125908, 14074.648133796736, 14076.225807580682, 14087.15477071379, 8356.623951922056, 8640.0, 8646.567671616394, 8792.648294068165, 8823.0, 8644.178803013703, 8657.740267791976, 8662.350323725746, 8853.51305678194, 8875.112488751203, 6591.89059500955, 6732.865342693119, 6759.121314229119, 6781.4574834013165, 6887.483438742114, 1647.3328302037694, 1760.8818400315893, 1839.6872531669649, 1842.824931631493, 1854.1007611308505, 886.5079880666163, 906.1889608800087, 928.8003079337975, 940.4216406909838, 1005.0954141272913, 21087.472813179793, 22417.0, 22509.212177573707, 22534.436639083826, 22547.88726056359, 955.9139677428926, 974.9171320437842, 976.0873583199589, 977.9902200978041, 988.7478692933256, 10245.129164020993, 11516.884831151749, 11712.238704484182, 11963.581274655346, 11970.40147992597, 556383.4520684915, 567392.0, 570337.0, 584410.1558984441, 585371.0, 79481.0, 115405.4229728846, 119741.0, 121494.77578363316, 123710.38144809219, 865.4547634990042, 983.8081574093127, 1009.3943633819649, 1026.9332493387906, 1028.8456731490337, 19213.807861921203, 19972.90013549923, 19983.900080499505, 19991.40025799228, 20018.499537511678, 47275.818104546284, 49385.012299754526, 49834.75082624709, 50348.741281468254, 50709.74645126751, 74453.0, 75374.0, 75570.62214688891, 76181.85727214206, 77229.22770772221, 7717.2668596483245, 7932.960340154023, 7965.203479651988, 8398.244158025807, 8405.915940840514, 13406.93296533511, 13496.595102146952, 13534.526291579747, 13810.861891380957, 13899.652508687364, 11604.245724027911, 11678.532858685558, 11746.76506469883, 11853.940730296294, 11944.581939632419, 10593.735156621146, 10710.464476776286, 11518.884811152117, 11623.070154387799, 11694.88305116972, 11674.774148714603, 11912.0, 11989.82015269789, 11991.280523168638, 12024.699382515508, 7113.6443177839865, 8033.598320084089, 8112.878306825284, 8232.876506852283, 8246.505209687439, 10454.843177352193, 11753.177277590486, 11834.988052031347, 11928.284302941851, 11965.461554229907], "value": 9857.837379972034, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1356.7829147336415, 1467.8018467506886, 1486.620911667525, 1699.1843914920837, 1907.4563749331444, 7762.805929851749, 8549.0, 8775.166359195866, 8786.12138786121, 8917.732468025953, 8695.521746303952, 8742.038375778659, 8762.430442021265, 8764.386492945481, 8776.0, 2374.3233178544074, 2400.747921468238, 2401.399650087478, 2403.038784486202, 2405.194259922919, 2019.2629690163058, 2349.130821596012, 2367.562001029815, 2395.4490467192554, 2397.496525729599, 2292.1748170658548, 2337.4857531343077, 2361.409647588103, 2366.9171578994738, 2376.1327115602767, 2035.4300795777206, 2064.9984757392654, 2273.158931195468, 2317.8145748340094, 2335.2877372401413, 2337.637666161748, 2339.4619237575453, 2340.1458467659354, 2345.5543446745064, 2345.8944347504234, 8658.783530411727, 8688.435251708683, 8749.518776467292, 8775.210231079234, 8781.692640757543, 5184.7407629618365, 5380.623356365017, 5384.67691938481, 5387.218853266255, 5404.5946554008215, 3497.45789402643, 3522.3131489359585, 3584.982075089608, 3586.4620306953907, 3589.353916295066, 1089.7548051688386, 1100.2023532938636, 1104.127739086121, 1123.5112725964232, 1127.7518945831903, 10170.542325595366, 11403.942980285046, 11464.19750617457, 11563.942180289128, 11574.842515748442, 3930.4890364252465, 4334.263175260214, 4423.933640995388, 4569.017661202857, 4604.355390245369, 9048.864267036, 9051.502167380857, 9061.501617410973, 9062.682806101755, 9090.136437038604, 5201.505856943584, 5211.296474975829, 5214.504622060898, 5226.660267082628, 5232.136697444879, 4362.149380870763, 4413.514513403523, 4522.615577675934, 4710.175719249186, 4908.558229759339, 3696.1868388954385, 3783.659470647655, 3847.96152038476, 3858.9421158682644, 3917.745346552522, 1147.6270212181075, 1169.8888605582456, 1170.0464121740754, 1177.6879127031343, 1182.9940850295693, 742.7548908860064, 847.9194476524721, 890.5814267294361, 902.9232515236279, 945.8297506448768, 5674.574406919498, 5740.253767010262, 5920.585559010914, 5968.701564921824, 6163.167972323679, 897.2463130969886, 917.3303488453466, 928.4077806561779, 959.4770849886828, 961.427950369529, 3310.354480876206, 3617.94573081404, 3624.7462677612316, 3963.8216280267457, 4072.511298644182, 10337.74155646115, 10683.09193718527, 10691.037806597444, 10696.251262411557, 10704.411257380918, 10574.2598018138, 10673.679789606324, 10679.199060070527, 10682.1988350874, 10694.0, 7502.887456688154, 8045.316148127357, 8118.188181181953, 8167.305779008732, 8183.754487365389, 5561.277033985556, 5724.141378793214, 5773.624714393551, 5837.883242335045, 5921.822345329647, 9789.85315220258, 9977.102060814272, 10032.097111260022, 10045.69862904114, 10127.442990635585, 9808.901910981085, 10003.949980250052, 10117.342372745748, 10170.694879153638, 10171.0, 6463.15978922737, 6672.332766723289, 6948.235694073749, 7056.294370562902, 7069.469789765685, 7636.541807491568, 8121.878171827546, 8236.711715090183, 8380.329573634217, 8392.118827523267, 7294.161171465349, 7388.335049845539, 7396.223396543501, 7492.288232617892, 7565.167831538416, 7271.563706177646, 7271.927280727125, 7305.086864141982, 7338.119425668954, 7387.556746595222, 6825.52221344521, 6835.760748373783, 6888.758893438706, 6901.344372284625, 6904.240533541407, 4900.191468407673, 4959.628027897994, 5035.723035232951, 5047.167217409086, 5127.3847138343635, 4248.171606536695, 4774.0, 4849.6904294203105, 5171.663841850268, 5210.348706411699], "value": 4500.2291746194105, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [286.46860074561675, 295.34138870319185, 305.54168746879685, 307.97998130121556, 310.37614395065924, 4412.6541404871505, 5341.893162136755, 5352.57179425646, 5359.820839415323, 5398.811041613544, 4562.224421848286, 5156.994386094713, 5185.637005409623, 5211.0, 5331.453878375267, 3280.901572952809, 3382.019214427813, 3938.684905207591, 4011.4785077939973, 4031.778252196128, 3026.576279320897, 4016.9957510622344, 4022.879313620596, 4048.4534587830562, 4058.533268674111, 3275.737940964729, 3887.028242939265, 3919.510061242345, 4001.9399709004388, 4011.277969965405, 3958.4062390641348, 3964.8810535683974, 3976.920461590752, 3988.5470435682137, 4004.2992476316526, 2848.430313937221, 2863.541833306661, 3381.9661803381896, 3430.622631510557, 3436.8453419595926, 3937.4684417603544, 4037.9394309085387, 4445.53321901199, 5163.432022477525, 5178.488433109938, 4419.690801468574, 4525.230710779176, 4552.317152427129, 4588.311753237042, 4603.83886563972, 5571.526420254283, 5578.776848926038, 5586.525145362649, 5610.4670056344585, 5612.719364031823, 3588.784672919633, 3857.4792403025795, 3860.1893602343544, 3863.4784304118866, 3983.203359328117, 4215.43091682622, 5248.606354523426, 5550.833474995757, 5736.311642602915, 5929.14027466015, 3647.8477698157685, 3664.157243833933, 3669.6513831185994, 3672.559292884871, 3692.8892133236045, 3722.571904230994, 3895.2793733159456, 3907.492026036597, 3917.5886531914307, 3922.5292964844402, 3802.0, 4301.09676967834, 4518.548145185455, 4532.864014079517, 4540.72755634663, 5117.8464646060675, 5485.790812374726, 5537.363203231599, 5538.806141785019, 5616.0, 1319.221659221064, 1347.7196663170027, 1348.3797453171455, 1354.1942544186193, 1356.9009102626903, 680.3604611665019, 747.9715391336912, 781.3202513813019, 799.2336935372891, 839.3998291221806, 3740.756850804689, 3998.300297447935, 4307.440032795779, 4354.412154359121, 4365.0, 4567.721038109335, 4754.9286760698615, 4769.356136921471, 4784.832530861403, 4784.851635607429, 3586.318599466093, 3741.4761933329355, 4253.0, 4323.502797178302, 4335.371371151228, 2516.232549072514, 2784.359597292634, 3013.819170849756, 3039.878404863781, 3048.0187877879557, 4970.0, 5509.614326997152, 5968.46283834457, 6022.849428764228, 6036.517078633787, 5386.676799392049, 5430.402655707869, 5865.677387743715, 5875.764969401262, 5944.613600115978, 5340.679559226459, 5965.7628389353995, 5984.940150598438, 5995.790147344822, 6019.548533859978, 5207.895842083061, 5397.353807088719, 5432.266644003009, 5595.63628364155, 5830.038043722739, 4473.865784026484, 4773.641976851681, 5396.676199428047, 5690.520464679131, 5742.913856292243, 4940.604751619933, 5317.314756048171, 5576.77692892296, 5648.4184428358485, 5840.912386314124, 3288.9013329599193, 3615.891523254307, 3627.927441451209, 3741.914844695008, 3752.0, 4985.0029994000615, 5414.485623865727, 5575.303087114111, 5677.77288908439, 5690.857728556819, 5330.120530112488, 5418.674879507243, 5507.421720719267, 5523.530499907552, 5549.0, 5250.396204436386, 5291.285676433632, 5500.697461639648, 5539.418361072029, 5543.473370029841, 5428.402875683592, 5435.972820135874, 5437.308532276548, 5437.4018857926385, 5477.0, 5316.660669305493, 5468.535174510212, 5524.585656075891, 5558.638688485235, 5566.749496272597, 5368.3557973041925, 5375.586502570817, 5397.568194544348, 5408.242846001484, 5422.48486393792], "value": 3947.101651341734, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [692.6536731634183, 706.3219309462916, 749.8687729647312, 772.2779201446648, 778.9610519474024, 15280.159591222478, 15973.361065557392, 15979.680406391864, 15997.360105595777, 16013.919930400361, 14415.06302090366, 14547.418103275857, 14549.418023279057, 14558.854411455908, 15013.699726005474, 690.1993687322706, 712.0600806934848, 846.3863698818369, 853.7694822397933, 869.3697069624503, 660.6036378173105, 862.8144948835999, 869.0657543141149, 871.6556960000796, 874.0167311774254, 931.1991687149081, 931.7297983584753, 932.39394393644, 932.9346945713803, 933.7712260496188, 734.3611058379191, 917.8256131335091, 921.5346250143668, 922.3036607361432, 925.4077390470104, 563.338077758636, 565.4486875296594, 565.9575531835088, 670.7048898484651, 681.4412182010753, 14680.632984175481, 14683.55949321522, 14735.336909838976, 14737.410503579844, 14737.557873263819, 13701.109427887155, 14131.505397311148, 14202.786958195635, 14238.572842814732, 14288.57134285973, 12862.614121576367, 12907.67730806728, 13020.414081366267, 13057.477700891954, 13121.278329691862, 4353.064091220371, 4355.281378572532, 4439.178751930871, 4535.795889184995, 4550.294704320836, 95370.56944145843, 98125.0, 116768.0, 118051.0, 120524.0, 10939.617113400993, 11076.833847492295, 11368.94315528417, 11445.141614378956, 11549.711257218636, 21867.562648747255, 21867.890660546596, 21887.890560547414, 21909.452263693536, 21912.603546450326, 7862.253085956825, 8184.345252379795, 8191.631376588068, 8262.049864265686, 8278.048024477142, 4282.593153650399, 4460.375547423363, 4465.888352791206, 4594.793234304464, 4636.9536304637195, 3550.1479644885044, 3662.414013757786, 3701.574318953301, 3702.6667599916136, 3707.017640325316, 654.4404534123352, 664.8398544539809, 676.5264314979534, 676.908617336663, 688.3873352716087, 576.6828244465527, 631.7978246960928, 653.8300041989118, 664.1731044849193, 667.3860048755157, 7979.48133371329, 8100.918990810134, 9194.080591940752, 9303.720888373224, 9401.24790016797, 647.9449246813979, 649.1658219188415, 670.9597424154566, 674.8076798112514, 677.9423748981293, 3829.195868867569, 3941.8249263338184, 3968.7420317679257, 3990.2817492850986, 4113.85601503946, 123022.76977230293, 124477.0, 127169.0, 136861.0, 171260.0, 76310.0, 111965.0, 115608.0, 117656.41171794086, 118503.40748296204, 101523.0, 103955.99999999849, 103987.0, 105377.0, 105471.94528054775, 9479.952600237231, 9691.563879625575, 9883.406995580288, 9893.752656183653, 9914.0, 48235.0, 58640.827183456946, 58871.70564147152, 61202.69398652978, 62069.75860482855, 70143.0, 80299.0, 84036.15963840528, 88939.11060889308, 89768.65347019669, 12827.230366178059, 13072.346382680638, 13162.47350105985, 13607.86392136066, 13678.316084195949, 14709.852901470847, 14746.196043388856, 14797.408103675732, 14919.179445130621, 15058.623534411288, 13643.522476713268, 14081.788773168615, 14091.0, 14165.716685666022, 14175.64560885986, 9531.571079301586, 13866.4453421862, 13882.791758123434, 14031.859681403055, 14129.6467588307, 13578.38897249646, 13579.456821727415, 13636.996796211202, 13947.721045578828, 14161.858381416054, 11334.60328888485, 11445.381330200687, 11487.13846461502, 11507.539698411969, 11745.236559623598, 9489.335746497678, 11167.82737812518, 11171.608993685182, 11440.084793216356, 11617.6514704559], "value": 7767.0948297450805, "unit": "score"}], "name": "dromaeo_css", "value": 7306.268920012132, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 113.0, 114.0, 113.0, 110.0, 125.0, 113.0, 113.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 110.0, 131.0, 180.0, 109.0, 128.0, 130.0, 107.0, 158.0, 107.0], "value": 130.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 185.0, 179.0, 185.0, 182.0, 183.0, 180.0, 182.0, 182.0], "value": 182.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 75.0, 77.0, 152.0, 58.0, 60.0, 81.0, 91.0, 161.0, 58.0], "value": 88.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 134.0, 69.0, 76.0, 80.0, 114.0, 67.0, 69.0, 116.0], "value": 88.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 86.0, 83.0, 86.0, 84.0, 86.0, 83.0, 87.0, 83.0], "value": 84.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 93.0, 94.0], "value": 94.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 87.0, 83.0, 84.0, 95.0, 142.0, 141.0, 83.0, 83.0, 84.0], "value": 96.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 54.0, 54.0, 55.0, 54.0, 54.0, 54.0, 54.0, 53.0, 55.0], "value": 54.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 76.0, 77.0, 74.0, 71.0, 73.0, 74.0, 69.0, 72.0], "value": 72.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 88.0, 86.0, 87.0, 86.0, 87.0, 86.0, 90.0, 90.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 157.0, 137.0, 138.0, 139.0, 138.0, 143.0, 127.0, 181.0, 139.0], "value": 145.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 170.0, 169.0, 167.0, 168.0, 166.0, 169.0, 166.0, 169.0, 169.0], "value": 168.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 67.0, 68.0, 69.0, 66.0, 68.0, 67.0, 67.0, 67.0], "value": 67.5, "unit": "score"}], "name": "kraken", "value": 1476.0, "alertThreshold": 2.0}]} 02:36:58 INFO - Return code: 0 02:36:58 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:36:58 INFO - # TBPL SUCCESS # 02:36:58 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:36:58 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:36:58 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:36:58 INFO - 2016-08-30 02:36:58.520 screenresolution[1672:10752] starting screenresolution argv=screenresolution get 02:36:58 INFO - 2016-08-30 02:36:58.523 screenresolution[1672:10752] Display 0: 1600x1200x32@60 02:36:58 INFO - 2016-08-30 02:36:58.529 screenresolution[1673:10756] starting screenresolution argv=screenresolution list 02:36:58 INFO - Available Modes on Display 0 02:36:58 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:36:58 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:36:58 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:36:58 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:36:58 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:36:58 INFO - Intel Iris: 02:36:58 INFO - Chipset Model: Intel Iris 02:36:58 INFO - Type: GPU 02:36:58 INFO - Bus: Built-In 02:36:58 INFO - VRAM (Dynamic, Max): 1536 MB 02:36:58 INFO - Vendor: Intel (0x8086) 02:36:58 INFO - Device ID: 0x0a2e 02:36:58 INFO - Revision ID: 0x0009 02:36:58 INFO - Displays: 02:36:58 INFO - Display: 02:36:58 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:36:58 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:36:58 INFO - Display Serial Number: 1600x1200 60 02:36:58 INFO - Main Display: Yes 02:36:58 INFO - Mirror: Off 02:36:58 INFO - Online: Yes 02:36:58 INFO - Rotation: Supported 02:36:58 INFO - Return code: 0 02:36:58 INFO - Running post-action listener: _resource_record_post_action 02:36:58 INFO - [mozharness: 2016-08-30 09:36:58.765705Z] Finished run-tests step (success) 02:36:58 INFO - Running post-run listener: _resource_record_post_run 02:36:58 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 32013312; Write bytes: 706818048; Read time: 200; Write time: 1992 02:36:58 INFO - TinderboxPrint: CPU usage
53.6% 02:36:58 INFO - TinderboxPrint: I/O read bytes / time
32,013,312 / 200 02:36:58 INFO - TinderboxPrint: I/O write bytes / time
706,818,048 / 1,992 02:36:58 INFO - TinderboxPrint: CPU idle
2,246.7 (46.4%) 02:36:58 INFO - TinderboxPrint: CPU system
970.6 (20.0%) 02:36:58 INFO - TinderboxPrint: CPU user
1,624.4 (33.6%) 02:36:58 INFO - TinderboxPrint: Swap in / out
434,778,112 / 0 02:36:58 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398795264; Write bytes: 393680896; Read time: 29427; Write time: 819 02:36:58 INFO - run-tests - Wall time: 1177s; CPU: 54.0%; Read bytes: 30039040; Write bytes: 296425472; Read time: 182; Write time: 1130 02:36:59 INFO - Running post-run listener: _upload_blobber_files 02:36:59 INFO - Blob upload gear active. 02:36:59 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:36:59 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 02:36:59 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:36:59 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:36:59 INFO - (blobuploader) - INFO - Open directory for files ... 02:36:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:36:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:36:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:37:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:00 INFO - (blobuploader) - INFO - Done attempting. 02:37:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:37:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:37:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:01 INFO - (blobuploader) - INFO - Done attempting. 02:37:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:37:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:37:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:37:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:37:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:37:03 INFO - (blobuploader) - INFO - Done attempting. 02:37:03 INFO - (blobuploader) - INFO - Iteration through files over. 02:37:03 INFO - Return code: 0 02:37:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:37:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:37:03 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1bacc5feaa9c21f080fa169a51deccb97838be1c8e058fad97e341ed306abbf79910417b8ab0a73fb7b0c15eda60d59f2de6efdcfae9106fe45410a53fd4f0a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8523b4fc32788c3b780f178edc98bb4f96e4d9df7f75d4e92d0cc4c873707a2c39deca424112b2ebf7001c1573f6747fdb3de4f10e4b6c2d045961c392395c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe66528b38eb5b8e82b68c722f812faf6df83354da719180b9ba9e562363744813ebeb517e04987b78f5f5e2744894f253bda8938a08c9885203c5b64e5d21b9"} 02:37:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:37:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:37:03 INFO - Contents: 02:37:03 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1bacc5feaa9c21f080fa169a51deccb97838be1c8e058fad97e341ed306abbf79910417b8ab0a73fb7b0c15eda60d59f2de6efdcfae9106fe45410a53fd4f0a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8523b4fc32788c3b780f178edc98bb4f96e4d9df7f75d4e92d0cc4c873707a2c39deca424112b2ebf7001c1573f6747fdb3de4f10e4b6c2d045961c392395c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe66528b38eb5b8e82b68c722f812faf6df83354da719180b9ba9e562363744813ebeb517e04987b78f5f5e2744894f253bda8938a08c9885203c5b64e5d21b9"} 02:37:03 INFO - Running post-run listener: copy_logs_to_upload_dir 02:37:03 INFO - Copying logs to upload dir... 02:37:03 INFO - mkdir: /builds/slave/test/build/upload/logs 02:37:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.145072 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-08-30 02:37:04.034140) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 02:37:04.038094) ========= 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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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/Larch/sha512/1bacc5feaa9c21f080fa169a51deccb97838be1c8e058fad97e341ed306abbf79910417b8ab0a73fb7b0c15eda60d59f2de6efdcfae9106fe45410a53fd4f0a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8523b4fc32788c3b780f178edc98bb4f96e4d9df7f75d4e92d0cc4c873707a2c39deca424112b2ebf7001c1573f6747fdb3de4f10e4b6c2d045961c392395c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe66528b38eb5b8e82b68c722f812faf6df83354da719180b9ba9e562363744813ebeb517e04987b78f5f5e2744894f253bda8938a08c9885203c5b64e5d21b9"} build_url:https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011079 build_url: 'https://queue.taskcluster.net/v1/task/MI_O0FAVSlGvuOAq9ZDLkg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/1bacc5feaa9c21f080fa169a51deccb97838be1c8e058fad97e341ed306abbf79910417b8ab0a73fb7b0c15eda60d59f2de6efdcfae9106fe45410a53fd4f0a1", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/8523b4fc32788c3b780f178edc98bb4f96e4d9df7f75d4e92d0cc4c873707a2c39deca424112b2ebf7001c1573f6747fdb3de4f10e4b6c2d045961c392395c19", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/fe66528b38eb5b8e82b68c722f812faf6df83354da719180b9ba9e562363744813ebeb517e04987b78f5f5e2744894f253bda8938a08c9885203c5b64e5d21b9"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-30 02:37:04.076049) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:37:04.076816) ========= 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.dKbGCQ3pBL/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.3F4z1RLEJA/Listeners TMPDIR=/var/folders/hr/wh__d5vx6m195v9p5jynqcc000000w/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.005140 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-30 02:37:04.129285) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 02:37:04.129968) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-30 02:37:04.130672) ========= ========= Total master_lag: 0.28 =========