builder: mozilla-central_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0123 starttime: 1471971432.54 results: success (0) buildid: 20160823072522 builduid: 9837df00c76740ac84c846a1b727c993 revision: 052656fc513c05da969590ac5934abd67271a897 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.544286) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.544787) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.545108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.572055) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.572481) ========= 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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-23 09:57:12-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 67.7M=0s 2016-08-23 09:57:12 (67.7 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097744 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.694707) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.695110) ========= 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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.031923 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:12.749435) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-23 09:57:12.749890) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 052656fc513c05da969590ac5934abd67271a897 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 052656fc513c05da969590ac5934abd67271a897 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-23 09:57:12,823 truncating revision to first 12 chars 2016-08-23 09:57:12,823 Setting DEBUG logging. 2016-08-23 09:57:12,823 attempt 1/10 2016-08-23 09:57:12,823 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/052656fc513c?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-23 09:57:15,125 unpacking tar archive at: mozilla-central-052656fc513c/testing/mozharness/ program finished with exit code 0 elapsedTime=2.571633 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2016-08-23 09:57:15.345932) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:15.346255) ========= script_repo_revision: 052656fc513c05da969590ac5934abd67271a897 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:15.346664) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:15.346929) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-23 09:57:15.367837) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-23 09:57:15.368351) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:15 INFO - MultiFileLogger online at 20160823 09:57:15 in /builds/slave/test 09:57:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 09:57:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:57:15 INFO - {'append_to_log': False, 09:57:15 INFO - 'base_work_dir': '/builds/slave/test', 09:57:15 INFO - 'blob_upload_branch': 'Firefox', 09:57:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:57:15 INFO - 'branch': 'Firefox', 09:57:15 INFO - 'buildbot_json_path': 'buildprops.json', 09:57:15 INFO - 'config_files': ('talos/mac_config.py',), 09:57:15 INFO - 'default_actions': ('clobber', 09:57:15 INFO - 'read-buildbot-config', 09:57:15 INFO - 'download-and-extract', 09:57:15 INFO - 'populate-webroot', 09:57:15 INFO - 'create-virtualenv', 09:57:15 INFO - 'install', 09:57:15 INFO - 'run-tests'), 09:57:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:57:15 INFO - 'download_minidump_stackwalk': True, 09:57:15 INFO - 'download_symbols': 'ondemand', 09:57:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:57:15 INFO - 'tooltool.py': '/tools/tooltool.py', 09:57:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:57:15 INFO - '/tools/misc-python/virtualenv.py')}, 09:57:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:57:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:57:15 INFO - 'installer_path': 'installer.exe', 09:57:15 INFO - 'log_level': 'info', 09:57:15 INFO - 'log_name': 'talos', 09:57:15 INFO - 'log_to_console': True, 09:57:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:57:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:57:15 INFO - 'opt_config_files': (), 09:57:15 INFO - 'pip_index': False, 09:57:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:57:15 INFO - 'cmd': ('bash', 09:57:15 INFO - '-c', 09:57:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:57:15 INFO - 'enabled': True, 09:57:15 INFO - 'halt_on_failure': False, 09:57:15 INFO - 'name': 'check_screen_resolution'},), 09:57:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:57:15 INFO - 'cmd': ('bash', 09:57:15 INFO - '-c', 09:57:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:57:15 INFO - 'enabled': True, 09:57:15 INFO - 'halt_on_failure': False, 09:57:15 INFO - 'name': 'check_screen_resolution'},), 09:57:15 INFO - 'run_cmd_checks_enabled': True, 09:57:15 INFO - 'sps_profile': False, 09:57:15 INFO - 'sps_profile_interval': 0, 09:57:15 INFO - 'suite': 'dromaeojs', 09:57:15 INFO - 'system_bits': '32', 09:57:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:57:15 INFO - 'title': 't-yosemite-r7-0123', 09:57:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:57:15 INFO - 'use_talos_json': True, 09:57:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:57:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:57:15 INFO - 'work_dir': 'build'} 09:57:15 INFO - [mozharness: 2016-08-23 16:57:15.580224Z] Running clobber step. 09:57:15 INFO - Running pre-action listener: _resource_record_pre_action 09:57:15 INFO - Running main action method: clobber 09:57:15 INFO - rmtree: /builds/slave/test/build 09:57:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:57:17 INFO - Running post-action listener: _resource_record_post_action 09:57:17 INFO - [mozharness: 2016-08-23 16:57:17.729394Z] Finished clobber step (success) 09:57:17 INFO - [mozharness: 2016-08-23 16:57:17.729511Z] Running read-buildbot-config step. 09:57:17 INFO - Running pre-action listener: _resource_record_pre_action 09:57:17 INFO - Running main action method: read_buildbot_config 09:57:17 INFO - Using buildbot properties: 09:57:17 INFO - { 09:57:17 INFO - "project": "", 09:57:17 INFO - "product": "firefox", 09:57:17 INFO - "script_repo_revision": "production", 09:57:17 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs", 09:57:17 INFO - "repository": "", 09:57:17 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs", 09:57:17 INFO - "buildid": "20160823072522", 09:57:17 INFO - "stage_platform": "macosx64", 09:57:17 INFO - "basedir": "/builds/slave/test", 09:57:17 INFO - "nightly_build": "True", 09:57:17 INFO - "buildnumber": 29, 09:57:17 INFO - "slavename": "t-yosemite-r7-0123", 09:57:17 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:57:17 INFO - "platform": "yosemite_r7", 09:57:17 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 09:57:17 INFO - "branch": "mozilla-central", 09:57:17 INFO - "repo_path": "mozilla-central", 09:57:17 INFO - "revision": "052656fc513c05da969590ac5934abd67271a897", 09:57:17 INFO - "pgo_build": "False", 09:57:17 INFO - "builduid": "9837df00c76740ac84c846a1b727c993", 09:57:17 INFO - "slavebuilddir": "test" 09:57:17 INFO - } 09:57:17 INFO - Found installer url https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:57:17 INFO - Running post-action listener: _resource_record_post_action 09:57:17 INFO - [mozharness: 2016-08-23 16:57:17.732460Z] Finished read-buildbot-config step (success) 09:57:17 INFO - [mozharness: 2016-08-23 16:57:17.732555Z] Running download-and-extract step. 09:57:17 INFO - Running pre-action listener: _resource_record_pre_action 09:57:17 INFO - Running main action method: download_and_extract 09:57:17 INFO - mkdir: /builds/slave/test/build/tests 09:57:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:17 INFO - trying https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:57:17 INFO - Downloading https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:19 INFO - Downloaded 1199 bytes. 09:57:19 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:57:19 INFO - Using the following test package requirements: 09:57:19 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:57:19 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:57:19 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:57:19 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'jsshell-mac.zip'], 09:57:19 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:57:19 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:57:19 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:57:19 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:57:19 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:57:19 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:57:19 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:57:19 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:57: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')]} 09:57:19 INFO - trying https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:57:19 INFO - Downloading https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:19 INFO - Downloaded 16346271 bytes. 09:57:19 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:57:26 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 09:57:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:26 INFO - trying https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:57:26 INFO - Downloading https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:27 INFO - Downloaded 16346271 bytes. 09:57:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 09:57:34 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')]} 09:57:34 INFO - trying https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:57:34 INFO - Downloading https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:36 INFO - Downloaded 14413981 bytes. 09:57:36 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 09:57:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:36 INFO - trying https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:57:36 INFO - Downloading https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:57:36 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/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 09:57:39 INFO - Downloaded 111643592 bytes. 09:57:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:57:39 INFO - mkdir: /builds/slave/test/properties 09:57:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:57:39 INFO - Writing to file /builds/slave/test/properties/build_url 09:57:39 INFO - Contents: 09:57:39 INFO - build_url:https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:57:39 INFO - Running post-action listener: _resource_record_post_action 09:57:39 INFO - Running post-action listener: set_extra_try_arguments 09:57:39 INFO - [mozharness: 2016-08-23 16:57:39.875164Z] Finished download-and-extract step (success) 09:57:39 INFO - [mozharness: 2016-08-23 16:57:39.875319Z] Running populate-webroot step. 09:57:39 INFO - Running pre-action listener: _resource_record_pre_action 09:57:39 INFO - Running main action method: populate_webroot 09:57:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:57:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:57:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:57:39 INFO - '--tppagecycles', 09:57:39 INFO - '7'], 09:57:39 INFO - 'tests': ['tsvgm']}}, 09:57:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:57:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:57:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:57:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:57:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:57:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['damp', 'tps']}, 09:57:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'tests': ['damp', 'tps']}, 09:57:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['dromaeo_dom']}, 09:57:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:57:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['basic_compositor_video']}, 09:57:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:57:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'other-e10s': {'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['a11yr', 09:57:39 INFO - 'ts_paint', 09:57:39 INFO - 'tpaint', 09:57:39 INFO - 'sessionrestore', 09:57:39 INFO - 'sessionrestore_no_auto_restore', 09:57:39 INFO - 'tabpaint']}, 09:57:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['tsvgx', 09:57:39 INFO - 'tsvgr_opacity', 09:57:39 INFO - 'tart', 09:57:39 INFO - 'tscrollx', 09:57:39 INFO - 'cart']}, 09:57:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:57:39 INFO - 'tsvgr_opacity', 09:57:39 INFO - 'tart', 09:57:39 INFO - 'tscrollx', 09:57:39 INFO - 'cart']}, 09:57:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'talos_options': ['--disable-e10s'], 09:57:39 INFO - 'tests': ['tp5o']}, 09:57:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'tests': ['tp5o']}, 09:57:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'talos_options': ['--disable-e10s', 09:57:39 INFO - '--xperf_path', 09:57:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:39 INFO - 'tests': ['tp5n']}, 09:57:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:57:39 INFO - 'talos_options': ['--xperf_path', 09:57:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:57:39 INFO - 'tests': ['tp5n']}}, 09:57:39 INFO - 'talos.zip': {'path': '', 09:57:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:57:39 INFO - Running post-action listener: _resource_record_post_action 09:57:39 INFO - [mozharness: 2016-08-23 16:57:39.894681Z] Finished populate-webroot step (success) 09:57:39 INFO - [mozharness: 2016-08-23 16:57:39.894798Z] Running create-virtualenv step. 09:57:39 INFO - Running pre-action listener: _resource_record_pre_action 09:57:39 INFO - Running main action method: create_virtualenv 09:57:39 INFO - Creating virtualenv /builds/slave/test/build/venv 09:57:39 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 09:57:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:57:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:57:40 INFO - Using real prefix '/tools/python27' 09:57:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:57:40 INFO - Installing distribute.............................................................................................................................................................................................done. 09:57:43 INFO - Installing pip.................done. 09:57:43 INFO - Return code: 0 09:57:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:57:43 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')]} 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:43 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')]} 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:43 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:43 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 09:57:43 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 09:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:43 INFO - 'HOME': '/Users/cltbld', 09:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:43 INFO - 'LOGNAME': 'cltbld', 09:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:43 INFO - 'MOZ_NO_REMOTE': '1', 09:57:43 INFO - 'NO_EM_RESTART': '1', 09:57:43 INFO - 'PAGER': '/bin/cat', 09:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:43 INFO - 'PWD': '/builds/slave/test', 09:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:43 INFO - 'SHELL': '/bin/bash', 09:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:43 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:43 INFO - 'USER': 'cltbld', 09:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:43 INFO - 'XPC_FLAGS': '0x0', 09:57:43 INFO - 'XPC_SERVICE_NAME': '0', 09:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:43 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 09:57:43 INFO - Cleaning up... 09:57:43 INFO - Return code: 0 09:57:43 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:57:43 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')]} 09:57:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:43 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')]} 09:57:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:43 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:43 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 09:57:43 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 09:57:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:43 INFO - 'HOME': '/Users/cltbld', 09:57:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:43 INFO - 'LOGNAME': 'cltbld', 09:57:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:43 INFO - 'MOZ_NO_REMOTE': '1', 09:57:43 INFO - 'NO_EM_RESTART': '1', 09:57:43 INFO - 'PAGER': '/bin/cat', 09:57:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:43 INFO - 'PWD': '/builds/slave/test', 09:57:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:43 INFO - 'SHELL': '/bin/bash', 09:57:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:43 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:43 INFO - 'USER': 'cltbld', 09:57:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:43 INFO - 'XPC_FLAGS': '0x0', 09:57:43 INFO - 'XPC_SERVICE_NAME': '0', 09:57:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:44 INFO - Downloading/unpacking psutil>=3.1.1 09:57:44 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 09:57:44 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 09:57:44 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 09:57:44 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 09:57:44 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 09:57:44 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 09:57:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:57:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:47 INFO - Installing collected packages: psutil 09:57:47 INFO - Running setup.py install for psutil 09:57:47 INFO - building 'psutil._psutil_osx' extension 09:57:47 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 09:57:48 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 09:57:48 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 09:57:48 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 09:57:48 INFO - building 'psutil._psutil_posix' extension 09:57:48 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 09:57:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:57:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:57:48 INFO - ^ 09:57:48 INFO - 1 warning generated. 09:57:48 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 09:57:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:57:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:57:48 INFO - Successfully installed psutil 09:57:48 INFO - Cleaning up... 09:57:48 INFO - Return code: 0 09:57:48 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:57:48 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')]} 09:57:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:48 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')]} 09:57:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:48 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:48 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 09:57:48 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 09:57:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:48 INFO - 'HOME': '/Users/cltbld', 09:57:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:48 INFO - 'LOGNAME': 'cltbld', 09:57:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:48 INFO - 'MOZ_NO_REMOTE': '1', 09:57:48 INFO - 'NO_EM_RESTART': '1', 09:57:48 INFO - 'PAGER': '/bin/cat', 09:57:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:48 INFO - 'PWD': '/builds/slave/test', 09:57:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:48 INFO - 'SHELL': '/bin/bash', 09:57:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:48 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:48 INFO - 'USER': 'cltbld', 09:57:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:48 INFO - 'XPC_FLAGS': '0x0', 09:57:48 INFO - 'XPC_SERVICE_NAME': '0', 09:57:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:57:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:57:51 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:57:51 INFO - Installing collected packages: mozsystemmonitor 09:57:51 INFO - Running setup.py install for mozsystemmonitor 09:57:51 INFO - Successfully installed mozsystemmonitor 09:57:51 INFO - Cleaning up... 09:57:51 INFO - Return code: 0 09:57:51 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:51 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:51 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:57:51 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:57:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:51 INFO - 'HOME': '/Users/cltbld', 09:57:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:51 INFO - 'LOGNAME': 'cltbld', 09:57:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:51 INFO - 'MOZ_NO_REMOTE': '1', 09:57:51 INFO - 'NO_EM_RESTART': '1', 09:57:51 INFO - 'PAGER': '/bin/cat', 09:57:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:51 INFO - 'PWD': '/builds/slave/test', 09:57:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:51 INFO - 'SHELL': '/bin/bash', 09:57:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:51 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:51 INFO - 'USER': 'cltbld', 09:57:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:51 INFO - 'XPC_FLAGS': '0x0', 09:57:51 INFO - 'XPC_SERVICE_NAME': '0', 09:57:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:52 INFO - Downloading/unpacking jsonschema==2.5.1 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:54 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:57:54 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:57:54 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 09:57:54 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 09:57:54 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 09:57:54 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 09:57:54 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 09:57:54 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 09:57:54 INFO - Downloading functools32-3.2.3-2.tar.gz 09:57:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:57:55 INFO - warning: no files found matching '*.txt' 09:57:55 INFO - no previously-included directories found matching 'build' 09:57:55 INFO - no previously-included directories found matching 'dist' 09:57:55 INFO - no previously-included directories found matching '.git*' 09:57:55 INFO - Installing collected packages: jsonschema, functools32 09:57:55 INFO - Running setup.py install for functools32 09:57:55 INFO - warning: no files found matching '*.txt' 09:57:55 INFO - no previously-included directories found matching 'build' 09:57:55 INFO - no previously-included directories found matching 'dist' 09:57:55 INFO - no previously-included directories found matching '.git*' 09:57:55 INFO - Successfully installed jsonschema functools32 09:57:55 INFO - Cleaning up... 09:57:55 INFO - Return code: 0 09:57:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:55 INFO - 'HOME': '/Users/cltbld', 09:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:55 INFO - 'LOGNAME': 'cltbld', 09:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:55 INFO - 'MOZ_NO_REMOTE': '1', 09:57:55 INFO - 'NO_EM_RESTART': '1', 09:57:55 INFO - 'PAGER': '/bin/cat', 09:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:55 INFO - 'PWD': '/builds/slave/test', 09:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:55 INFO - 'SHELL': '/bin/bash', 09:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:55 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:55 INFO - 'USER': 'cltbld', 09:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:55 INFO - 'XPC_FLAGS': '0x0', 09:57:55 INFO - 'XPC_SERVICE_NAME': '0', 09:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:57:55 INFO - Cleaning up... 09:57:55 INFO - Return code: 0 09:57:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:57:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:57:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:57:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:57:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:57:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:57:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:57:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:57:55 INFO - 'HOME': '/Users/cltbld', 09:57:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:57:55 INFO - 'LOGNAME': 'cltbld', 09:57:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:57:55 INFO - 'MOZ_NO_REMOTE': '1', 09:57:55 INFO - 'NO_EM_RESTART': '1', 09:57:55 INFO - 'PAGER': '/bin/cat', 09:57:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:57:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:57:55 INFO - 'PWD': '/builds/slave/test', 09:57:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:57:55 INFO - 'SHELL': '/bin/bash', 09:57:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:57:55 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:57:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:57:55 INFO - 'USER': 'cltbld', 09:57:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:57:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:57:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:57:55 INFO - 'XPC_FLAGS': '0x0', 09:57:55 INFO - 'XPC_SERVICE_NAME': '0', 09:57:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:57:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:57:55 INFO - Downloading/unpacking blobuploader==1.2.4 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:57:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:57:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:57:58 INFO - Downloading blobuploader-1.2.4.tar.gz 09:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:57:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:57:58 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 09:57:58 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 09:57:58 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 09:57:58 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 09:57:58 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 09:57:58 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 09:57:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:57:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:57:59 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 09:57:59 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 09:57:59 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 09:57:59 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 09:57:59 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 09:57:59 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 09:57:59 INFO - Downloading docopt-0.6.1.tar.gz 09:57:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:57:59 INFO - Installing collected packages: blobuploader, requests, docopt 09:57:59 INFO - Running setup.py install for blobuploader 09:57:59 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:57:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:57:59 INFO - Running setup.py install for requests 09:58:00 INFO - Running setup.py install for docopt 09:58:00 INFO - Successfully installed blobuploader requests docopt 09:58:00 INFO - Cleaning up... 09:58:00 INFO - Return code: 0 09:58:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:00 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:58:00 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 09:58:00 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 09:58:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:58:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:00 INFO - 'HOME': '/Users/cltbld', 09:58:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:00 INFO - 'LOGNAME': 'cltbld', 09:58:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:00 INFO - 'MOZ_NO_REMOTE': '1', 09:58:00 INFO - 'NO_EM_RESTART': '1', 09:58:00 INFO - 'PAGER': '/bin/cat', 09:58:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:00 INFO - 'PWD': '/builds/slave/test', 09:58:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:00 INFO - 'SHELL': '/bin/bash', 09:58:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:58:00 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:58:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:00 INFO - 'USER': 'cltbld', 09:58:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:00 INFO - 'XPC_FLAGS': '0x0', 09:58:00 INFO - 'XPC_SERVICE_NAME': '0', 09:58:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-07NvPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VZpgTp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:00 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-i3T0Fb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Nb2ePk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-6xoCg2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-9OjrYv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-JWrK3C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-CUK96j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-sryz1F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-vbQ1vi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QN8QXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:01 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-du8ADa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-pwwxFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UKxogn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-s7ukUH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1UBqCh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:02 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wA7TOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:02 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:58:02 INFO - Running setup.py install for manifestparser 09:58:02 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:58:02 INFO - Running setup.py install for mozcrash 09:58:02 INFO - Running setup.py install for mozdebug 09:58:02 INFO - Running setup.py install for mozdevice 09:58:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Running setup.py install for mozfile 09:58:03 INFO - Running setup.py install for mozhttpd 09:58:03 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Running setup.py install for mozinfo 09:58:03 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Running setup.py install for mozInstall 09:58:03 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:58:03 INFO - Running setup.py install for mozleak 09:58:03 INFO - Running setup.py install for mozlog 09:58:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Running setup.py install for moznetwork 09:58:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Running setup.py install for mozprocess 09:58:04 INFO - Running setup.py install for mozprofile 09:58:04 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Running setup.py install for mozrunner 09:58:04 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:58:04 INFO - Running setup.py install for mozscreenshot 09:58:04 INFO - Running setup.py install for moztest 09:58:04 INFO - Running setup.py install for mozversion 09:58:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:58:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:58:05 INFO - Cleaning up... 09:58:05 INFO - Return code: 0 09:58:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58:05 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')]} 09:58:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 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')]} 09:58:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:05 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:58:05 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 09:58:05 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 09:58:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:58:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:05 INFO - 'HOME': '/Users/cltbld', 09:58:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:05 INFO - 'LOGNAME': 'cltbld', 09:58:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:05 INFO - 'MOZ_NO_REMOTE': '1', 09:58:05 INFO - 'NO_EM_RESTART': '1', 09:58:05 INFO - 'PAGER': '/bin/cat', 09:58:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:05 INFO - 'PWD': '/builds/slave/test', 09:58:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:05 INFO - 'SHELL': '/bin/bash', 09:58:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:58:05 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:58:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:05 INFO - 'USER': 'cltbld', 09:58:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:05 INFO - 'XPC_FLAGS': '0x0', 09:58:05 INFO - 'XPC_SERVICE_NAME': '0', 09:58:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-I3FbsU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:58:05 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)) 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lGlmpx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:58:05 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)) 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_Lv1Ku-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:58:05 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)) 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-TsWtrv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:58:05 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)) 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-f0RzJi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:58:05 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)) 09:58:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:58:05 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-QuzGmj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-wxvKhK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-OZLsx2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-z71jKJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-3DYsiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-UTk__p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-O3ZBEA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-gx6Juk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-XH2XIe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:58:06 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)) 09:58:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:06 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-lHenby-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:58:07 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)) 09:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:58:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-q1nrjr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:58:07 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)) 09:58:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:58:07 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-_c_Z7g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:58:07 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)) 09:58:07 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)) 09:58:07 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)) 09:58:07 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)) 09:58:07 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)) 09:58:07 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)) 09:58:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:58:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:58:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:58:09 INFO - Downloading blessings-1.6.tar.gz 09:58:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:58:09 INFO - Installing collected packages: blessings 09:58:09 INFO - Running setup.py install for blessings 09:58:09 INFO - Successfully installed blessings 09:58:09 INFO - Cleaning up... 09:58:10 INFO - Return code: 0 09:58:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:58:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:10 INFO - Reading from file tmpfile_stdout 09:58:10 INFO - Current package versions: 09:58:10 INFO - blessings == 1.6 09:58:10 INFO - blobuploader == 1.2.4 09:58:10 INFO - docopt == 0.6.1 09:58:10 INFO - functools32 == 3.2.3-2 09:58:10 INFO - jsonschema == 2.5.1 09:58:10 INFO - manifestparser == 1.1 09:58:10 INFO - mozInstall == 1.12 09:58:10 INFO - mozcrash == 0.17 09:58:10 INFO - mozdebug == 0.1 09:58:10 INFO - mozdevice == 0.48 09:58:10 INFO - mozfile == 1.2 09:58:10 INFO - mozhttpd == 0.7 09:58:10 INFO - mozinfo == 0.9 09:58:10 INFO - mozleak == 0.1 09:58:10 INFO - mozlog == 3.3 09:58:10 INFO - moznetwork == 0.27 09:58:10 INFO - mozprocess == 0.23 09:58:10 INFO - mozprofile == 0.28 09:58:10 INFO - mozrunner == 6.12 09:58:10 INFO - mozscreenshot == 0.1 09:58:10 INFO - mozsystemmonitor == 0.3 09:58:10 INFO - moztest == 0.7 09:58:10 INFO - mozversion == 1.4 09:58:10 INFO - psutil == 3.1.1 09:58:10 INFO - requests == 1.2.3 09:58:10 INFO - wsgiref == 0.1.2 09:58:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:10 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:58:10 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 09:58:10 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 09:58:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:58:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:10 INFO - 'HOME': '/Users/cltbld', 09:58:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:10 INFO - 'LOGNAME': 'cltbld', 09:58:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:10 INFO - 'MOZ_NO_REMOTE': '1', 09:58:10 INFO - 'NO_EM_RESTART': '1', 09:58:10 INFO - 'PAGER': '/bin/cat', 09:58:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:10 INFO - 'PWD': '/builds/slave/test', 09:58:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:10 INFO - 'SHELL': '/bin/bash', 09:58:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:58:10 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:58:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:10 INFO - 'USER': 'cltbld', 09:58:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:10 INFO - 'XPC_FLAGS': '0x0', 09:58:10 INFO - 'XPC_SERVICE_NAME': '0', 09:58:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 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)) 09:58:11 INFO - Cleaning up... 09:58:11 INFO - Return code: 0 09:58:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:58: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')]} 09:58:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:58:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58: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')]} 09:58:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:58:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:58:11 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 0x10914aa30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1091df618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdbad94ef0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, '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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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 09:58:11 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 09:58:11 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 09:58:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:58:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:11 INFO - 'HOME': '/Users/cltbld', 09:58:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:11 INFO - 'LOGNAME': 'cltbld', 09:58:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:11 INFO - 'MOZ_NO_REMOTE': '1', 09:58:11 INFO - 'NO_EM_RESTART': '1', 09:58:11 INFO - 'PAGER': '/bin/cat', 09:58:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:11 INFO - 'PWD': '/builds/slave/test', 09:58:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:11 INFO - 'SHELL': '/bin/bash', 09:58:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:58:11 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:58:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:11 INFO - 'USER': 'cltbld', 09:58:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:11 INFO - 'XPC_FLAGS': '0x0', 09:58:11 INFO - 'XPC_SERVICE_NAME': '0', 09:58:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:58:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:58:11 INFO - Cleaning up... 09:58:11 INFO - Return code: 0 09:58:11 INFO - Running post-action listener: _resource_record_post_action 09:58:11 INFO - Running post-action listener: _start_resource_monitoring 09:58:11 INFO - Starting resource monitoring. 09:58:11 INFO - [mozharness: 2016-08-23 16:58:11.367468Z] Finished create-virtualenv step (success) 09:58:11 INFO - [mozharness: 2016-08-23 16:58:11.368475Z] Running install step. 09:58:11 INFO - Running pre-action listener: _resource_record_pre_action 09:58:11 INFO - Running main action method: install 09:58:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:58:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:58:11 INFO - Reading from file tmpfile_stdout 09:58:11 INFO - Detecting whether we're running mozinstall >=1.0... 09:58:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:58:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:58:11 INFO - Reading from file tmpfile_stdout 09:58:11 INFO - Output received: 09:58:11 INFO - Usage: mozinstall [options] installer 09:58:11 INFO - Options: 09:58:11 INFO - -h, --help show this help message and exit 09:58:11 INFO - -d DEST, --destination=DEST 09:58:11 INFO - Directory to install application into. [default: 09:58:11 INFO - "/builds/slave/test"] 09:58:11 INFO - --app=APP Application being installed. [default: firefox] 09:58:11 INFO - mkdir: /builds/slave/test/build/application 09:58:11 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'] 09:58:11 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 09:58:46 INFO - Reading from file tmpfile_stdout 09:58:46 INFO - Output received: 09:58:46 INFO - /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox 09:58:46 INFO - Running post-action listener: _resource_record_post_action 09:58:46 INFO - [mozharness: 2016-08-23 16:58:46.637475Z] Finished install step (success) 09:58:46 INFO - [mozharness: 2016-08-23 16:58:46.637657Z] Running run-tests step. 09:58:46 INFO - Running pre-action listener: _resource_record_pre_action 09:58:46 INFO - Running main action method: run_tests 09:58:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:58:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:58:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:58:46 INFO - 2016-08-23 09:58:46.652 screenresolution[1637:7511] starting screenresolution argv=screenresolution get 09:58:46 INFO - 2016-08-23 09:58:46.655 screenresolution[1637:7511] Display 0: 1600x1200x32@60 09:58:46 INFO - 2016-08-23 09:58:46.661 screenresolution[1638:7513] starting screenresolution argv=screenresolution list 09:58:46 INFO - Available Modes on Display 0 09:58:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:58:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:58:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:58:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:58:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:58:46 INFO - Intel Iris: 09:58:46 INFO - Chipset Model: Intel Iris 09:58:46 INFO - Type: GPU 09:58:46 INFO - Bus: Built-In 09:58:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:58:46 INFO - Vendor: Intel (0x8086) 09:58:46 INFO - Device ID: 0x0a2e 09:58:46 INFO - Revision ID: 0x0009 09:58:46 INFO - Displays: 09:58:46 INFO - Display: 09:58:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:58:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:58:46 INFO - Display Serial Number: 1600x1200 60 09:58:46 INFO - Main Display: Yes 09:58:46 INFO - Mirror: Off 09:58:46 INFO - Online: Yes 09:58:46 INFO - Rotation: Supported 09:58:46 INFO - Return code: 0 09:58:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:58:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:58:46 INFO - Python 2.7.3 09:58:46 INFO - Return code: 0 09:58:46 INFO - grabbing minidump binary from tooltool 09:58:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:58:46 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 0x1090aed30>, '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 0x108e13db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1090a6b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:58:46 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 09:58:46 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 09:58:46 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 09:58:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:58:47 INFO - Return code: 0 09:58:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:58:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:58:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:58:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:58:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:58:47 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 09:58:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0123 --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 09:58:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.TYMUVjHnN5/Render', 09:58:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:58:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:58:47 INFO - 'HOME': '/Users/cltbld', 09:58:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:58:47 INFO - 'LOGNAME': 'cltbld', 09:58:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:58:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:58:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:58:47 INFO - 'MOZ_NO_REMOTE': '1', 09:58:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:58:47 INFO - 'NO_EM_RESTART': '1', 09:58:47 INFO - 'PAGER': '/bin/cat', 09:58:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:58:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:58:47 INFO - 'PWD': '/builds/slave/test', 09:58:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:58:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:58:47 INFO - 'SHELL': '/bin/bash', 09:58:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.6wBSCz3VJo/Listeners', 09:58:47 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 09:58:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:58:47 INFO - 'USER': 'cltbld', 09:58:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:58:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:58:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:58:47 INFO - 'XPC_FLAGS': '0x0', 09:58:47 INFO - 'XPC_SERVICE_NAME': '0', 09:58:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:58:47 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0123', '--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 09:58:47 INFO - mozversion application_buildid: 20160823072522 09:58:47 INFO - mozversion application_changeset: 052656fc513c05da969590ac5934abd67271a897 09:58:47 INFO - mozversion application_display_name: Nightly 09:58:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:58:47 INFO - mozversion application_name: Firefox 09:58:47 INFO - mozversion application_remotingname: firefox 09:58:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 09:58:47 INFO - mozversion application_vendor: Mozilla 09:58:47 INFO - mozversion application_version: 51.0a1 09:58:47 INFO - mozversion platform_buildid: 20160823072522 09:58:47 INFO - mozversion platform_changeset: 052656fc513c05da969590ac5934abd67271a897 09:58:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 09:58:47 INFO - mozversion platform_version: 51.0a1 09:58:47 INFO - using testdate: 1471971527 09:58:47 INFO - actual date: 1471971527 09:58:47 INFO - starting webserver on 'localhost:49270' 09:58:47 INFO - SUITE-START | Running 2 tests 09:58:47 INFO - TEST-START | dromaeo_css 09:58:47 INFO - operating with platform_type : mac_ 09:58:47 INFO - Initialising browser for dromaeo_css test... 09:58:47 INFO - TEST-INFO | started process 1648 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpj_4J0G/profile http://localhost:49270/getInfo.html) 09:58:49 INFO - PROCESS | 1648 | __metrics Screen width/height:1600/1200 09:58:49 INFO - PROCESS | 1648 | colorDepth:24 09:58:49 INFO - PROCESS | 1648 | Browser inner width/height: 1024/689 09:58:49 INFO - PROCESS | 1648 | __metrics 09:58:49 INFO - PROCESS | 1648 | 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" 09:58:49 INFO - PROCESS | 1648 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 09:58:50 INFO - TEST-INFO | 1648: exit 0 09:58:50 INFO - Browser initialized. 09:58:50 INFO - Running cycle 1/1 for dromaeo_css test... 09:58:50 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpj_4J0G/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:59:02 INFO - PROCESS | 1651 | RSS: Main: 235118592 09:59:02 INFO - PROCESS | 1651 | 09:59:53 INFO - PROCESS | 1651 | 1471971593621 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] 10:02:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49270/tests/dromaeo/cssquery-ext.html) 10:02:07 INFO - PROCESS | 1651 | RSS: Main: 352681984 10:02:07 INFO - PROCESS | 1651 | 10:05:10 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-ext.html (next: http://localhost:49270/tests/dromaeo/cssquery-jquery.html) 10:05:11 INFO - PROCESS | 1651 | RSS: Main: 367046656 10:05:11 INFO - PROCESS | 1651 | 10:08:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49270/tests/dromaeo/cssquery-mootools.html) 10:08:14 INFO - PROCESS | 1651 | RSS: Main: 343642112 10:08:14 INFO - PROCESS | 1651 | 10:11:17 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49270/tests/dromaeo/cssquery-prototype.html) 10:11:17 INFO - PROCESS | 1651 | RSS: Main: 338821120 10:11:17 INFO - PROCESS | 1651 | 10:14:21 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49270/tests/dromaeo/cssquery-yui.html) 10:14:21 INFO - PROCESS | 1651 | RSS: Main: 330047488 10:14:21 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49270/tests/dromaeo/cssquery-yui.html (next: http://localhost:49270/tests/dromaeo/cssquery-dojo.html) 10:17:25 INFO - PROCESS | 1651 | RSS: Main: 390815744 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | __start_tp_report 10:17:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 10:17:25 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 10:17:25 INFO - PROCESS | 1651 | |i|pagename|runs| 10:17:25 INFO - PROCESS | 1651 | |0;dojo.html;3311.751618628603;3319.8672053117875;3341.966580334196;3388.2715216228507;3413.590369155702;5565.9443405565935;5612.074007788715;5692.316921969359;5715.714214289284;5744.195812586236;5636.718164091789;5693.003724348233;5731.627444216123;5754.913676294859;5787.276590426197;5115.411727651331;5171.7414129293575;5199.272101905717;5201.817936372209;5270.341207349082;5290.497402746733;5293.52358287754;5298.549623282026;5320.866978325535;5336.199570064502;5115.181570948667;5159.74201289934;5166.173412254059;5182.818601348972;5280.841574752763;5104.64267501275;5215.035218484554;5216.504432078985;5221.2951251580935;5229.869253268661;5121.923171152435;5125.308083408767;5133.281340612317;5150.665206761549;5176.456472070416;4985.653873454147;5645.407232240637;5678.886422271531;5697.430256974311;5700.23046888673;5873.618214816023;5937;6104.542159338067;6249.812505624838;6262.342454042306;5666.716664166775;5997.310309314441;6049.243844519435;6114.419130182626;6126.632402055891;5609;5615.634983726045;5617.438256174431;5634.971825140889;5658.321001479808;6209.161763161916;6227.719752611143;6265.906011409833;6280.685965701697;6337.144485494399;6096.933036718645;6127.754889804447;6147.784827531015;6148.077788331786;6166.691665416712;6232.875342493125;6259.6870156492;6262.12330273762;6276.717547710364;6280.811575652738;6038.184845045863;6110.450059494667;6140.381550880983;6229.813105606839;6238.251409830758;6204.875902481925;6222.937770622327;6252.5935814171935;6289.96855015722;6298.811035668938;4937.35814344133;4988.825391111293;4999.275105109811;5005.34930459038;5024.748762561857;4417.006173610912;4433.4236549249035;4475.485319188335;4498.797554110073;4523.185826551252;4734.573888350065;5588.441155884379;5646.887062258732;5652.3217213934595;5656.2929633795775;5154.484551544815;6236.095766113888;6243.75024999004;6250.843728906722;6252.562320637603;5546.112621980545;5599.524040456526;5632.183333416631;5645.463680950304;5654.632448890891;5374;5410;5417.945820541744;5422.566194704415;5424.593155513352;6150.446459818638;6250;6292.905606415908;6320.873582528232;6321.336259692758;6149.41580549847;6180.227471566054;6181.289151747606;6183.412575805291;6186.0102383618405;6278.968605157128;6294.748210071546;6297.685115744286;6306.558540902093;6314.715837787402;6019.067044608093;6020.668863212565;6029.427204415573;6070.3929607038945;6087.05650624154;5994.520438364833;6149.538784591263;6163.9383606163365;6186.505079593531;6187;6102.156009074812;6114.054067497291;6130.0312685453755;6139.553468857593;6147.708981113838;6098.148120573121;6119.563485741731;6130.428711073822;6132.819519597447;6147.770445910746;5804.6807425592;5813.011787996135;5875.353711091691;5997.100434934795;6046.365131661289;4799.736014519235;5991.221141251609;5995.850103747442;6023.427774361428;6067.089936509559;5778.451047150514;5819.912701309399;5825.155352473952;5825.650460972355;5831.096180092092 10:17:25 INFO - PROCESS | 1651 | |1;ext.html;473.495727050691;491.6018025399427;519.6855902179182;523.2987796352886;556.7772890843664;36674.816625916865;38837.611623883786;38933;38972.610273897255;38992.22015559687;38617;39102.413463797995;39109.41335879964;39115.21769564607;39262.21475570487;8204.999999999985;8985.910140898573;9381.343305968585;9891.060349266809;9903.356281841658;9634.566444510014;9730.56212470437;9757.951210243938;9779.217662586976;9799.559019844088;9783.315167938246;9812.264080193978;9814.901850981505;9825.164860986824;9843.507824608776;9725.659601913934;9780.853287200698;9780.951095244549;9793.412395256308;9822.116009559173;8523.914760852436;9586.233101351945;9680.612775488971;9790.167835733972;9810.21518278536;38362.61637383619;38698.613013869784;38738;38751.03122421934;38759.8062009691;3207.7273431758326;3371.3088816792497;3382.289719158955;3404.438267685829;3420.7263418926673;30574.54138187929;30733.38533229323;31353.686463135306;31393.52909706356;31536.526952095737;2712.609238451935;2925.414917016584;3278.1640681626272;3369.2082360645227;3420.6921377076183;398160;493378.53310733574;495470;495963;505267;57485.13772293419;58559.36482858282;58639.70680146572;58664;58785.11822322669;52062.73968630133;61759.691201543705;61773.691131544954;61825.690871545354;61865.38134618686;14488.637784055272;16190.352385904433;16504.587385315463;16540.83459165417;16957.830421695624;12798.040146988827;12872.742545149045;12887.54893578739;12895.290759008345;12902.483900643869;15504.379824806882;17966.281348746164;18092.819071809114;18174.091295435177;18174.728158980128;5387.299651045339;7894.052713674396;7897.447178697551;7958.482698624571;8049.51702897828;855.8673405622138;955.5461155950868;1000.1148983149965;1001.4191768774093;1055.8280308857163;29059.273518161794;29130.689118989696;29131.85434072816;29996.400071998873;30001.849990749906;1053.167997282154;1071.266182664877;1080.3409919948824;1087.8748943871399;1129.949152288149;14646.26768661584;15189.544313670607;15223.695526089416;15609.687806243812;15682.294296756674;671070;676325.5653495506;680502;686035;1001056;55665.443345566026;56479;56631.71684141553;56668;56898;1370.4312710225145;1393.4217299820461;1415.490423447558;1417.6597616572155;1418.8793952513945;29034.274143145747;29515.262118447212;29527.85236073806;29627.851860740557;30429.391412172074;48128.27807582819;48633.5136648629;48674.756626218055;48761.51238487712;48823.0235395283;639627;645941;645989;646074;646676;25359.6196057063;26040.08859689902;26186.86906565455;26204.73795262099;26297.290676105993;32870.506942395405;37495.81252093722;43976.34035489534;44846.551534485545;46676.833079171935;35347.46978795371;36378.088163248896;37496.81251593724;37734.245315092994;37785.433218501195;29528.96648617288;36350.8182459086;36361.27277454383;37279;37619.623803762704;33946.83026584851;34058.14854628555;34190.97427077192;36329.63670363263;36642.08394790045;31225.37549249048;32068.358632826745;32145.678543215203;32474.02577922666;32787.50818737769;21675.132994680036;22801.88599056994;24335.02659893655;24739.381515462257;24949.88326926037 10:17:25 INFO - PROCESS | 1651 | |2;jquery.html;345.9655629667296;363.25895173845373;370.52757733889285;377.22103855538313;394.5876558995849;15582.766258506117;16892.66214675706;21025.47436314093;21549.892250538724;21651.025703843345;20320.898395508;20710.27514037009;21090.89454552725;21442.785572144276;21624.351269461942;5457.290552228224;5859.882802343972;6056.848578785543;6188.690565471731;6214.098955651428;4144.751314921099;5602.7758889644365;5928.992071347881;6098.115773212881;6217.689115544227;4353.782310884443;4371.14762621289;4863.781129849148;6725.7309707611885;6782.389584937355;5597.608167428281;6475.676216189218;6769.0861733665815;6801.081853949728;6806.591604503721;5824.213731146326;5857.912131317991;5858.648481091148;5862.384449632812;5866.061430171152;26032.73967260322;26453.735462645513;27147.72852271472;27364.726352736416;27551.724482755315;3041;3129.04564107947;3281.278118813857;3324.4212520895594;3424.537687412217;16165.676686466322;16508.926919750178;16576.419825306057;16614.086225257604;17504.562385940455;1997.3408775104326;2092.7279453670926;2111.461577297779;2115.5345823918715;2199.3951663292564;89441;135588;139818;148561;150177.49822501745;20417.89552611847;21281.255156069463;21300.573988520144;21513.56972860534;21571.67642485364;34447.65552344495;34452.82773586116;34487.13782155416;34531.827340863136;34647.65352346495;10613.310134841366;10627.946860265649;10813.459327033617;10900.291481053706;11054.336739795477;8943.776405589782;8944;8963.238124759338;9129.77175570616;9138.81722365549;9194.862077068849;9514.47670378122;9665.951670241604;9666.323357365058;9682.419054856731;3417.179876829543;3450.9309813803584;3617.2403795202777;3711.888643340704;3751.399776035821;775.2944820213579;843.9451435656663;861.0992205309956;1001.484235618657;1034.6637342863598;20261.797382026285;20849.957502124835;20872.89563552203;20933;21109.410597578255;916.1800188831093;929.2937367600537;944.4663764972905;953.6233187890745;1002.1431675917132;10180.898191018143;10250.537720067012;10909.598631687526;10993.230473866752;10999.780004400027;626052;627187;641889;643132;660993;128822.71177288295;134618.32690836483;139773.99999999796;140279;141745.24292813696;970.2335155227291;990.2375171118199;1006.7030226083469;1012.0436187802457;1012.5291739341242;14612.415503379745;18848.52878677989;19029.524261893563;19053.380462660276;19068.95120768371;54534.45465545294;54752.45247547473;55265.72367138138;56031;56132.43867561436;77907.598778116;78900.21099789158;80368.59815700885;80653.19346806456;81077.59461202892;6508.991106378518;7629.694812207672;7667.616619169131;7920.326772224195;8232.259096681328;11498.54005839757;12881.677958051123;12970.092093553361;13152.145110568164;13251.867481325064;11035.944820275847;11064.336139831314;11260.211785175286;11376.089912807121;11418;10992.835107473235;11003.284786488852;11127.387993660102;11144.944275278573;11237.4124939353;9350.25197984146;11424.600138995096;11547.653570392902;11559.595414160463;11663.41682915834;7806.960965195365;8048.476849004796;8084.636191371284;8291.792705182177;8313.584320783815;10334.689959301233;10859.728506787393;11308.547658093585;11338.546458141913;11349.48927298257 10:17:25 INFO - PROCESS | 1651 | |3;mootools.html;1368.7262547490504;1540.7765873948279;1545.6599548099414;1732.8353806388388;1874.1847296426056;7594;8146.063202731688;8446.746597602074;8690.565471726404;8696.304295656333;8591.785205369868;8643.740687779376;8655.480671159736;8657.783555411117;8658.783530411743;2332.8483648562874;2359.277727259102;2364.385259832439;2366.538524987628;2372.8813559321966;2316.2240649382393;2360.752121027293;2379.0848367064505;2380.5833979053596;2382.5115851250453;2350.435895385104;2370.7629237076326;2376.7504412036747;2387.940301492459;2392.3779817247555;2321.419645088728;2353.846999944998;2359.23324919401;2362.598358279097;2374.1453076892303;2318.2002209237785;2345.2143531916827;2349.295211436562;2349.5770761262966;2352.1532248390567;8617.913820861837;8635.568221588896;8692.43499172558;8717.864123289772;8741.30069594431;5103.311053007871;5110.105731496973;5264.552513036397;5276.4459731728;5276.657017293864;2934.53047512397;3633.7274704397278;3636.945445818315;3641.635836416364;3652.1052342176336;1035.891231420705;1050.453764042701;1054.4674939155677;1070.6520380876245;1072.780080083581;11191.552337906474;11328.98039176478;11329.376884271362;11350;11358.71603209918;4312.913741725148;4410.647148228134;4423.601875831127;4476.753778542212;4561.224591819332;8929.955350223338;9052.592633331516;9055.728328150166;9058.909410905939;9066.773330666787;5146.2280657900865;5150.948490515122;5152.587792976553;5156.046131465616;5157.252198431282;4347.586979236968;4484.573965473275;4671.462781780071;4803.327534145223;4827.61379089672;3347.330533893231;3643.307771523402;3695.279420512974;3750.5499340079364;3780.678642315379;1141.178351586856;1153.5731779241735;1157.438642258504;1164.394514852272;1168.5909931524068;834.1991687979607;880.7974165941869;884.6815146547237;903.6295119001178;917.7567944494714;5335.932813437328;5672.177534257592;5822.7088645567555;5903.114532820111;6049.364816694272;415.84198004757997;418.4518281051771;451.9638428925744;452.1386758225585;454.14620513435193;3262.5432439458496;3798.2213646202463;3982.820773065219;3995;4123.793810309473;10379.429131397847;10408;10526.31578947366;10674.62638807638;10710.839337409945;10404.219683523615;10568.207384446043;10626.415547144981;10653.627123050812;10671.932806719422;7866.488678235896;7918.564478953713;8087.959560202161;8118.3099436548555;8159.714409995621;4738.526147385372;5135.3324067870935;5368.845698174911;5890.357409707262;5968.22413086296;8211.95894020526;9313.627454902;9709.36889102206;9863.852042219229;9890.060444257784;9468.668596599086;9734.805303894023;9854.950725246328;9861.161801246973;10050.346727462691;5934.813037392625;6652.667366631746;7379.520331178456;7409.481336306407;7489.775306740807;8221.712240071569;8418.95790521068;8426.199511046441;8432.747017589481;8438.324934005142;7366.410687145123;7407.2592740725495;7418.703251870081;7489.400847932261;7571.848563028599;7078.752243671447;7241.674124664508;7287.526310789782;7289;7332.596707180943;6772.932270677427;6797.1843378794865;6849.1096157499205;6861.691223895036;6880.965595171992;4885.9022819544125;4903.117438861039;4926.039422312615;4953.777080031336;5053.7473126344275;4896.510348965217;4911.484294149063;4954.380702412199;5022.673526220784;5031.84904452867 10:17:25 INFO - PROCESS | 1651 | |4;prototype.html;297.29096105787704;300.82552119770526;310.45515120962716;313.78348939231944;313.7913287663704;4631;4813.916868704539;5220.216967454884;5253.369595648523;5324.988252232073;4728.3380326754195;4772.546608072236;4867.732274724889;5005.6496045276845;5163.63854530183;3405.5402520659763;3953.892909985209;4024.7925622313337;4054.797260136996;4085.673146148316;3366.713829324498;3584.086058055192;4047;4075.3071977763852;4086.5504794472586;3292.901212963615;3554.822258887058;3981.263466258737;4004.5783746769916;4020.3768415895584;3435;3980.20395920817;3994.400839874013;4012.9799351003057;4022.517297924268;2892.421515696848;3310.3710295043866;3477.287156133012;3484.4250698634696;3485.35520928629;4227.492700875884;4444.933326000112;5121.8463446096675;5140.820071297487;5265.815696450606;4402.7798610069685;4549.886252843672;4557.248054071074;4565.155446242456;4572.908541829145;4291.785410729451;4604.58558729716;4814.6870453420415;5482.887834124406;5523.944760552424;3532.5584301962253;3597.6582224688614;3796.90507737306;3827.464155018272;3857.1514266861245;5564.916526252109;5893.469587737081;5905.527557795366;5914.2311499504785;5950.613210141327;3396.1679388549965;3591.874284400086;3746.901735028393;3760.52993375828;3823.1397935464297;3177.570093457953;3917.5494818095713;3924.4505769192338;3926.7447615904875;3961.6830653547645;3435.587729472479;3924.0961688350417;4571.337156112345;4581.610563102174;4594.839180628662;5000.174971129795;5289.3917199521775;5527.9220551991975;5631.662100273997;5680.233168522279;1175.5532897498895;1184.4314728930162;1190.15498995714;1290.470906928155;1334.658668038615;871.8926962757263;894.959726812295;899.8155378147464;904.1636486250324;904.3495620492628;4321.546237645002;4604.493505714368;4885.018709751541;5155.896882062338;5159.793608255703;4936.851894443173;5332.546733527616;5697.6296540724725;5705.857353566111;5746.971265143731;5086.23706444029;5104.566111880457;5109.952459745743;5114.974425127851;5131.358580177478;2190.6636951459595;2235.407616981469;2453.754624537532;2482.8013758899247;2497.987510062438;3964.3746064113575;4459.487158976694;4479;4627.894308089811;4790.209615413418;4410.867673969786;4427.299347398084;4514.954850451519;4794.712317260975;4860.927086093711;3876.4185372194397;4820.758962051884;4824.179889418807;4828.903421931612;4838.685485443435;4392.945693033713;4448.5551444855255;4663.473783566485;4670.64970127235;4719.952800471951;3948.6702845321397;3999.1002024544246;4307.569243075667;4625.687156421789;4717.2216584263215;4141.81361838724;4866.391701037371;4885.731284779371;4897.706137631753;4898;3295.719863811603;3337.5327454155954;3590.31783961057;3666.6699997000396;3746.1196618795107;5637.238972738792;5645.604807663424;5667.744951477111;5678.886422271449;5760.596758226884;4863.416390033219;4869.683470574401;5519.944800551943;5525.806596769094;5565.16522521625;5168;5561.886483136383;5573.832785016456;5650.485533937358;5664.886702266014;4576.4737055239075;5490.10591345985;5512.862178445571;5515.806946757005;5534.225208470737;5271.578273738169;5559.471145435038;5582.720863956704;5611.551075913999;5650.802221922213;4746.762661866824;5037.773300201573;5444.861904952344;5508.394076651645;5513.365962914156 10:17:25 INFO - PROCESS | 1651 | |5;yui.html;568.7525926222094;686.5846163071342;804.8913396691448;813.7518056992615;820.3806126374586;15303.846961530382;15768.84231157688;16295.266712997915;16304.429344972928;16576.08831514266;14762.557123286291;14913;14926.925365373158;14933.253337333144;14963.401463941429;675.4090171100287;838.7399906029135;859.866720658299;860.3375400941271;860.8536548786722;701.7403560682529;830.6511265268601;860.3332417376552;872.0102683454294;876.4128034217058;917.3257655623112;918.4902379179553;919.3564504846601;920.6547544670749;927.7402327348319;706.131458306281;913.0914739833875;914.0676509959837;915.8992510823804;922.2115091596722;502.138831903284;541.7345500704627;584.1500616602833;670.2024590737057;671.1811589860363;12435.689107772288;15172.165530895796;15202.315895784715;15226.238688065663;15232.314545845464;14050.508232211823;14244.430222791076;14439.494617688331;14457.638559036002;14743.483978060716;12129.69675758113;12929.405169976268;12999;13189.406476708475;13324.733505329936;4486.327050942352;4490.658971616216;4522.253828118356;4541.772911354452;4696.765161741933;115363.99999999916;117328;118722;118974;119345;9311.44131352121;11053.834192487118;11067.72330691739;11252.26860254081;11831.763364732657;22478.100875964778;22753.544929101325;22771;22799.202027928943;22805;7958.920410795934;7967.3626109911065;8093.635786389626;8137.430379873352;8175.305099066527;3610.765300255475;4333.696641235083;4406.237504997963;4414.889627759332;4595.816167353269;3296.5055241713935;3458;3471.635478274745;3473.0622731862254;3478.4608385700258;535.9624826262202;582.8630271886103;589.6933594530906;602.978895738647;610.078781040623;527.3460908473542;550.7439040845945;606.7239406070228;628.7359309090193;645.6997496164306;8600.5699715014;8947.881514810648;9040;9264.397814142194;9374.329389101593;587.6063037764708;589.3605438099625;602.208096353294;633.499535367064;651.2998526583847;4293.441852559147;4572.74849883253;5006.956867421998;5023.120320442201;5181.222816577543;151838.48161518463;152470;155631.22184389006;159142.2042889778;165785;106047;106294;108056.37915431274;141406;145933;67305.32694672991;71507;104826.47586762017;108072;111791;7850.6074696266105;8927.196552310064;9715.611375544899;9791.265655075755;9802.313838031268;59104.704476477345;60076.699616503385;60302.69848650729;60389.698051511215;60950.695246523486;91361.17277654543;91790.08209917815;92460.53769731108;93492;94248;12893.290869002294;12927.935360323138;12947.5468358607;13095.934520327337;13438.932805335911;10896.509657065293;14740.336684849422;14831.40674373013;15154.772678409612;15593.76609350838;14152.646183845487;14182.787258190929;14205.857941420454;14376.784348234994;14511.564653060424;13482.090193844067;13597.541507024393;14066.718665626835;14290.214038227998;14337.713245735236;13518.797218041935;13611.659708507366;13725.519606813714;13814;14504;11358.26171298863;11659.417029148679;11686.415679215836;11712.414379281172;11797.081471858357;11066.648681863811;11417.31496110236;11488.080953523866;11494.367809770209;11674.94162529182 10:17:25 INFO - PROCESS | 1651 | __end_tp_report 10:17:25 INFO - PROCESS | 1651 | __start_cc_report 10:17:25 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,1629 10:17:25 INFO - PROCESS | 1651 | __end_cc_report 10:17:25 INFO - PROCESS | 1651 | __startTimestamp1471972645627__endTimestamp 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | ------- Summary: start ------- 10:17:25 INFO - PROCESS | 1651 | Number of tests: 6 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#0] dojo.html Cycles:165 Average:5688.47 Median:5825.16 stddev:629.60 (10.8%) stddev-sans-first:603.29 10:17:25 INFO - PROCESS | 1651 | Values: 3311.8 3319.9 3342.0 3388.3 3413.6 5565.9 5612.1 5692.3 5715.7 5744.2 5636.7 5693.0 5731.6 5754.9 5787.3 5115.4 5171.7 5199.3 5201.8 5270.3 5290.5 5293.5 5298.5 5320.9 5336.2 5115.2 5159.7 5166.2 5182.8 5280.8 5104.6 5215.0 5216.5 5221.3 5229.9 5121.9 5125.3 5133.3 5150.7 5176.5 4985.7 5645.4 5678.9 5697.4 5700.2 5873.6 5937.0 6104.5 6249.8 6262.3 5666.7 5997.3 6049.2 6114.4 6126.6 5609.0 5615.6 5617.4 5635.0 5658.3 6209.2 6227.7 6265.9 6280.7 6337.1 6096.9 6127.8 6147.8 6148.1 6166.7 6232.9 6259.7 6262.1 6276.7 6280.8 6038.2 6110.5 6140.4 6229.8 6238.3 6204.9 6222.9 6252.6 6290.0 6298.8 4937.4 4988.8 4999.3 5005.3 5024.7 4417.0 4433.4 4475.5 4498.8 4523.2 4734.6 5588.4 5646.9 5652.3 5656.3 5154.5 6236.1 6243.8 6250.8 6252.6 5546.1 5599.5 5632.2 5645.5 5654.6 5374.0 5410.0 5417.9 5422.6 5424.6 6150.4 6250.0 6292.9 6320.9 6321.3 6149.4 6180.2 6181.3 6183.4 6186.0 6279.0 6294.7 6297.7 6306.6 6314.7 6019.1 6020.7 6029.4 6070.4 6087.1 5994.5 6149.5 6163.9 6186.5 6187.0 6102.2 6114.1 6130.0 6139.6 6147.7 6098.1 6119.6 6130.4 6132.8 6147.8 5804.7 5813.0 5875.4 5997.1 6046.4 4799.7 5991.2 5995.9 6023.4 6067.1 5778.5 5819.9 5825.2 5825.7 5831.1 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#1] ext.html Cycles:180 Average:73709.96 Median:37759.84 stddev:171085.14 (453.1%) stddev-sans-first:171476.71 10:17:25 INFO - PROCESS | 1651 | Values: 473.5 491.6 519.7 523.3 556.8 36674.8 38837.6 38933.0 38972.6 38992.2 38617.0 39102.4 39109.4 39115.2 39262.2 8205.0 8985.9 9381.3 9891.1 9903.4 9634.6 9730.6 9758.0 9779.2 9799.6 9783.3 9812.3 9814.9 9825.2 9843.5 9725.7 9780.9 9781.0 9793.4 9822.1 8523.9 9586.2 9680.6 9790.2 9810.2 38362.6 38698.6 38738.0 38751.0 38759.8 3207.7 3371.3 3382.3 3404.4 3420.7 30574.5 30733.4 31353.7 31393.5 31536.5 2712.6 2925.4 3278.2 3369.2 3420.7 398160.0 493378.5 495470.0 495963.0 505267.0 57485.1 58559.4 58639.7 58664.0 58785.1 52062.7 61759.7 61773.7 61825.7 61865.4 14488.6 16190.4 16504.6 16540.8 16957.8 12798.0 12872.7 12887.5 12895.3 12902.5 15504.4 17966.3 18092.8 18174.1 18174.7 5387.3 7894.1 7897.4 7958.5 8049.5 855.9 955.5 1000.1 1001.4 1055.8 29059.3 29130.7 29131.9 29996.4 30001.8 1053.2 1071.3 1080.3 1087.9 1129.9 14646.3 15189.5 15223.7 15609.7 15682.3 671070.0 676325.6 680502.0 686035.0 1001056.0 55665.4 56479.0 56631.7 56668.0 56898.0 1370.4 1393.4 1415.5 1417.7 1418.9 29034.3 29515.3 29527.9 29627.9 30429.4 48128.3 48633.5 48674.8 48761.5 48823.0 639627.0 645941.0 645989.0 646074.0 646676.0 25359.6 26040.1 26186.9 26204.7 26297.3 32870.5 37495.8 43976.3 44846.6 46676.8 35347.5 36378.1 37496.8 37734.2 37785.4 29529.0 36350.8 36361.3 37279.0 37619.6 33946.8 34058.1 34191.0 36329.6 36642.1 31225.4 32068.4 32145.7 32474.0 32787.5 21675.1 22801.9 24335.0 24739.4 24949.9 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#2] jquery.html Cycles:180 Average:38272.24 Median:3205.16 stddev:106860.85 (3334.0%) stddev-sans-first:107122.68 10:17:25 INFO - PROCESS | 1651 | Values: 346.0 363.3 370.5 377.2 394.6 15582.8 16892.7 21025.5 21549.9 21651.0 20320.9 20710.3 21090.9 21442.8 21624.4 5457.3 5859.9 6056.8 6188.7 6214.1 4144.8 5602.8 5929.0 6098.1 6217.7 4353.8 4371.1 4863.8 6725.7 6782.4 5597.6 6475.7 6769.1 6801.1 6806.6 5824.2 5857.9 5858.6 5862.4 5866.1 26032.7 26453.7 27147.7 27364.7 27551.7 3041.0 3129.0 3281.3 3324.4 3424.5 16165.7 16508.9 16576.4 16614.1 17504.6 1997.3 2092.7 2111.5 2115.5 2199.4 89441.0 135588.0 139818.0 148561.0 150177.5 20417.9 21281.3 21300.6 21513.6 21571.7 34447.7 34452.8 34487.1 34531.8 34647.7 10613.3 10627.9 10813.5 10900.3 11054.3 8943.8 8944.0 8963.2 9129.8 9138.8 9194.9 9514.5 9666.0 9666.3 9682.4 3417.2 3450.9 3617.2 3711.9 3751.4 775.3 843.9 861.1 1001.5 1034.7 20261.8 20850.0 20872.9 20933.0 21109.4 916.2 929.3 944.5 953.6 1002.1 10180.9 10250.5 10909.6 10993.2 10999.8 626052.0 627187.0 641889.0 643132.0 660993.0 128822.7 134618.3 139774.0 140279.0 141745.2 970.2 990.2 1006.7 1012.0 1012.5 14612.4 18848.5 19029.5 19053.4 19069.0 54534.5 54752.5 55265.7 56031.0 56132.4 77907.6 78900.2 80368.6 80653.2 81077.6 6509.0 7629.7 7667.6 7920.3 8232.3 11498.5 12881.7 12970.1 13152.1 13251.9 11035.9 11064.3 11260.2 11376.1 11418.0 10992.8 11003.3 11127.4 11144.9 11237.4 9350.3 11424.6 11547.7 11559.6 11663.4 7807.0 8048.5 8084.6 8291.8 8313.6 10334.7 10859.7 11308.5 11338.5 11349.5 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#3] mootools.html Cycles:180 Average:5545.73 Median:4918.76 stddev:3122.59 (63.5%) stddev-sans-first:3115.57 10:17:25 INFO - PROCESS | 1651 | Values: 1368.7 1540.8 1545.7 1732.8 1874.2 7594.0 8146.1 8446.7 8690.6 8696.3 8591.8 8643.7 8655.5 8657.8 8658.8 2332.8 2359.3 2364.4 2366.5 2372.9 2316.2 2360.8 2379.1 2380.6 2382.5 2350.4 2370.8 2376.8 2387.9 2392.4 2321.4 2353.8 2359.2 2362.6 2374.1 2318.2 2345.2 2349.3 2349.6 2352.2 8617.9 8635.6 8692.4 8717.9 8741.3 5103.3 5110.1 5264.6 5276.4 5276.7 2934.5 3633.7 3636.9 3641.6 3652.1 1035.9 1050.5 1054.5 1070.7 1072.8 11191.6 11329.0 11329.4 11350.0 11358.7 4312.9 4410.6 4423.6 4476.8 4561.2 8930.0 9052.6 9055.7 9058.9 9066.8 5146.2 5150.9 5152.6 5156.0 5157.3 4347.6 4484.6 4671.5 4803.3 4827.6 3347.3 3643.3 3695.3 3750.5 3780.7 1141.2 1153.6 1157.4 1164.4 1168.6 834.2 880.8 884.7 903.6 917.8 5335.9 5672.2 5822.7 5903.1 6049.4 415.8 418.5 452.0 452.1 454.1 3262.5 3798.2 3982.8 3995.0 4123.8 10379.4 10408.0 10526.3 10674.6 10710.8 10404.2 10568.2 10626.4 10653.6 10671.9 7866.5 7918.6 8088.0 8118.3 8159.7 4738.5 5135.3 5368.8 5890.4 5968.2 8212.0 9313.6 9709.4 9863.9 9890.1 9468.7 9734.8 9855.0 9861.2 10050.3 5934.8 6652.7 7379.5 7409.5 7489.8 8221.7 8419.0 8426.2 8432.7 8438.3 7366.4 7407.3 7418.7 7489.4 7571.8 7078.8 7241.7 7287.5 7289.0 7332.6 6772.9 6797.2 6849.1 6861.7 6881.0 4885.9 4903.1 4926.0 4953.8 5053.7 4896.5 4911.5 4954.4 5022.7 5031.8 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#4] prototype.html Cycles:180 Average:4259.30 Median:4626.79 stddev:1319.96 (28.5%) stddev-sans-first:1289.73 10:17:25 INFO - PROCESS | 1651 | Values: 297.3 300.8 310.5 313.8 313.8 4631.0 4813.9 5220.2 5253.4 5325.0 4728.3 4772.5 4867.7 5005.6 5163.6 3405.5 3953.9 4024.8 4054.8 4085.7 3366.7 3584.1 4047.0 4075.3 4086.6 3292.9 3554.8 3981.3 4004.6 4020.4 3435.0 3980.2 3994.4 4013.0 4022.5 2892.4 3310.4 3477.3 3484.4 3485.4 4227.5 4444.9 5121.8 5140.8 5265.8 4402.8 4549.9 4557.2 4565.2 4572.9 4291.8 4604.6 4814.7 5482.9 5523.9 3532.6 3597.7 3796.9 3827.5 3857.2 5564.9 5893.5 5905.5 5914.2 5950.6 3396.2 3591.9 3746.9 3760.5 3823.1 3177.6 3917.5 3924.5 3926.7 3961.7 3435.6 3924.1 4571.3 4581.6 4594.8 5000.2 5289.4 5527.9 5631.7 5680.2 1175.6 1184.4 1190.2 1290.5 1334.7 871.9 895.0 899.8 904.2 904.3 4321.5 4604.5 4885.0 5155.9 5159.8 4936.9 5332.5 5697.6 5705.9 5747.0 5086.2 5104.6 5110.0 5115.0 5131.4 2190.7 2235.4 2453.8 2482.8 2498.0 3964.4 4459.5 4479.0 4627.9 4790.2 4410.9 4427.3 4515.0 4794.7 4860.9 3876.4 4820.8 4824.2 4828.9 4838.7 4392.9 4448.6 4663.5 4670.6 4720.0 3948.7 3999.1 4307.6 4625.7 4717.2 4141.8 4866.4 4885.7 4897.7 4898.0 3295.7 3337.5 3590.3 3666.7 3746.1 5637.2 5645.6 5667.7 5678.9 5760.6 4863.4 4869.7 5519.9 5525.8 5565.2 5168.0 5561.9 5573.8 5650.5 5664.9 4576.5 5490.1 5512.9 5515.8 5534.2 5271.6 5559.5 5582.7 5611.6 5650.8 4746.8 5037.8 5444.9 5508.4 5513.4 10:17:25 INFO - PROCESS | 1651 | 10:17:25 INFO - PROCESS | 1651 | [#5] yui.html Cycles:180 Average:24748.54 Median:3475.76 stddev:39569.77 (1138.4%) stddev-sans-first:39639.12 10:17:25 INFO - PROCESS | 1651 | Values: 568.8 686.6 804.9 813.8 820.4 15303.8 15768.8 16295.3 16304.4 16576.1 14762.6 14913.0 14926.9 14933.3 14963.4 675.4 838.7 859.9 860.3 860.9 701.7 830.7 860.3 872.0 876.4 917.3 918.5 919.4 920.7 927.7 706.1 913.1 914.1 915.9 922.2 502.1 541.7 584.2 670.2 671.2 12435.7 15172.2 15202.3 15226.2 15232.3 14050.5 14244.4 14439.5 14457.6 14743.5 12129.7 12929.4 12999.0 13189.4 13324.7 4486.3 4490.7 4522.3 4541.8 4696.8 115364.0 117328.0 118722.0 118974.0 119345.0 9311.4 11053.8 11067.7 11252.3 11831.8 22478.1 22753.5 22771.0 22799.2 22805.0 7958.9 7967.4 8093.6 8137.4 8175.3 3610.8 4333.7 4406.2 4414.9 4595.8 3296.5 3458.0 3471.6 3473.1 3478.5 536.0 582.9 589.7 603.0 610.1 527.3 550.7 606.7 628.7 645.7 8600.6 8947.9 9040.0 9264.4 9374.3 587.6 589.4 602.2 633.5 651.3 4293.4 4572.7 5007.0 5023.1 5181.2 151838.5 152470.0 155631.2 159142.2 165785.0 106047.0 106294.0 108056.4 141406.0 145933.0 67305.3 71507.0 104826.5 108072.0 111791.0 7850.6 8927.2 9715.6 9791.3 9802.3 59104.7 60076.7 60302.7 60389.7 60950.7 91361.2 91790.1 92460.5 93492.0 94248.0 12893.3 12927.9 12947.5 13095.9 13438.9 10896.5 14740.3 14831.4 15154.8 15593.8 14152.6 14182.8 14205.9 14376.8 14511.6 13482.1 13597.5 14066.7 14290.2 14337.7 13518.8 13611.7 13725.5 13814.0 14504.0 11358.3 11659.4 11686.4 11712.4 11797.1 11066.6 11417.3 11488.1 11494.4 11674.9 10:17:25 INFO - PROCESS | 1651 | -------- Summary: end -------- 10:17:25 INFO - PROCESS | 1651 | 10:17:26 INFO - TEST-INFO | 1651: exit 0 10:17:26 INFO - TEST-OK | dromaeo_css | took 1118997ms 10:17:26 INFO - TEST-START | kraken 10:17:26 INFO - operating with platform_type : mac_ 10:17:26 INFO - Initialising browser for kraken test... 10:17:26 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpZTRU_3/profile http://localhost:49270/getInfo.html) 10:17:28 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:17:28 INFO - PROCESS | 1661 | colorDepth:24 10:17:28 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:17:28 INFO - PROCESS | 1661 | __metrics 10:17:28 INFO - PROCESS | 1661 | 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" 10:17:28 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:17:29 INFO - TEST-INFO | 1661: exit 0 10:17:29 INFO - Browser initialized. 10:17:29 INFO - Running cycle 1/1 for kraken test... 10:17:29 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/FirefoxNightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpZTRU_3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:17:41 INFO - PROCESS | 1663 | RSS: Main: 234971136 10:17:41 INFO - PROCESS | 1663 | 10:17:44 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection) 10:17:45 INFO - PROCESS | 1663 | RSS: Main: 293797888 10:17:45 INFO - PROCESS | 1663 | 10:17:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-dft) 10:17:48 INFO - PROCESS | 1663 | RSS: Main: 292061184 10:17:48 INFO - PROCESS | 1663 | 10:17:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-fft) 10:17:52 INFO - PROCESS | 1663 | RSS: Main: 378859520 10:17:52 INFO - PROCESS | 1663 | 10:17:54 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator) 10:17:55 INFO - PROCESS | 1663 | RSS: Main: 281325568 10:17:55 INFO - PROCESS | 1663 | 10:17:57 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:17:57 INFO - PROCESS | 1663 | RSS: Main: 284397568 10:17:57 INFO - PROCESS | 1663 | 10:18:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom) 10:18:01 INFO - PROCESS | 1663 | RSS: Main: 322801664 10:18:01 INFO - PROCESS | 1663 | 10:18:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate) 10:18:04 INFO - PROCESS | 1663 | RSS: Main: 324296704 10:18:04 INFO - PROCESS | 1663 | 10:18:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial) 10:18:08 INFO - PROCESS | 1663 | RSS: Main: 325480448 10:18:08 INFO - PROCESS | 1663 | 10:18:09 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:18:10 INFO - PROCESS | 1663 | RSS: Main: 300564480 10:18:10 INFO - PROCESS | 1663 | 10:18:12 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:18:13 INFO - PROCESS | 1663 | RSS: Main: 305729536 10:18:13 INFO - PROCESS | 1663 | 10:18:15 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:18:16 INFO - PROCESS | 1663 | RSS: Main: 308822016 10:18:16 INFO - PROCESS | 1663 | 10:18:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:18:19 INFO - PROCESS | 1663 | RSS: Main: 316444672 10:18:19 INFO - PROCESS | 1663 | 10:18:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:18:23 INFO - PROCESS | 1663 | RSS: Main: 319094784 10:18:23 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49270/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49270/tests/kraken/driver.html?testName=ai-astar) 10:18:25 INFO - PROCESS | 1663 | RSS: Main: 362459136 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | __start_tp_report 10:18:25 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:18:25 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:18:25 INFO - PROCESS | 1663 | |i|pagename|runs| 10:18:25 INFO - PROCESS | 1663 | |0;ai-astar;113;112;115;111;113;110;113;111;113;121 10:18:25 INFO - PROCESS | 1663 | |1;audio-beat-detection;179;146;120;190;138;163;128;108;161;109 10:18:25 INFO - PROCESS | 1663 | |2;audio-dft;188;184;182;184;186;184;182;183;183;179 10:18:25 INFO - PROCESS | 1663 | |3;audio-fft;78;75;77;152;58;57;57;68;140;57 10:18:25 INFO - PROCESS | 1663 | |4;audio-oscillator;84;83;134;70;75;90;118;70;70;121 10:18:25 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;90;87;90;88;89;87;92;87;90;87 10:18:25 INFO - PROCESS | 1663 | |6;imaging-darkroom;95;94;94;96;116;150;95;95;95;95 10:18:25 INFO - PROCESS | 1663 | |7;imaging-desaturate;84;86;81;91;69;126;134;95;85;84 10:18:25 INFO - PROCESS | 1663 | |8;json-parse-financial;53;53;55;56;54;55;56;55;54;55 10:18:25 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;66;66;75;81;67;73;72;68;69;72 10:18:25 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;88;84;87;83;81;84;83;86;90;87 10:18:25 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;145;157;148;135;138;135;139;146;221;138 10:18:25 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;171;167;167;167;166;170;169;167;169;166 10:18:25 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;65;85;86;87;88;70;67;66;65;66 10:18:25 INFO - PROCESS | 1663 | __end_tp_report 10:18:25 INFO - PROCESS | 1663 | __start_cc_report 10:18:25 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,2082 10:18:25 INFO - PROCESS | 1663 | __end_cc_report 10:18:25 INFO - PROCESS | 1663 | __startTimestamp1471972705281__endTimestamp 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:18:25 INFO - PROCESS | 1663 | Number of tests: 14 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:113.20 Median:113.00 stddev:3.08 (2.7%) stddev-sans-first:3.27 10:18:25 INFO - PROCESS | 1663 | Values: 113.0 112.0 115.0 111.0 113.0 110.0 113.0 111.0 113.0 121.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:144.20 Median:153.50 stddev:28.64 (18.7%) stddev-sans-first:27.47 10:18:25 INFO - PROCESS | 1663 | Values: 179.0 146.0 120.0 190.0 138.0 163.0 128.0 108.0 161.0 109.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:2.42 (1.3%) stddev-sans-first:1.94 10:18:25 INFO - PROCESS | 1663 | Values: 188.0 184.0 182.0 184.0 186.0 184.0 182.0 183.0 183.0 179.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:81.90 Median:63.00 stddev:34.96 (55.5%) stddev-sans-first:37.05 10:18:25 INFO - PROCESS | 1663 | Values: 78.0 75.0 77.0 152.0 58.0 57.0 57.0 68.0 140.0 57.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:91.50 Median:72.50 stddev:23.96 (33.1%) stddev-sans-first:25.26 10:18:25 INFO - PROCESS | 1663 | Values: 84.0 83.0 134.0 70.0 75.0 90.0 118.0 70.0 70.0 121.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:88.70 Median:89.50 stddev:1.77 (2.0%) stddev-sans-first:1.81 10:18:25 INFO - PROCESS | 1663 | Values: 90.0 87.0 90.0 88.0 89.0 87.0 92.0 87.0 90.0 87.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:95.00 stddev:17.97 (18.9%) stddev-sans-first:18.85 10:18:25 INFO - PROCESS | 1663 | Values: 95.0 94.0 94.0 96.0 116.0 150.0 95.0 95.0 95.0 95.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:93.50 Median:84.50 stddev:20.47 (24.2%) stddev-sans-first:21.42 10:18:25 INFO - PROCESS | 1663 | Values: 84.0 86.0 81.0 91.0 69.0 126.0 134.0 95.0 85.0 84.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:1.07 (2.0%) stddev-sans-first:0.97 10:18:25 INFO - PROCESS | 1663 | Values: 53.0 53.0 55.0 56.0 54.0 55.0 56.0 55.0 54.0 55.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:70.90 Median:72.00 stddev:4.72 (6.6%) stddev-sans-first:4.67 10:18:25 INFO - PROCESS | 1663 | Values: 66.0 66.0 75.0 81.0 67.0 73.0 72.0 68.0 69.0 72.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:85.30 Median:86.50 stddev:2.75 (3.2%) stddev-sans-first:2.74 10:18:25 INFO - PROCESS | 1663 | Values: 88.0 84.0 87.0 83.0 81.0 84.0 83.0 86.0 90.0 87.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:150.20 Median:145.50 stddev:25.81 (17.7%) stddev-sans-first:27.30 10:18:25 INFO - PROCESS | 1663 | Values: 145.0 157.0 148.0 135.0 138.0 135.0 139.0 146.0 221.0 138.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.90 Median:168.00 stddev:1.73 (1.0%) stddev-sans-first:1.42 10:18:25 INFO - PROCESS | 1663 | Values: 171.0 167.0 167.0 167.0 166.0 170.0 169.0 167.0 169.0 166.0 10:18:25 INFO - PROCESS | 1663 | 10:18:25 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:74.50 Median:77.50 stddev:10.45 (13.5%) stddev-sans-first:10.50 10:18:25 INFO - PROCESS | 1663 | Values: 65.0 85.0 86.0 87.0 88.0 70.0 67.0 66.0 65.0 66.0 10:18:25 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:18:25 INFO - PROCESS | 1663 | 10:18:26 INFO - TEST-INFO | 1663: exit 0 10:18:26 INFO - TEST-OK | kraken | took 59859ms 10:18:26 INFO - SUITE-END | took 1178s 10:18:26 INFO - Completed test suite (00:19:39) 10:18:26 INFO - javascript benchmark 10:18:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3311.751618628603, 3319.8672053117875, 3341.966580334196, 3388.2715216228507, 3413.590369155702, 5565.9443405565935, 5612.074007788715, 5692.316921969359, 5715.714214289284, 5744.195812586236, 5636.718164091789, 5693.003724348233, 5731.627444216123, 5754.913676294859, 5787.276590426197, 5115.411727651331, 5171.7414129293575, 5199.272101905717, 5201.817936372209, 5270.341207349082, 5290.497402746733, 5293.52358287754, 5298.549623282026, 5320.866978325535, 5336.199570064502, 5115.181570948667, 5159.74201289934, 5166.173412254059, 5182.818601348972, 5280.841574752763, 5104.64267501275, 5215.035218484554, 5216.504432078985, 5221.2951251580935, 5229.869253268661, 5121.923171152435, 5125.308083408767, 5133.281340612317, 5150.665206761549, 5176.456472070416, 4985.653873454147, 5645.407232240637, 5678.886422271531, 5697.430256974311, 5700.23046888673, 5873.618214816023, 5937.0, 6104.542159338067, 6249.812505624838, 6262.342454042306, 5666.716664166775, 5997.310309314441, 6049.243844519435, 6114.419130182626, 6126.632402055891, 5609.0, 5615.634983726045, 5617.438256174431, 5634.971825140889, 5658.321001479808, 6209.161763161916, 6227.719752611143, 6265.906011409833, 6280.685965701697, 6337.144485494399, 6096.933036718645, 6127.754889804447, 6147.784827531015, 6148.077788331786, 6166.691665416712, 6232.875342493125, 6259.6870156492, 6262.12330273762, 6276.717547710364, 6280.811575652738, 6038.184845045863, 6110.450059494667, 6140.381550880983, 6229.813105606839, 6238.251409830758, 6204.875902481925, 6222.937770622327, 6252.5935814171935, 6289.96855015722, 6298.811035668938, 4937.35814344133, 4988.825391111293, 4999.275105109811, 5005.34930459038, 5024.748762561857, 4417.006173610912, 4433.4236549249035, 4475.485319188335, 4498.797554110073, 4523.185826551252, 4734.573888350065, 5588.441155884379, 5646.887062258732, 5652.3217213934595, 5656.2929633795775, 5154.484551544815, 6236.095766113888, 6243.75024999004, 6250.843728906722, 6252.562320637603, 5546.112621980545, 5599.524040456526, 5632.183333416631, 5645.463680950304, 5654.632448890891, 5374.0, 5410.0, 5417.945820541744, 5422.566194704415, 5424.593155513352, 6150.446459818638, 6250.0, 6292.905606415908, 6320.873582528232, 6321.336259692758, 6149.41580549847, 6180.227471566054, 6181.289151747606, 6183.412575805291, 6186.0102383618405, 6278.968605157128, 6294.748210071546, 6297.685115744286, 6306.558540902093, 6314.715837787402, 6019.067044608093, 6020.668863212565, 6029.427204415573, 6070.3929607038945, 6087.05650624154, 5994.520438364833, 6149.538784591263, 6163.9383606163365, 6186.505079593531, 6187.0, 6102.156009074812, 6114.054067497291, 6130.0312685453755, 6139.553468857593, 6147.708981113838, 6098.148120573121, 6119.563485741731, 6130.428711073822, 6132.819519597447, 6147.770445910746, 5804.6807425592, 5813.011787996135, 5875.353711091691, 5997.100434934795, 6046.365131661289, 4799.736014519235, 5991.221141251609, 5995.850103747442, 6023.427774361428, 6067.089936509559, 5778.451047150514, 5819.912701309399, 5825.155352473952, 5825.650460972355, 5831.096180092092], "value": 5649.670171434353, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [473.495727050691, 491.6018025399427, 519.6855902179182, 523.2987796352886, 556.7772890843664, 36674.816625916865, 38837.611623883786, 38933.0, 38972.610273897255, 38992.22015559687, 38617.0, 39102.413463797995, 39109.41335879964, 39115.21769564607, 39262.21475570487, 8204.999999999985, 8985.910140898573, 9381.343305968585, 9891.060349266809, 9903.356281841658, 9634.566444510014, 9730.56212470437, 9757.951210243938, 9779.217662586976, 9799.559019844088, 9783.315167938246, 9812.264080193978, 9814.901850981505, 9825.164860986824, 9843.507824608776, 9725.659601913934, 9780.853287200698, 9780.951095244549, 9793.412395256308, 9822.116009559173, 8523.914760852436, 9586.233101351945, 9680.612775488971, 9790.167835733972, 9810.21518278536, 38362.61637383619, 38698.613013869784, 38738.0, 38751.03122421934, 38759.8062009691, 3207.7273431758326, 3371.3088816792497, 3382.289719158955, 3404.438267685829, 3420.7263418926673, 30574.54138187929, 30733.38533229323, 31353.686463135306, 31393.52909706356, 31536.526952095737, 2712.609238451935, 2925.414917016584, 3278.1640681626272, 3369.2082360645227, 3420.6921377076183, 398160.0, 493378.53310733574, 495470.0, 495963.0, 505267.0, 57485.13772293419, 58559.36482858282, 58639.70680146572, 58664.0, 58785.11822322669, 52062.73968630133, 61759.691201543705, 61773.691131544954, 61825.690871545354, 61865.38134618686, 14488.637784055272, 16190.352385904433, 16504.587385315463, 16540.83459165417, 16957.830421695624, 12798.040146988827, 12872.742545149045, 12887.54893578739, 12895.290759008345, 12902.483900643869, 15504.379824806882, 17966.281348746164, 18092.819071809114, 18174.091295435177, 18174.728158980128, 5387.299651045339, 7894.052713674396, 7897.447178697551, 7958.482698624571, 8049.51702897828, 855.8673405622138, 955.5461155950868, 1000.1148983149965, 1001.4191768774093, 1055.8280308857163, 29059.273518161794, 29130.689118989696, 29131.85434072816, 29996.400071998873, 30001.849990749906, 1053.167997282154, 1071.266182664877, 1080.3409919948824, 1087.8748943871399, 1129.949152288149, 14646.26768661584, 15189.544313670607, 15223.695526089416, 15609.687806243812, 15682.294296756674, 671070.0, 676325.5653495506, 680502.0, 686035.0, 1001056.0, 55665.443345566026, 56479.0, 56631.71684141553, 56668.0, 56898.0, 1370.4312710225145, 1393.4217299820461, 1415.490423447558, 1417.6597616572155, 1418.8793952513945, 29034.274143145747, 29515.262118447212, 29527.85236073806, 29627.851860740557, 30429.391412172074, 48128.27807582819, 48633.5136648629, 48674.756626218055, 48761.51238487712, 48823.0235395283, 639627.0, 645941.0, 645989.0, 646074.0, 646676.0, 25359.6196057063, 26040.08859689902, 26186.86906565455, 26204.73795262099, 26297.290676105993, 32870.506942395405, 37495.81252093722, 43976.34035489534, 44846.551534485545, 46676.833079171935, 35347.46978795371, 36378.088163248896, 37496.81251593724, 37734.245315092994, 37785.433218501195, 29528.96648617288, 36350.8182459086, 36361.27277454383, 37279.0, 37619.623803762704, 33946.83026584851, 34058.14854628555, 34190.97427077192, 36329.63670363263, 36642.08394790045, 31225.37549249048, 32068.358632826745, 32145.678543215203, 32474.02577922666, 32787.50818737769, 21675.132994680036, 22801.88599056994, 24335.02659893655, 24739.381515462257, 24949.88326926037], "value": 19638.130785073838, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [345.9655629667296, 363.25895173845373, 370.52757733889285, 377.22103855538313, 394.5876558995849, 15582.766258506117, 16892.66214675706, 21025.47436314093, 21549.892250538724, 21651.025703843345, 20320.898395508, 20710.27514037009, 21090.89454552725, 21442.785572144276, 21624.351269461942, 5457.290552228224, 5859.882802343972, 6056.848578785543, 6188.690565471731, 6214.098955651428, 4144.751314921099, 5602.7758889644365, 5928.992071347881, 6098.115773212881, 6217.689115544227, 4353.782310884443, 4371.14762621289, 4863.781129849148, 6725.7309707611885, 6782.389584937355, 5597.608167428281, 6475.676216189218, 6769.0861733665815, 6801.081853949728, 6806.591604503721, 5824.213731146326, 5857.912131317991, 5858.648481091148, 5862.384449632812, 5866.061430171152, 26032.73967260322, 26453.735462645513, 27147.72852271472, 27364.726352736416, 27551.724482755315, 3041.0, 3129.04564107947, 3281.278118813857, 3324.4212520895594, 3424.537687412217, 16165.676686466322, 16508.926919750178, 16576.419825306057, 16614.086225257604, 17504.562385940455, 1997.3408775104326, 2092.7279453670926, 2111.461577297779, 2115.5345823918715, 2199.3951663292564, 89441.0, 135588.0, 139818.0, 148561.0, 150177.49822501745, 20417.89552611847, 21281.255156069463, 21300.573988520144, 21513.56972860534, 21571.67642485364, 34447.65552344495, 34452.82773586116, 34487.13782155416, 34531.827340863136, 34647.65352346495, 10613.310134841366, 10627.946860265649, 10813.459327033617, 10900.291481053706, 11054.336739795477, 8943.776405589782, 8944.0, 8963.238124759338, 9129.77175570616, 9138.81722365549, 9194.862077068849, 9514.47670378122, 9665.951670241604, 9666.323357365058, 9682.419054856731, 3417.179876829543, 3450.9309813803584, 3617.2403795202777, 3711.888643340704, 3751.399776035821, 775.2944820213579, 843.9451435656663, 861.0992205309956, 1001.484235618657, 1034.6637342863598, 20261.797382026285, 20849.957502124835, 20872.89563552203, 20933.0, 21109.410597578255, 916.1800188831093, 929.2937367600537, 944.4663764972905, 953.6233187890745, 1002.1431675917132, 10180.898191018143, 10250.537720067012, 10909.598631687526, 10993.230473866752, 10999.780004400027, 626052.0, 627187.0, 641889.0, 643132.0, 660993.0, 128822.71177288295, 134618.32690836483, 139773.99999999796, 140279.0, 141745.24292813696, 970.2335155227291, 990.2375171118199, 1006.7030226083469, 1012.0436187802457, 1012.5291739341242, 14612.415503379745, 18848.52878677989, 19029.524261893563, 19053.380462660276, 19068.95120768371, 54534.45465545294, 54752.45247547473, 55265.72367138138, 56031.0, 56132.43867561436, 77907.598778116, 78900.21099789158, 80368.59815700885, 80653.19346806456, 81077.59461202892, 6508.991106378518, 7629.694812207672, 7667.616619169131, 7920.326772224195, 8232.259096681328, 11498.54005839757, 12881.677958051123, 12970.092093553361, 13152.145110568164, 13251.867481325064, 11035.944820275847, 11064.336139831314, 11260.211785175286, 11376.089912807121, 11418.0, 10992.835107473235, 11003.284786488852, 11127.387993660102, 11144.944275278573, 11237.4124939353, 9350.25197984146, 11424.600138995096, 11547.653570392902, 11559.595414160463, 11663.41682915834, 7806.960965195365, 8048.476849004796, 8084.636191371284, 8291.792705182177, 8313.584320783815, 10334.689959301233, 10859.728506787393, 11308.547658093585, 11338.546458141913, 11349.48927298257], "value": 10810.170555337198, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1368.7262547490504, 1540.7765873948279, 1545.6599548099414, 1732.8353806388388, 1874.1847296426056, 7594.0, 8146.063202731688, 8446.746597602074, 8690.565471726404, 8696.304295656333, 8591.785205369868, 8643.740687779376, 8655.480671159736, 8657.783555411117, 8658.783530411743, 2332.8483648562874, 2359.277727259102, 2364.385259832439, 2366.538524987628, 2372.8813559321966, 2316.2240649382393, 2360.752121027293, 2379.0848367064505, 2380.5833979053596, 2382.5115851250453, 2350.435895385104, 2370.7629237076326, 2376.7504412036747, 2387.940301492459, 2392.3779817247555, 2321.419645088728, 2353.846999944998, 2359.23324919401, 2362.598358279097, 2374.1453076892303, 2318.2002209237785, 2345.2143531916827, 2349.295211436562, 2349.5770761262966, 2352.1532248390567, 8617.913820861837, 8635.568221588896, 8692.43499172558, 8717.864123289772, 8741.30069594431, 5103.311053007871, 5110.105731496973, 5264.552513036397, 5276.4459731728, 5276.657017293864, 2934.53047512397, 3633.7274704397278, 3636.945445818315, 3641.635836416364, 3652.1052342176336, 1035.891231420705, 1050.453764042701, 1054.4674939155677, 1070.6520380876245, 1072.780080083581, 11191.552337906474, 11328.98039176478, 11329.376884271362, 11350.0, 11358.71603209918, 4312.913741725148, 4410.647148228134, 4423.601875831127, 4476.753778542212, 4561.224591819332, 8929.955350223338, 9052.592633331516, 9055.728328150166, 9058.909410905939, 9066.773330666787, 5146.2280657900865, 5150.948490515122, 5152.587792976553, 5156.046131465616, 5157.252198431282, 4347.586979236968, 4484.573965473275, 4671.462781780071, 4803.327534145223, 4827.61379089672, 3347.330533893231, 3643.307771523402, 3695.279420512974, 3750.5499340079364, 3780.678642315379, 1141.178351586856, 1153.5731779241735, 1157.438642258504, 1164.394514852272, 1168.5909931524068, 834.1991687979607, 880.7974165941869, 884.6815146547237, 903.6295119001178, 917.7567944494714, 5335.932813437328, 5672.177534257592, 5822.7088645567555, 5903.114532820111, 6049.364816694272, 415.84198004757997, 418.4518281051771, 451.9638428925744, 452.1386758225585, 454.14620513435193, 3262.5432439458496, 3798.2213646202463, 3982.820773065219, 3995.0, 4123.793810309473, 10379.429131397847, 10408.0, 10526.31578947366, 10674.62638807638, 10710.839337409945, 10404.219683523615, 10568.207384446043, 10626.415547144981, 10653.627123050812, 10671.932806719422, 7866.488678235896, 7918.564478953713, 8087.959560202161, 8118.3099436548555, 8159.714409995621, 4738.526147385372, 5135.3324067870935, 5368.845698174911, 5890.357409707262, 5968.22413086296, 8211.95894020526, 9313.627454902, 9709.36889102206, 9863.852042219229, 9890.060444257784, 9468.668596599086, 9734.805303894023, 9854.950725246328, 9861.161801246973, 10050.346727462691, 5934.813037392625, 6652.667366631746, 7379.520331178456, 7409.481336306407, 7489.775306740807, 8221.712240071569, 8418.95790521068, 8426.199511046441, 8432.747017589481, 8438.324934005142, 7366.410687145123, 7407.2592740725495, 7418.703251870081, 7489.400847932261, 7571.848563028599, 7078.752243671447, 7241.674124664508, 7287.526310789782, 7289.0, 7332.596707180943, 6772.932270677427, 6797.1843378794865, 6849.1096157499205, 6861.691223895036, 6880.965595171992, 4885.9022819544125, 4903.117438861039, 4926.039422312615, 4953.777080031336, 5053.7473126344275, 4896.510348965217, 4911.484294149063, 4954.380702412199, 5022.673526220784, 5031.84904452867], "value": 4386.829262688745, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [297.29096105787704, 300.82552119770526, 310.45515120962716, 313.78348939231944, 313.7913287663704, 4631.0, 4813.916868704539, 5220.216967454884, 5253.369595648523, 5324.988252232073, 4728.3380326754195, 4772.546608072236, 4867.732274724889, 5005.6496045276845, 5163.63854530183, 3405.5402520659763, 3953.892909985209, 4024.7925622313337, 4054.797260136996, 4085.673146148316, 3366.713829324498, 3584.086058055192, 4047.0, 4075.3071977763852, 4086.5504794472586, 3292.901212963615, 3554.822258887058, 3981.263466258737, 4004.5783746769916, 4020.3768415895584, 3435.0, 3980.20395920817, 3994.400839874013, 4012.9799351003057, 4022.517297924268, 2892.421515696848, 3310.3710295043866, 3477.287156133012, 3484.4250698634696, 3485.35520928629, 4227.492700875884, 4444.933326000112, 5121.8463446096675, 5140.820071297487, 5265.815696450606, 4402.7798610069685, 4549.886252843672, 4557.248054071074, 4565.155446242456, 4572.908541829145, 4291.785410729451, 4604.58558729716, 4814.6870453420415, 5482.887834124406, 5523.944760552424, 3532.5584301962253, 3597.6582224688614, 3796.90507737306, 3827.464155018272, 3857.1514266861245, 5564.916526252109, 5893.469587737081, 5905.527557795366, 5914.2311499504785, 5950.613210141327, 3396.1679388549965, 3591.874284400086, 3746.901735028393, 3760.52993375828, 3823.1397935464297, 3177.570093457953, 3917.5494818095713, 3924.4505769192338, 3926.7447615904875, 3961.6830653547645, 3435.587729472479, 3924.0961688350417, 4571.337156112345, 4581.610563102174, 4594.839180628662, 5000.174971129795, 5289.3917199521775, 5527.9220551991975, 5631.662100273997, 5680.233168522279, 1175.5532897498895, 1184.4314728930162, 1190.15498995714, 1290.470906928155, 1334.658668038615, 871.8926962757263, 894.959726812295, 899.8155378147464, 904.1636486250324, 904.3495620492628, 4321.546237645002, 4604.493505714368, 4885.018709751541, 5155.896882062338, 5159.793608255703, 4936.851894443173, 5332.546733527616, 5697.6296540724725, 5705.857353566111, 5746.971265143731, 5086.23706444029, 5104.566111880457, 5109.952459745743, 5114.974425127851, 5131.358580177478, 2190.6636951459595, 2235.407616981469, 2453.754624537532, 2482.8013758899247, 2497.987510062438, 3964.3746064113575, 4459.487158976694, 4479.0, 4627.894308089811, 4790.209615413418, 4410.867673969786, 4427.299347398084, 4514.954850451519, 4794.712317260975, 4860.927086093711, 3876.4185372194397, 4820.758962051884, 4824.179889418807, 4828.903421931612, 4838.685485443435, 4392.945693033713, 4448.5551444855255, 4663.473783566485, 4670.64970127235, 4719.952800471951, 3948.6702845321397, 3999.1002024544246, 4307.569243075667, 4625.687156421789, 4717.2216584263215, 4141.81361838724, 4866.391701037371, 4885.731284779371, 4897.706137631753, 4898.0, 3295.719863811603, 3337.5327454155954, 3590.31783961057, 3666.6699997000396, 3746.1196618795107, 5637.238972738792, 5645.604807663424, 5667.744951477111, 5678.886422271449, 5760.596758226884, 4863.416390033219, 4869.683470574401, 5519.944800551943, 5525.806596769094, 5565.16522521625, 5168.0, 5561.886483136383, 5573.832785016456, 5650.485533937358, 5664.886702266014, 4576.4737055239075, 5490.10591345985, 5512.862178445571, 5515.806946757005, 5534.225208470737, 5271.578273738169, 5559.471145435038, 5582.720863956704, 5611.551075913999, 5650.802221922213, 4746.762661866824, 5037.773300201573, 5444.861904952344, 5508.394076651645, 5513.365962914156], "value": 3850.0018092833143, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [568.7525926222094, 686.5846163071342, 804.8913396691448, 813.7518056992615, 820.3806126374586, 15303.846961530382, 15768.84231157688, 16295.266712997915, 16304.429344972928, 16576.08831514266, 14762.557123286291, 14913.0, 14926.925365373158, 14933.253337333144, 14963.401463941429, 675.4090171100287, 838.7399906029135, 859.866720658299, 860.3375400941271, 860.8536548786722, 701.7403560682529, 830.6511265268601, 860.3332417376552, 872.0102683454294, 876.4128034217058, 917.3257655623112, 918.4902379179553, 919.3564504846601, 920.6547544670749, 927.7402327348319, 706.131458306281, 913.0914739833875, 914.0676509959837, 915.8992510823804, 922.2115091596722, 502.138831903284, 541.7345500704627, 584.1500616602833, 670.2024590737057, 671.1811589860363, 12435.689107772288, 15172.165530895796, 15202.315895784715, 15226.238688065663, 15232.314545845464, 14050.508232211823, 14244.430222791076, 14439.494617688331, 14457.638559036002, 14743.483978060716, 12129.69675758113, 12929.405169976268, 12999.0, 13189.406476708475, 13324.733505329936, 4486.327050942352, 4490.658971616216, 4522.253828118356, 4541.772911354452, 4696.765161741933, 115363.99999999916, 117328.0, 118722.0, 118974.0, 119345.0, 9311.44131352121, 11053.834192487118, 11067.72330691739, 11252.26860254081, 11831.763364732657, 22478.100875964778, 22753.544929101325, 22771.0, 22799.202027928943, 22805.0, 7958.920410795934, 7967.3626109911065, 8093.635786389626, 8137.430379873352, 8175.305099066527, 3610.765300255475, 4333.696641235083, 4406.237504997963, 4414.889627759332, 4595.816167353269, 3296.5055241713935, 3458.0, 3471.635478274745, 3473.0622731862254, 3478.4608385700258, 535.9624826262202, 582.8630271886103, 589.6933594530906, 602.978895738647, 610.078781040623, 527.3460908473542, 550.7439040845945, 606.7239406070228, 628.7359309090193, 645.6997496164306, 8600.5699715014, 8947.881514810648, 9040.0, 9264.397814142194, 9374.329389101593, 587.6063037764708, 589.3605438099625, 602.208096353294, 633.499535367064, 651.2998526583847, 4293.441852559147, 4572.74849883253, 5006.956867421998, 5023.120320442201, 5181.222816577543, 151838.48161518463, 152470.0, 155631.22184389006, 159142.2042889778, 165785.0, 106047.0, 106294.0, 108056.37915431274, 141406.0, 145933.0, 67305.32694672991, 71507.0, 104826.47586762017, 108072.0, 111791.0, 7850.6074696266105, 8927.196552310064, 9715.611375544899, 9791.265655075755, 9802.313838031268, 59104.704476477345, 60076.699616503385, 60302.69848650729, 60389.698051511215, 60950.695246523486, 91361.17277654543, 91790.08209917815, 92460.53769731108, 93492.0, 94248.0, 12893.290869002294, 12927.935360323138, 12947.5468358607, 13095.934520327337, 13438.932805335911, 10896.509657065293, 14740.336684849422, 14831.40674373013, 15154.772678409612, 15593.76609350838, 14152.646183845487, 14182.787258190929, 14205.857941420454, 14376.784348234994, 14511.564653060424, 13482.090193844067, 13597.541507024393, 14066.718665626835, 14290.214038227998, 14337.713245735236, 13518.797218041935, 13611.659708507366, 13725.519606813714, 13814.0, 14504.0, 11358.26171298863, 11659.417029148679, 11686.415679215836, 11712.414379281172, 11797.081471858357, 11066.648681863811, 11417.31496110236, 11488.080953523866, 11494.367809770209, 11674.94162529182], "value": 7818.534517233268, "unit": "score"}], "name": "dromaeo_css", "value": 7355.702839698283, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 112.0, 115.0, 111.0, 113.0, 110.0, 113.0, 111.0, 113.0, 121.0], "value": 113.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 146.0, 120.0, 190.0, 138.0, 163.0, 128.0, 108.0, 161.0, 109.0], "value": 144.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [188.0, 184.0, 182.0, 184.0, 186.0, 184.0, 182.0, 183.0, 183.0, 179.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 75.0, 77.0, 152.0, 58.0, 57.0, 57.0, 68.0, 140.0, 57.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 134.0, 70.0, 75.0, 90.0, 118.0, 70.0, 70.0, 121.0], "value": 91.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 87.0, 90.0, 88.0, 89.0, 87.0, 92.0, 87.0, 90.0, 87.0], "value": 88.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 116.0, 150.0, 95.0, 95.0, 95.0, 95.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 81.0, 91.0, 69.0, 126.0, 134.0, 95.0, 85.0, 84.0], "value": 93.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 53.0, 55.0, 56.0, 54.0, 55.0, 56.0, 55.0, 54.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 66.0, 75.0, 81.0, 67.0, 73.0, 72.0, 68.0, 69.0, 72.0], "value": 70.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 84.0, 87.0, 83.0, 81.0, 84.0, 83.0, 86.0, 90.0, 87.0], "value": 85.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 157.0, 148.0, 135.0, 138.0, 135.0, 139.0, 146.0, 221.0, 138.0], "value": 150.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 167.0, 167.0, 167.0, 166.0, 170.0, 169.0, 167.0, 169.0, 166.0], "value": 167.9, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 85.0, 86.0, 87.0, 88.0, 70.0, 67.0, 66.0, 65.0, 66.0], "value": 74.5, "unit": "score"}], "name": "kraken", "value": 1502.4, "alertThreshold": 2.0}]} 10:18:26 INFO - Return code: 0 10:18:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:18:26 INFO - # TBPL SUCCESS # 10:18:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:18:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:18:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:18:26 INFO - 2016-08-23 10:18:26.606 screenresolution[1667:9910] starting screenresolution argv=screenresolution get 10:18:26 INFO - 2016-08-23 10:18:26.609 screenresolution[1667:9910] Display 0: 1600x1200x32@60 10:18:26 INFO - 2016-08-23 10:18:26.614 screenresolution[1668:9913] starting screenresolution argv=screenresolution list 10:18:26 INFO - Available Modes on Display 0 10:18:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:18:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:18:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:18:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:18:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:18:26 INFO - Intel Iris: 10:18:26 INFO - Chipset Model: Intel Iris 10:18:26 INFO - Type: GPU 10:18:26 INFO - Bus: Built-In 10:18:26 INFO - VRAM (Dynamic, Max): 1536 MB 10:18:26 INFO - Vendor: Intel (0x8086) 10:18:26 INFO - Device ID: 0x0a2e 10:18:26 INFO - Revision ID: 0x0009 10:18:26 INFO - Displays: 10:18:26 INFO - Display: 10:18:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:18:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:18:26 INFO - Display Serial Number: 1600x1200 60 10:18:26 INFO - Main Display: Yes 10:18:26 INFO - Mirror: Off 10:18:26 INFO - Online: Yes 10:18:26 INFO - Rotation: Supported 10:18:26 INFO - Return code: 0 10:18:26 INFO - Running post-action listener: _resource_record_post_action 10:18:26 INFO - [mozharness: 2016-08-23 17:18:26.850674Z] Finished run-tests step (success) 10:18:26 INFO - Running post-run listener: _resource_record_post_run 10:18:26 INFO - Total resource usage - Wall time: 1215s; CPU: 53.0%; Read bytes: 38339584; Write bytes: 705084928; Read time: 375; Write time: 2099 10:18:26 INFO - TinderboxPrint: CPU usage
53.5% 10:18:26 INFO - TinderboxPrint: I/O read bytes / time
38,339,584 / 375 10:18:26 INFO - TinderboxPrint: I/O write bytes / time
705,084,928 / 2,099 10:18:26 INFO - TinderboxPrint: CPU idle
2,262.8 (46.5%) 10:18:26 INFO - TinderboxPrint: CPU system
973.2 (20.0%) 10:18:26 INFO - TinderboxPrint: CPU user
1,625.6 (33.4%) 10:18:26 INFO - TinderboxPrint: Swap in / out
444,039,168 / 0 10:18:26 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 403296768; Write bytes: 397534720; Read time: 30379; Write time: 936 10:18:26 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36217856; Write bytes: 295659520; Read time: 350; Write time: 1126 10:18:27 INFO - Running post-run listener: _upload_blobber_files 10:18:27 INFO - Blob upload gear active. 10:18:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:18:27 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 10:18:27 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:18:27 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:18:27 INFO - (blobuploader) - INFO - Open directory for files ... 10:18:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:18:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:18:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:28 INFO - (blobuploader) - INFO - Done attempting. 10:18:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:18:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:29 INFO - (blobuploader) - INFO - Done attempting. 10:18:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:18:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:18:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:18:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:18:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:18:32 INFO - (blobuploader) - INFO - Done attempting. 10:18:32 INFO - (blobuploader) - INFO - Iteration through files over. 10:18:32 INFO - Return code: 0 10:18:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:18:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:18:32 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02cf5c346a9d5fad7a795b61004483caf75a3706f0e098320b2959e684ddf5ecdaa84b6cd801b329f397f0f72b1a128a572e24dc9e110c92c145cd75b12aa5f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a10d0857c826510ca6a53793ef9f74a005ca18ea0631db1f860287de0895b12b014b72a620c02ab974045db95c44ba731391eab28ade6f1afa18ac87c49343ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd14e126e6643d224a6d20d03f6d6a8633a5be6889dfa5102da0f2c44326b0370673928ac915441ad275e3ad95a43f06ef10b8a81441bd5f1e9f19d7ed02fdb8"} 10:18:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:18:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:18:32 INFO - Contents: 10:18:32 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02cf5c346a9d5fad7a795b61004483caf75a3706f0e098320b2959e684ddf5ecdaa84b6cd801b329f397f0f72b1a128a572e24dc9e110c92c145cd75b12aa5f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a10d0857c826510ca6a53793ef9f74a005ca18ea0631db1f860287de0895b12b014b72a620c02ab974045db95c44ba731391eab28ade6f1afa18ac87c49343ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd14e126e6643d224a6d20d03f6d6a8633a5be6889dfa5102da0f2c44326b0370673928ac915441ad275e3ad95a43f06ef10b8a81441bd5f1e9f19d7ed02fdb8"} 10:18:32 INFO - Running post-run listener: copy_logs_to_upload_dir 10:18:32 INFO - Copying logs to upload dir... 10:18:32 INFO - mkdir: /builds/slave/test/build/upload/logs 10:18:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.320116 ========= master_lag: 3.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 20 secs) (at 2016-08-23 10:18:36.348648) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 10:18:36.349784) ========= 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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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/Firefox/sha512/02cf5c346a9d5fad7a795b61004483caf75a3706f0e098320b2959e684ddf5ecdaa84b6cd801b329f397f0f72b1a128a572e24dc9e110c92c145cd75b12aa5f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a10d0857c826510ca6a53793ef9f74a005ca18ea0631db1f860287de0895b12b014b72a620c02ab974045db95c44ba731391eab28ade6f1afa18ac87c49343ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd14e126e6643d224a6d20d03f6d6a8633a5be6889dfa5102da0f2c44326b0370673928ac915441ad275e3ad95a43f06ef10b8a81441bd5f1e9f19d7ed02fdb8"} build_url:https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011217 build_url: 'https://queue.taskcluster.net/v1/task/KZ9wpam2TX6n5dQGebaW3w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/02cf5c346a9d5fad7a795b61004483caf75a3706f0e098320b2959e684ddf5ecdaa84b6cd801b329f397f0f72b1a128a572e24dc9e110c92c145cd75b12aa5f5", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/a10d0857c826510ca6a53793ef9f74a005ca18ea0631db1f860287de0895b12b014b72a620c02ab974045db95c44ba731391eab28ade6f1afa18ac87c49343ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bd14e126e6643d224a6d20d03f6d6a8633a5be6889dfa5102da0f2c44326b0370673928ac915441ad275e3ad95a43f06ef10b8a81441bd5f1e9f19d7ed02fdb8"}' ========= master_lag: 0.15 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-23 10:18:36.515128) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 10:18:36.515457) ========= 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.TYMUVjHnN5/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.6wBSCz3VJo/Listeners TMPDIR=/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/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.005672 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-23 10:18:36.544004) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 10:18:36.544301) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-23 10:18:36.544617) ========= ========= Total master_lag: 3.91 =========