builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0123 starttime: 1471339922.23 results: success (0) buildid: 20160815234825 builduid: 37c6dc186a564643b5b4d6bb1350f1cb revision: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.229943) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.230391) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.230687) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.255882) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.256181) ========= 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.uAdyogPOwz/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.SFENF2xQsy/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-16 02:32:02-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 193M=0s 2016-08-16 02:32:02 (193 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.153809 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.432652) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.432998) ========= 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.uAdyogPOwz/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.SFENF2xQsy/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.028561 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:02.483437) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 02:32:02.483809) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev cc8350ff1a9ba2e9754d9d2d98063e864f38c655 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev cc8350ff1a9ba2e9754d9d2d98063e864f38c655 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uAdyogPOwz/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.SFENF2xQsy/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-16 02:32:02,562 truncating revision to first 12 chars 2016-08-16 02:32:02,562 Setting DEBUG logging. 2016-08-16 02:32:02,562 attempt 1/10 2016-08-16 02:32:02,562 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/cc8350ff1a9b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-16 02:32:03,632 unpacking tar archive at: fx-team-cc8350ff1a9b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.322593 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-16 02:32:03.828937) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:03.829267) ========= script_repo_revision: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:03.829812) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:03.830088) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-16 02:32:03.849122) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-16 02:32:03.849416) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.uAdyogPOwz/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.SFENF2xQsy/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 02:32:04 INFO - MultiFileLogger online at 20160816 02:32:04 in /builds/slave/test 02:32:04 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 02:32:04 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 02:32:04 INFO - {'append_to_log': False, 02:32:04 INFO - 'base_work_dir': '/builds/slave/test', 02:32:04 INFO - 'blob_upload_branch': 'Fx-Team', 02:32:04 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 02:32:04 INFO - 'branch': 'Fx-Team', 02:32:04 INFO - 'buildbot_json_path': 'buildprops.json', 02:32:04 INFO - 'config_files': ('talos/mac_config.py',), 02:32:04 INFO - 'default_actions': ('clobber', 02:32:04 INFO - 'read-buildbot-config', 02:32:04 INFO - 'download-and-extract', 02:32:04 INFO - 'populate-webroot', 02:32:04 INFO - 'create-virtualenv', 02:32:04 INFO - 'install', 02:32:04 INFO - 'run-tests'), 02:32:04 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 02:32:04 INFO - 'download_minidump_stackwalk': True, 02:32:04 INFO - 'download_symbols': 'ondemand', 02:32:04 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 02:32:04 INFO - 'tooltool.py': '/tools/tooltool.py', 02:32:04 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 02:32:04 INFO - '/tools/misc-python/virtualenv.py')}, 02:32:04 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 02:32:04 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 02:32:04 INFO - 'installer_path': 'installer.exe', 02:32:04 INFO - 'log_level': 'info', 02:32:04 INFO - 'log_name': 'talos', 02:32:04 INFO - 'log_to_console': True, 02:32:04 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 02:32:04 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 02:32:04 INFO - 'opt_config_files': (), 02:32:04 INFO - 'pip_index': False, 02:32:04 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:32:04 INFO - 'cmd': ('bash', 02:32:04 INFO - '-c', 02:32:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:32:04 INFO - 'enabled': True, 02:32:04 INFO - 'halt_on_failure': False, 02:32:04 INFO - 'name': 'check_screen_resolution'},), 02:32:04 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 02:32:04 INFO - 'cmd': ('bash', 02:32:04 INFO - '-c', 02:32:04 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 02:32:04 INFO - 'enabled': True, 02:32:04 INFO - 'halt_on_failure': False, 02:32:04 INFO - 'name': 'check_screen_resolution'},), 02:32:04 INFO - 'run_cmd_checks_enabled': True, 02:32:04 INFO - 'sps_profile': False, 02:32:04 INFO - 'sps_profile_interval': 0, 02:32:04 INFO - 'suite': 'dromaeojs', 02:32:04 INFO - 'system_bits': '32', 02:32:04 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 02:32:04 INFO - 'title': 't-yosemite-r7-0123', 02:32:04 INFO - 'tooltool_cache': '/builds/tooltool_cache', 02:32:04 INFO - 'use_talos_json': True, 02:32:04 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 02:32:04 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 02:32:04 INFO - 'work_dir': 'build'} 02:32:04 INFO - [mozharness: 2016-08-16 09:32:04.045982Z] Running clobber step. 02:32:04 INFO - Running pre-action listener: _resource_record_pre_action 02:32:04 INFO - Running main action method: clobber 02:32:04 INFO - rmtree: /builds/slave/test/build 02:32:04 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 02:32:06 INFO - Running post-action listener: _resource_record_post_action 02:32:06 INFO - [mozharness: 2016-08-16 09:32:06.184582Z] Finished clobber step (success) 02:32:06 INFO - [mozharness: 2016-08-16 09:32:06.184747Z] Running read-buildbot-config step. 02:32:06 INFO - Running pre-action listener: _resource_record_pre_action 02:32:06 INFO - Running main action method: read_buildbot_config 02:32:06 INFO - Using buildbot properties: 02:32:06 INFO - { 02:32:06 INFO - "project": "", 02:32:06 INFO - "product": "firefox", 02:32:06 INFO - "script_repo_revision": "production", 02:32:06 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 02:32:06 INFO - "repository": "", 02:32:06 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 02:32:06 INFO - "buildid": "20160815234825", 02:32:06 INFO - "pgo_build": "False", 02:32:06 INFO - "basedir": "/builds/slave/test", 02:32:06 INFO - "buildnumber": 90, 02:32:06 INFO - "slavename": "t-yosemite-r7-0123", 02:32:06 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 02:32:06 INFO - "platform": "yosemite_r7", 02:32:06 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 02:32:06 INFO - "branch": "fx-team", 02:32:06 INFO - "repo_path": "integration/fx-team", 02:32:06 INFO - "revision": "cc8350ff1a9ba2e9754d9d2d98063e864f38c655", 02:32:06 INFO - "stage_platform": "macosx64", 02:32:06 INFO - "builduid": "37c6dc186a564643b5b4d6bb1350f1cb", 02:32:06 INFO - "slavebuilddir": "test" 02:32:06 INFO - } 02:32:06 INFO - Found installer url https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 02:32:06 INFO - Running post-action listener: _resource_record_post_action 02:32:06 INFO - [mozharness: 2016-08-16 09:32:06.188287Z] Finished read-buildbot-config step (success) 02:32:06 INFO - [mozharness: 2016-08-16 09:32:06.188398Z] Running download-and-extract step. 02:32:06 INFO - Running pre-action listener: _resource_record_pre_action 02:32:06 INFO - Running main action method: download_and_extract 02:32:06 INFO - mkdir: /builds/slave/test/build/tests 02:32:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:06 INFO - trying https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 02:32:06 INFO - Downloading https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:32:06 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 02:32:07 INFO - Downloaded 1199 bytes. 02:32:07 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 02:32:07 INFO - Using the following test package requirements: 02:32:07 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:32:07 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 02:32:07 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 02:32:07 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'jsshell-mac.zip'], 02:32:07 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 02:32:07 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 02:32:07 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 02:32:07 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 02:32:07 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 02:32:07 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 02:32:07 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 02:32:07 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 02:32:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:07 INFO - trying https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:32:07 INFO - Downloading https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:32:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:32:08 INFO - Downloaded 16332077 bytes. 02:32:08 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 02:32:15 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 02:32:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:15 INFO - trying https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:32:15 INFO - Downloading https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 02:32:15 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 02:32:16 INFO - Downloaded 16332077 bytes. 02:32:16 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 02:32:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:22 INFO - trying https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:32:22 INFO - Downloading https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 02:32:22 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 02:32:24 INFO - Downloaded 14413719 bytes. 02:32:24 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 02:32:24 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:24 INFO - trying https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:32:24 INFO - Downloading https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 02:32:24 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 02:32:27 INFO - Downloaded 111104257 bytes. 02:32:27 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:32:27 INFO - mkdir: /builds/slave/test/properties 02:32:27 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 02:32:27 INFO - Writing to file /builds/slave/test/properties/build_url 02:32:27 INFO - Contents: 02:32:27 INFO - build_url:https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 02:32:28 INFO - Running post-action listener: _resource_record_post_action 02:32:28 INFO - Running post-action listener: set_extra_try_arguments 02:32:28 INFO - [mozharness: 2016-08-16 09:32:28.311480Z] Finished download-and-extract step (success) 02:32:28 INFO - [mozharness: 2016-08-16 09:32:28.311597Z] Running populate-webroot step. 02:32:28 INFO - Running pre-action listener: _resource_record_pre_action 02:32:28 INFO - Running main action method: populate_webroot 02:32:28 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 02:32:28 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 02:32:28 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 02:32:28 INFO - '--tppagecycles', 02:32:28 INFO - '7'], 02:32:28 INFO - 'tests': ['tsvgm']}}, 02:32:28 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tresize', 'tcanvasmark']}, 02:32:28 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 02:32:28 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['dromaeo_css', 'kraken']}, 02:32:28 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 02:32:28 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:28 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 02:32:28 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['damp', 'tps']}, 02:32:28 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['damp', 'tps']}, 02:32:28 INFO - 'g3': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['dromaeo_dom']}, 02:32:28 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 02:32:28 INFO - 'g4': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['basic_compositor_video']}, 02:32:28 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 02:32:28 INFO - 'other': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s_l64': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['a11yr', 02:32:28 INFO - 'ts_paint', 02:32:28 INFO - 'tpaint', 02:32:28 INFO - 'sessionrestore', 02:32:28 INFO - 'sessionrestore_no_auto_restore', 02:32:28 INFO - 'tabpaint']}, 02:32:28 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tsvgx', 02:32:28 INFO - 'tsvgr_opacity', 02:32:28 INFO - 'tart', 02:32:28 INFO - 'tscrollx', 02:32:28 INFO - 'cart']}, 02:32:28 INFO - 'svgr-e10s': {'tests': ['tsvgx', 02:32:28 INFO - 'tsvgr_opacity', 02:32:28 INFO - 'tart', 02:32:28 INFO - 'tscrollx', 02:32:28 INFO - 'cart']}, 02:32:28 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s'], 02:32:28 INFO - 'tests': ['tp5o']}, 02:32:28 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'tests': ['tp5o']}, 02:32:28 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--disable-e10s', 02:32:28 INFO - '--xperf_path', 02:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:28 INFO - 'tests': ['tp5n']}, 02:32:28 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 02:32:28 INFO - 'talos_options': ['--xperf_path', 02:32:28 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 02:32:28 INFO - 'tests': ['tp5n']}}, 02:32:28 INFO - 'talos.zip': {'path': '', 02:32:28 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 02:32:28 INFO - Running post-action listener: _resource_record_post_action 02:32:28 INFO - [mozharness: 2016-08-16 09:32:28.330357Z] Finished populate-webroot step (success) 02:32:28 INFO - [mozharness: 2016-08-16 09:32:28.330468Z] Running create-virtualenv step. 02:32:28 INFO - Running pre-action listener: _resource_record_pre_action 02:32:28 INFO - Running main action method: create_virtualenv 02:32:28 INFO - Creating virtualenv /builds/slave/test/build/venv 02:32:28 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 02:32:28 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 02:32:28 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 02:32:28 INFO - Using real prefix '/tools/python27' 02:32:28 INFO - New python executable in /builds/slave/test/build/venv/bin/python 02:32:29 INFO - Installing distribute.............................................................................................................................................................................................done. 02:32:32 INFO - Installing pip.................done. 02:32:32 INFO - Return code: 0 02:32:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 02:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 02:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 02:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:32 INFO - 'HOME': '/Users/cltbld', 02:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:32 INFO - 'LOGNAME': 'cltbld', 02:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:32 INFO - 'MOZ_NO_REMOTE': '1', 02:32:32 INFO - 'NO_EM_RESTART': '1', 02:32:32 INFO - 'PAGER': '/bin/cat', 02:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:32 INFO - 'PWD': '/builds/slave/test', 02:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:32 INFO - 'SHELL': '/bin/bash', 02:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:32 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:32 INFO - 'USER': 'cltbld', 02:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:32 INFO - 'XPC_FLAGS': '0x0', 02:32:32 INFO - 'XPC_SERVICE_NAME': '0', 02:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:32 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 02:32:32 INFO - Cleaning up... 02:32:32 INFO - Return code: 0 02:32:32 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 02:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:32 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 02:32:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 02:32:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:32 INFO - 'HOME': '/Users/cltbld', 02:32:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:32 INFO - 'LOGNAME': 'cltbld', 02:32:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:32 INFO - 'MOZ_NO_REMOTE': '1', 02:32:32 INFO - 'NO_EM_RESTART': '1', 02:32:32 INFO - 'PAGER': '/bin/cat', 02:32:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:32 INFO - 'PWD': '/builds/slave/test', 02:32:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:32 INFO - 'SHELL': '/bin/bash', 02:32:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:32 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:32 INFO - 'USER': 'cltbld', 02:32:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:32 INFO - 'XPC_FLAGS': '0x0', 02:32:32 INFO - 'XPC_SERVICE_NAME': '0', 02:32:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:32 INFO - Downloading/unpacking psutil>=3.1.1 02:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:32 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:32 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:32 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:32 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 02:32:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:35 INFO - Installing collected packages: psutil 02:32:35 INFO - Running setup.py install for psutil 02:32:35 INFO - building 'psutil._psutil_osx' extension 02:32:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 02:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 02:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 02:32:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 02:32:36 INFO - building 'psutil._psutil_posix' extension 02:32:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 02:32:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 02:32:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 02:32:36 INFO - ^ 02:32:36 INFO - 1 warning generated. 02:32:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 02:32:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 02:32:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 02:32:36 INFO - Successfully installed psutil 02:32:36 INFO - Cleaning up... 02:32:36 INFO - Return code: 0 02:32:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:36 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 02:32:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 02:32:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:36 INFO - 'HOME': '/Users/cltbld', 02:32:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:36 INFO - 'LOGNAME': 'cltbld', 02:32:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:36 INFO - 'MOZ_NO_REMOTE': '1', 02:32:36 INFO - 'NO_EM_RESTART': '1', 02:32:36 INFO - 'PAGER': '/bin/cat', 02:32:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:36 INFO - 'PWD': '/builds/slave/test', 02:32:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:36 INFO - 'SHELL': '/bin/bash', 02:32:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:36 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:36 INFO - 'USER': 'cltbld', 02:32:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:36 INFO - 'XPC_FLAGS': '0x0', 02:32:36 INFO - 'XPC_SERVICE_NAME': '0', 02:32:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:37 INFO - Downloading/unpacking mozsystemmonitor==0.3 02:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:37 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:37 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:37 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:37 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 02:32:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 02:32:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 02:32:39 INFO - Installing collected packages: mozsystemmonitor 02:32:39 INFO - Running setup.py install for mozsystemmonitor 02:32:39 INFO - Successfully installed mozsystemmonitor 02:32:39 INFO - Cleaning up... 02:32:39 INFO - Return code: 0 02:32:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:39 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 02:32:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 02:32:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:39 INFO - 'HOME': '/Users/cltbld', 02:32:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:39 INFO - 'LOGNAME': 'cltbld', 02:32:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:39 INFO - 'MOZ_NO_REMOTE': '1', 02:32:39 INFO - 'NO_EM_RESTART': '1', 02:32:39 INFO - 'PAGER': '/bin/cat', 02:32:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:39 INFO - 'PWD': '/builds/slave/test', 02:32:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:39 INFO - 'SHELL': '/bin/bash', 02:32:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:39 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:39 INFO - 'USER': 'cltbld', 02:32:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:39 INFO - 'XPC_FLAGS': '0x0', 02:32:39 INFO - 'XPC_SERVICE_NAME': '0', 02:32:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:40 INFO - Downloading/unpacking jsonschema==2.5.1 02:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 02:32:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 02:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:42 INFO - Downloading functools32-3.2.3-2.tar.gz 02:32:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 02:32:43 INFO - warning: no files found matching '*.txt' 02:32:43 INFO - no previously-included directories found matching 'build' 02:32:43 INFO - no previously-included directories found matching 'dist' 02:32:43 INFO - no previously-included directories found matching '.git*' 02:32:43 INFO - Installing collected packages: jsonschema, functools32 02:32:43 INFO - Running setup.py install for functools32 02:32:43 INFO - warning: no files found matching '*.txt' 02:32:43 INFO - no previously-included directories found matching 'build' 02:32:43 INFO - no previously-included directories found matching 'dist' 02:32:43 INFO - no previously-included directories found matching '.git*' 02:32:43 INFO - Successfully installed jsonschema functools32 02:32:43 INFO - Cleaning up... 02:32:43 INFO - Return code: 0 02:32:43 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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', '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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 02:32: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 functools32==3.2.3-2 02:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:43 INFO - 'HOME': '/Users/cltbld', 02:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:43 INFO - 'LOGNAME': 'cltbld', 02:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:43 INFO - 'MOZ_NO_REMOTE': '1', 02:32:43 INFO - 'NO_EM_RESTART': '1', 02:32:43 INFO - 'PAGER': '/bin/cat', 02:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:43 INFO - 'PWD': '/builds/slave/test', 02:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:43 INFO - 'SHELL': '/bin/bash', 02:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:43 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:43 INFO - 'USER': 'cltbld', 02:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:43 INFO - 'XPC_FLAGS': '0x0', 02:32:43 INFO - 'XPC_SERVICE_NAME': '0', 02:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 02:32:43 INFO - Cleaning up... 02:32:43 INFO - Return code: 0 02:32:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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', '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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 02:32: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 blobuploader==1.2.4 02:32:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:43 INFO - 'HOME': '/Users/cltbld', 02:32:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:43 INFO - 'LOGNAME': 'cltbld', 02:32:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:43 INFO - 'MOZ_NO_REMOTE': '1', 02:32:43 INFO - 'NO_EM_RESTART': '1', 02:32:43 INFO - 'PAGER': '/bin/cat', 02:32:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:43 INFO - 'PWD': '/builds/slave/test', 02:32:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:43 INFO - 'SHELL': '/bin/bash', 02:32:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:43 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:43 INFO - 'USER': 'cltbld', 02:32:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:43 INFO - 'XPC_FLAGS': '0x0', 02:32:43 INFO - 'XPC_SERVICE_NAME': '0', 02:32:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:43 INFO - Downloading/unpacking blobuploader==1.2.4 02:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:46 INFO - Downloading blobuploader-1.2.4.tar.gz 02:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 02:32:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 02:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 02:32:47 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 02:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 02:32:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 02:32:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 02:32:47 INFO - Downloading docopt-0.6.1.tar.gz 02:32:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 02:32:47 INFO - Installing collected packages: blobuploader, requests, docopt 02:32:47 INFO - Running setup.py install for blobuploader 02:32:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 02:32:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 02:32:47 INFO - Running setup.py install for requests 02:32:48 INFO - Running setup.py install for docopt 02:32:48 INFO - Successfully installed blobuploader requests docopt 02:32:48 INFO - Cleaning up... 02:32:48 INFO - Return code: 0 02:32:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32: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')]} 02:32:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32: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')]} 02:32:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:48 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:32:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:48 INFO - 'HOME': '/Users/cltbld', 02:32:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:48 INFO - 'LOGNAME': 'cltbld', 02:32:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:48 INFO - 'MOZ_NO_REMOTE': '1', 02:32:48 INFO - 'NO_EM_RESTART': '1', 02:32:48 INFO - 'PAGER': '/bin/cat', 02:32:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:48 INFO - 'PWD': '/builds/slave/test', 02:32:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:48 INFO - 'SHELL': '/bin/bash', 02:32:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:48 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:48 INFO - 'USER': 'cltbld', 02:32:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:48 INFO - 'XPC_FLAGS': '0x0', 02:32:48 INFO - 'XPC_SERVICE_NAME': '0', 02:32:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:48 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-le9Cas-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:48 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-B32_mW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:48 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZG7EEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VYsztN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KcP6MG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-GBDlzC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-b7xz2o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-c0_pXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Qv68MJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-nn_HbE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MP2qqv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:49 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-eg8xNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:50 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-ZSf24G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:50 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-W2JV_H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:50 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-whyl1S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:50 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-4Ok5YS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:50 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-aiSQJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 02:32:50 INFO - Running setup.py install for manifestparser 02:32:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 02:32:50 INFO - Running setup.py install for mozcrash 02:32:50 INFO - Running setup.py install for mozdebug 02:32:50 INFO - Running setup.py install for mozdevice 02:32:51 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Installing dm script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Running setup.py install for mozfile 02:32:51 INFO - Running setup.py install for mozhttpd 02:32:51 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Running setup.py install for mozinfo 02:32:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Running setup.py install for mozInstall 02:32:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 02:32:51 INFO - Running setup.py install for mozleak 02:32:51 INFO - Running setup.py install for mozlog 02:32:52 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Running setup.py install for moznetwork 02:32:52 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Running setup.py install for mozprocess 02:32:52 INFO - Running setup.py install for mozprofile 02:32:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Running setup.py install for mozrunner 02:32:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 02:32:52 INFO - Running setup.py install for mozscreenshot 02:32:52 INFO - Running setup.py install for moztest 02:32:52 INFO - Running setup.py install for mozversion 02:32:53 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 02:32:53 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 02:32:53 INFO - Cleaning up... 02:32:53 INFO - Return code: 0 02:32:53 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:53 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 02:32:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:53 INFO - 'HOME': '/Users/cltbld', 02:32:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:53 INFO - 'LOGNAME': 'cltbld', 02:32:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:53 INFO - 'MOZ_NO_REMOTE': '1', 02:32:53 INFO - 'NO_EM_RESTART': '1', 02:32:53 INFO - 'PAGER': '/bin/cat', 02:32:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:53 INFO - 'PWD': '/builds/slave/test', 02:32:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:53 INFO - 'SHELL': '/bin/bash', 02:32:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:53 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:53 INFO - 'USER': 'cltbld', 02:32:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:53 INFO - 'XPC_FLAGS': '0x0', 02:32:53 INFO - 'XPC_SERVICE_NAME': '0', 02:32:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EUjBTy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 02:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-Zdiuhg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 02:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-guRiFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 02:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-LrrAQs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 02:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-dpn64d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 02:32:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 02:32:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 02:32:53 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0sN9Px-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-EWL38T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-0WtyRC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-j4Jmw8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-8cHl6l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-KPD14l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-olmyZ_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-1qWO0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-MRcRza-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-a4FWt5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 02:32:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 02:32:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 02:32:54 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-dkZDeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 02:32:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 02:32:55 INFO - Running setup.py (path:/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/pip-VzTEqB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 02:32:55 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32: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 02:32:57 INFO - Downloading blessings-1.6.tar.gz 02:32:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 02:32:57 INFO - Installing collected packages: blessings 02:32:57 INFO - Running setup.py install for blessings 02:32:57 INFO - Successfully installed blessings 02:32:57 INFO - Cleaning up... 02:32:57 INFO - Return code: 0 02:32:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 02:32:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:58 INFO - Reading from file tmpfile_stdout 02:32:58 INFO - Current package versions: 02:32:58 INFO - blessings == 1.6 02:32:58 INFO - blobuploader == 1.2.4 02:32:58 INFO - docopt == 0.6.1 02:32:58 INFO - functools32 == 3.2.3-2 02:32:58 INFO - jsonschema == 2.5.1 02:32:58 INFO - manifestparser == 1.1 02:32:58 INFO - mozInstall == 1.12 02:32:58 INFO - mozcrash == 0.17 02:32:58 INFO - mozdebug == 0.1 02:32:58 INFO - mozdevice == 0.48 02:32:58 INFO - mozfile == 1.2 02:32:58 INFO - mozhttpd == 0.7 02:32:58 INFO - mozinfo == 0.9 02:32:58 INFO - mozleak == 0.1 02:32:58 INFO - mozlog == 3.3 02:32:58 INFO - moznetwork == 0.27 02:32:58 INFO - mozprocess == 0.23 02:32:58 INFO - mozprofile == 0.28 02:32:58 INFO - mozrunner == 6.12 02:32:58 INFO - mozscreenshot == 0.1 02:32:58 INFO - mozsystemmonitor == 0.3 02:32:58 INFO - moztest == 0.7 02:32:58 INFO - mozversion == 1.4 02:32:58 INFO - psutil == 3.1.1 02:32:58 INFO - requests == 1.2.3 02:32:58 INFO - wsgiref == 0.1.2 02:32:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 02:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:58 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 02:32:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 02:32:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:58 INFO - 'HOME': '/Users/cltbld', 02:32:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:58 INFO - 'LOGNAME': 'cltbld', 02:32:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:58 INFO - 'MOZ_NO_REMOTE': '1', 02:32:58 INFO - 'NO_EM_RESTART': '1', 02:32:58 INFO - 'PAGER': '/bin/cat', 02:32:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:58 INFO - 'PWD': '/builds/slave/test', 02:32:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:58 INFO - 'SHELL': '/bin/bash', 02:32:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:58 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:58 INFO - 'USER': 'cltbld', 02:32:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:58 INFO - 'XPC_FLAGS': '0x0', 02:32:58 INFO - 'XPC_SERVICE_NAME': '0', 02:32:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 02:32:59 INFO - Cleaning up... 02:32:59 INFO - Return code: 0 02:32:59 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 02:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 02:32:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 02:32:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 02:32:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 02:32:59 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 0x1021d8a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10226d618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f85515800d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, '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.uAdyogPOwz/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.SFENF2xQsy/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 02:32:59 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 02:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 02:32:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:32:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:32:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:32:59 INFO - 'HOME': '/Users/cltbld', 02:32:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:32:59 INFO - 'LOGNAME': 'cltbld', 02:32:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:32:59 INFO - 'MOZ_NO_REMOTE': '1', 02:32:59 INFO - 'NO_EM_RESTART': '1', 02:32:59 INFO - 'PAGER': '/bin/cat', 02:32:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:32:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:32:59 INFO - 'PWD': '/builds/slave/test', 02:32:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:32:59 INFO - 'SHELL': '/bin/bash', 02:32:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:32:59 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:32:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:32:59 INFO - 'USER': 'cltbld', 02:32:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:32:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:32:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:32:59 INFO - 'XPC_FLAGS': '0x0', 02:32:59 INFO - 'XPC_SERVICE_NAME': '0', 02:32:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:32:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 02:32:59 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 02:32:59 INFO - Cleaning up... 02:32:59 INFO - Return code: 0 02:32:59 INFO - Running post-action listener: _resource_record_post_action 02:32:59 INFO - Running post-action listener: _start_resource_monitoring 02:32:59 INFO - Starting resource monitoring. 02:32:59 INFO - [mozharness: 2016-08-16 09:32:59.547022Z] Finished create-virtualenv step (success) 02:32:59 INFO - [mozharness: 2016-08-16 09:32:59.547990Z] Running install step. 02:32:59 INFO - Running pre-action listener: _resource_record_pre_action 02:32:59 INFO - Running main action method: install 02:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 02:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 02:32:59 INFO - Reading from file tmpfile_stdout 02:32:59 INFO - Detecting whether we're running mozinstall >=1.0... 02:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 02:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 02:32:59 INFO - Reading from file tmpfile_stdout 02:32:59 INFO - Output received: 02:32:59 INFO - Usage: mozinstall [options] installer 02:32:59 INFO - Options: 02:32:59 INFO - -h, --help show this help message and exit 02:32:59 INFO - -d DEST, --destination=DEST 02:32:59 INFO - Directory to install application into. [default: 02:32:59 INFO - "/builds/slave/test"] 02:32:59 INFO - --app=APP Application being installed. [default: firefox] 02:32:59 INFO - mkdir: /builds/slave/test/build/application 02:32:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 02:32:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 02:33:34 INFO - Reading from file tmpfile_stdout 02:33:34 INFO - Output received: 02:33:34 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 02:33:34 INFO - Running post-action listener: _resource_record_post_action 02:33:34 INFO - [mozharness: 2016-08-16 09:33:34.432010Z] Finished install step (success) 02:33:34 INFO - [mozharness: 2016-08-16 09:33:34.432145Z] Running run-tests step. 02:33:34 INFO - Running pre-action listener: _resource_record_pre_action 02:33:34 INFO - Running main action method: run_tests 02:33:34 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:33:34 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:33:34 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:33:34 INFO - 2016-08-16 02:33:34.446 screenresolution[1633:7723] starting screenresolution argv=screenresolution get 02:33:34 INFO - 2016-08-16 02:33:34.449 screenresolution[1633:7723] Display 0: 1600x1200x32@60 02:33:34 INFO - 2016-08-16 02:33:34.455 screenresolution[1634:7725] starting screenresolution argv=screenresolution list 02:33:34 INFO - Available Modes on Display 0 02:33:34 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:33:34 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:33:34 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:33:34 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:33:34 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:33:34 INFO - Intel Iris: 02:33:34 INFO - Chipset Model: Intel Iris 02:33:34 INFO - Type: GPU 02:33:34 INFO - Bus: Built-In 02:33:34 INFO - VRAM (Dynamic, Max): 1536 MB 02:33:34 INFO - Vendor: Intel (0x8086) 02:33:34 INFO - Device ID: 0x0a2e 02:33:34 INFO - Revision ID: 0x0009 02:33:34 INFO - Displays: 02:33:34 INFO - Display: 02:33:34 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:33:34 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:33:34 INFO - Display Serial Number: 1600x1200 60 02:33:34 INFO - Main Display: Yes 02:33:34 INFO - Mirror: Off 02:33:34 INFO - Online: Yes 02:33:34 INFO - Rotation: Supported 02:33:34 INFO - Return code: 0 02:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 02:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 02:33:34 INFO - Python 2.7.3 02:33:34 INFO - Return code: 0 02:33:34 INFO - grabbing minidump binary from tooltool 02:33: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')]} 02:33:34 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 0x10213cd30>, '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 0x101ea1db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102134b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 02:33:34 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 02:33:34 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 02:33:34 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 02:33:34 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 02:33:34 INFO - Return code: 0 02:33:34 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 02:33:34 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 02:33:34 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 02:33:34 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 02:33:34 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 02:33:34 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/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 02:33:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/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 02:33:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.uAdyogPOwz/Render', 02:33:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 02:33:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 02:33:34 INFO - 'HOME': '/Users/cltbld', 02:33:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 02:33:34 INFO - 'LOGNAME': 'cltbld', 02:33:34 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 02:33:34 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 02:33:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 02:33:34 INFO - 'MOZ_NO_REMOTE': '1', 02:33:34 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 02:33:34 INFO - 'NO_EM_RESTART': '1', 02:33:34 INFO - 'PAGER': '/bin/cat', 02:33:34 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 02:33:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 02:33:34 INFO - 'PWD': '/builds/slave/test', 02:33:34 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 02:33:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 02:33:34 INFO - 'SHELL': '/bin/bash', 02:33:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SFENF2xQsy/Listeners', 02:33:34 INFO - 'TMPDIR': '/var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/', 02:33:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 02:33:34 INFO - 'USER': 'cltbld', 02:33:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 02:33:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 02:33:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 02:33:34 INFO - 'XPC_FLAGS': '0x0', 02:33:34 INFO - 'XPC_SERVICE_NAME': '0', 02:33:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 02:33:34 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/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 02:33:35 INFO - mozversion application_buildid: 20160815234825 02:33:35 INFO - mozversion application_changeset: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 02:33:35 INFO - mozversion application_display_name: Nightly 02:33:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 02:33:35 INFO - mozversion application_name: Firefox 02:33:35 INFO - mozversion application_remotingname: firefox 02:33:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 02:33:35 INFO - mozversion application_vendor: Mozilla 02:33:35 INFO - mozversion application_version: 51.0a1 02:33:35 INFO - mozversion platform_buildid: 20160815234825 02:33:35 INFO - mozversion platform_changeset: cc8350ff1a9ba2e9754d9d2d98063e864f38c655 02:33:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 02:33:35 INFO - mozversion platform_version: 51.0a1 02:33:35 INFO - using testdate: 1471340015 02:33:35 INFO - actual date: 1471340015 02:33:35 INFO - starting webserver on 'localhost:49277' 02:33:35 INFO - SUITE-START | Running 2 tests 02:33:35 INFO - TEST-START | dromaeo_css 02:33:35 INFO - operating with platform_type : mac_ 02:33:35 INFO - Initialising browser for dromaeo_css test... 02:33:35 INFO - TEST-INFO | started process 1644 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpt81wse/profile http://localhost:49277/getInfo.html) 02:33:37 INFO - PROCESS | 1644 | __metrics Screen width/height:1600/1200 02:33:37 INFO - PROCESS | 1644 | colorDepth:24 02:33:37 INFO - PROCESS | 1644 | Browser inner width/height: 1024/689 02:33:37 INFO - PROCESS | 1644 | __metrics 02:33:37 INFO - PROCESS | 1644 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:33:37 INFO - PROCESS | 1644 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:33:38 INFO - TEST-INFO | 1644: exit 0 02:33:38 INFO - Browser initialized. 02:33:38 INFO - Running cycle 1/1 for dromaeo_css test... 02:33:38 INFO - TEST-INFO | started process 1646 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpt81wse/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:33:50 INFO - PROCESS | 1646 | RSS: Main: 228311040 02:33:50 INFO - PROCESS | 1646 | 02:34:41 INFO - PROCESS | 1646 | 1471340081937 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 02:36:54 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49277/tests/dromaeo/cssquery-ext.html) 02:36:55 INFO - PROCESS | 1646 | RSS: Main: 367218688 02:36:55 INFO - PROCESS | 1646 | 02:39:58 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-ext.html (next: http://localhost:49277/tests/dromaeo/cssquery-jquery.html) 02:39:58 INFO - PROCESS | 1646 | RSS: Main: 395857920 02:39:58 INFO - PROCESS | 1646 | 02:43:01 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49277/tests/dromaeo/cssquery-mootools.html) 02:43:01 INFO - PROCESS | 1646 | RSS: Main: 343502848 02:43:01 INFO - PROCESS | 1646 | 02:46:04 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49277/tests/dromaeo/cssquery-prototype.html) 02:46:04 INFO - PROCESS | 1646 | RSS: Main: 340172800 02:46:04 INFO - PROCESS | 1646 | 02:49:08 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49277/tests/dromaeo/cssquery-yui.html) 02:49:09 INFO - PROCESS | 1646 | RSS: Main: 335716352 02:49:09 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | Cycle 1(1): loaded http://localhost:49277/tests/dromaeo/cssquery-yui.html (next: http://localhost:49277/tests/dromaeo/cssquery-dojo.html) 02:52:12 INFO - PROCESS | 1646 | RSS: Main: 377565184 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | __start_tp_report 02:52:12 INFO - PROCESS | 1646 | _x_x_mozilla_page_load 02:52:12 INFO - PROCESS | 1646 | _x_x_mozilla_page_load_details 02:52:12 INFO - PROCESS | 1646 | |i|pagename|runs| 02:52:12 INFO - PROCESS | 1646 | |0;dojo.html;2970.88116475341;3394.2193295542024;3432.673895979884;3435.4159792835217;3464.4283693190623;5648.926703926251;5722.1988921550965;5795.246617939672;5797.956367853784;5806.651600903949;5675.177099320596;5797.739101740422;5851.122331650244;5857.3849745776715;5890.617109887854;5220.686758794484;5293.258943747878;5306.416294207634;5325.866853328659;5400.756965936523;5390.4879036491475;5393.352797664285;5406.08096623575;5410.377806552237;5430.456954304557;5221.921671174935;5274.997750427445;5289.523942845162;5297;5349.170878513836;5312.946870531284;5313.0967735485065;5320.388155362144;5321.122014867542;5328;5181.948180518222;5201.739913004372;5255.6583822051825;5261.210818377236;5265.104932161542;5726.169705392695;5781.768729250867;5786.826395208151;5796.797112101099;5807.651540907559;6313.842153946188;6320.209973753281;6322.241331040259;6332.240131184241;6333;6188.597741146811;6238.033104868752;6317.399847014526;6319.494440444753;6370.681465926685;5838.182654428383;5870.26621672291;5882.205902203234;5888.146218798251;5895.056790913433;6364.745410183541;6395.712192951329;6398.584092034003;6401.519886008568;6420.293767685551;6076.118962750376;6084.11780291864;6123.387661233841;6160.260768707694;6174.166487524217;6226.657533835682;6253.187085678924;6279.120923070774;6308.148399966038;6309.810705678837;5236.6857988520815;6189.566730328924;6312.210973628296;6312.968435157794;6316.463100636405;6244.594101383395;6290.261462675303;6329.113924050636;6349.714262858182;6359.745610175633;4135.793210339471;4887.731174785421;4958.033183529177;4990.6506544541535;5006.249062640633;4424.778761061934;4498.707584007029;4527.343535187445;4531.660125490602;4538.341940418621;5671.602987790898;5715.5427565794635;5724.398938111438;5729.1119876419225;5742.569307301969;6254.781082662176;6279.309275979638;6300.905486417708;6360.236771587439;6362.363763623694;5804.361520232771;5813.09896965971;5827.125931110368;5830.76676932918;5864.560157988082;5466.808661696901;5486.890262194734;5492.697901615449;5501.0648189807025;5519.254900588449;6261.686915654199;6336.556441049174;6367.076773867856;6403.807885763435;6483.902741458882;5247.370315562004;6312.905306420315;6342.460890824332;6346.873062538816;6347.428731414195;6468.094466774562;6471.352864713491;6510.934890651222;6513.381228783258;6522.05430212626;6106.157323464254;6150.138980542817;6189.257289125334;6263.718132684131;6285.685715714105;6273.65494897785;6351.3331100235655;6354.332795056453;6358.682065896779;6364.64994425311;6189.752409903734;6214.471769899609;6229.688515574113;6232.283287422005;6241.251049874044;5054.620903432331;5971.641701497924;6168.321484636776;6220.782272620437;6247.625142491283;5840.620359676607;5979.760809567569;5987.042073268256;6100;6100.176476175659;6211.099390588249;6218.067289906369;6225.470834979077;6237.75048998035;6242.875142497215;5615.522680572034;6045.153678485103;6074.331823499323;6091.756329746938;6094.2077529920825 02:52:12 INFO - PROCESS | 1646 | |1;ext.html;512.1396054628224;514.7014731455756;527.7941602774916;536.5921899356488;542.1677724692597;30808;34297.48553771695;40475.59524404755;42068.57931420692;44107.77946110265;42826.57173428265;43218.783906080505;43254.91862703426;43304;43359.3496097558;8867.822643547126;9758.65844695434;9913.405195688245;9931.652392166276;9932.503374831265;8922.687705930293;11896.405179741021;12120.515179392858;12146.878531214707;12417.441215145294;12092.395380230997;12115.333656648882;12126.878731212708;12155.817662735066;12224.022078233764;12184.451699673536;12274.956628686483;12280.815787763146;12330.32183229922;12364.814527782;11782.410879455992;11864;11875.52497900083;11878.465469053914;11910.225835320676;40199.79900100481;40457.59542404568;40553.18893622111;40559.594404055875;40560.79719601413;3614.132608174046;3674.8346324415465;4034.9193016139807;4039.9596004039877;4196.118815048813;31245.687543124506;31897.52153717696;31957.360852783047;32095;32315.676843231737;3209.807411555314;3310.0400883743687;3385.712214461774;3401.3367393358303;3411.9829400852836;461440;465485;465828;468695;472565;59393.40606593965;59727.70136149292;59805.10292345619;59815.70092149511;59925.10112348318;62932.05601915975;63073.68463157655;63125;63128.68435657792;63200.683996579726;15410.152441615819;15658.765118523232;15692.76460853088;15696.058236505845;15697.843021569866;11085.168612354106;11339.546418143182;11345.716357091138;12073.27560346382;12172.14794964344;14287.357068931922;15345.462908798361;15443.691126177639;15505.84494155044;17840.929544227387;4723.433091907006;6364.236291644939;6394.1687580614225;6403.167588213502;6436.517261205428;871.4858233642182;932.3007744191856;987.9407235565889;1038.849366841819;1054.1935419404163;30157;30255;30522.236944076576;30752;31220.68779312178;890.8863920099875;903.9819203615891;1046.398320965448;1064.6433444795925;1112.3159257057014;13358.532451364155;13392.397342119628;15598.9220053899;15753.606159845865;15962.12208328553;642904;647150;649228;650206;680361;58706;59764;59795.70102149461;60032.39967600355;60063.69968150131;1339.7052648417332;1374.2785037855006;1382.9792553111913;1390.2353705462158;1394.9651258718613;30613.54079688762;31129.221769455944;31307.84346078255;31989.36021279608;32139;51773.223401648254;52222.477775223284;52282;52300;52353.47646523486;580257.1974280203;581153;583840;583869;584132;22204.666929996387;25867.22398328053;26153.60769588496;26598.867005664848;26749.732502674724;32821.55851425365;35291.647083528835;39871;43194.56805432031;48542;34378.31243375068;38192.80903595464;38751.6124838748;39328.2134357317;40668.98327541835;38181.4272785914;38461.119177043154;38983.415248770725;39203.09949846468;39822.2035559293;35824.46263306105;36218.275634486636;36438;36508.90473285806;36509.45235821412;31387;33702.49446258357;34510.48234276438;34832.65167348395;35019;24970.500589988464;25412.872935635205;25614.359641009123;25913.611295830953;26112.347191319608 02:52:12 INFO - PROCESS | 1646 | |2;jquery.html;349.00881496549823;358.99641003589954;368.7676763638907;376.40527965814016;397.5309135220439;15690.52928412148;16587.668246635076;20501.589968200664;20830.89584552079;20940.685889711665;19371.90314048428;20199.899000505015;20527.794722052775;20739.06674199661;21342.786572134275;5314.362276526803;5970.611910225822;6176.320604733476;6237.968810155943;6256.874862502747;6087.20866287382;6201.503879689614;6208.78269260576;6222.502199824025;6223.004319308911;5631.042722737124;5817.592768506206;5880.059190529516;6134.969325153368;6213.980456351681;6121.540884433641;6196.101565273055;6223.875522489525;6227.3772622737815;6240.563160578761;6009.308929473123;6073.969630151865;6093.6039157454625;6108.1143234230785;6137.079438084323;13057.86942130576;13109.934450327688;13218.735625287536;13297.66755831112;13400.597982060553;2179.5422961178174;2230.185982116532;2359.1035406545575;2415.275417374798;2909.839958802265;9539.56906464029;9843.114119729258;10181.949090254502;10269.537870795757;10401.427921464316;1734.7224444088886;1871.6724573199772;1884.9340273090377;1895.924163033477;1932.3140285198692;99152.50423747835;107775;113109.43445282803;113944.43027784808;118265.99999999914;9322.673706420243;11355.829662555068;11384.715382115348;11469.31184128955;11678.532858685558;14251.50119745804;14264;14271.14373137615;14295.499657511937;14326.212058336892;7966.322862556747;8268.710595129141;8312.168783121639;8440.915590844135;8459.661613535389;8122.75631731049;8147.5111493309405;8174.468659537111;8321.209485098905;8336.374771892133;4370.781460926941;4482.394876691605;4491.752953587518;4567.908641827211;4571.6513628479015;1910.7516022916932;1926.4509614759727;1927.1616846671743;1949.483386902472;1980.9900950495444;325.3996376685012;378.2340759961101;397.7434554712194;762.2187258060421;882.2280504558511;11653.359065251492;11798.87910648487;11817.704557385961;12144.392780360946;12182.329971851455;412.57092623671645;422.84777480107135;424.51181141687306;431.4111238159889;452.87093178444644;4652.62778977681;8618.138186181457;10402.739931501621;11293.927076927679;11683.591074312528;602491;621538;626828;630759;640179;78770.21229787743;130858.22275995206;132387.33806330906;132409.77287611854;137169.62830371768;951.9190868776093;986.2898712926682;994.2741798487145;997.1474389397106;1011.0092109732503;16932.57668558296;17201.311947521957;17248.913755431142;17268.877522960967;17615.854969426946;48834.511654884416;52273.647685853466;56609.71695141662;58475.83048339094;58767.706161468916;54036.91926161376;65232;79263.2810390845;80895;81474.59262703886;8097;8137.715179968909;8227.83544329098;8334.458260213309;8412.242898139197;11915.285082895054;12791.976641868441;12929.870701292866;12946.417411216706;13206.471741130246;10731.463426828472;11104.278221915549;11113.388763618079;11116.555337786722;11688.70778230551;10056.044675755791;10552.894471055191;10911.83632245501;11201.271917325348;11368.772624547628;10229.283950123718;10539.894601053891;10985.61550345734;11250.83123753128;11362.488688008894;8002.1997800219515;8042.356611470951;8248.422610417214;8287.709930152416;8446.028706698562;10514.684559463229;10815.56737730482;11024.944875275572;11051.447427628747;11316.660500185008 02:52:12 INFO - PROCESS | 1646 | |3;mootools.html;1315.3686230609314;1447.0811034992782;1491.664375515509;1621.5864954436618;1822.608139250061;7758.069031716195;8258;8265.545395003272;8277.710280140196;8286.337093032558;8221.712240071598;8596.914030859689;8601.827963440728;8724.65101395945;8730.170633789796;2361.1263832382;2393.676853624756;2401.1715957994465;2403.759624037591;2404.7715467030694;2330.8018818400374;2388.2596395117484;2388.8208384371155;2389.0921449849034;2392.808575313971;2338.8947497362574;2371.715394152704;2376.2158487699016;2382.5949588569983;2385.1651921827397;2310.8266879984067;2328.7671232876746;2332.0788288626068;2339.391758142878;2364.621660534306;2291.8281128915232;2327.9650805237934;2329.6738456616085;2333.708286464192;2335.9649605255936;8661.610227539713;8700.956495217484;8772.078931712205;8783.38516303859;8786.60460279289;4997.875053123665;5170.586353091744;5173.042987047371;5183.170692689152;5183.922241166386;3553.200529880782;3607.981960090209;3611.2235869287965;3616.18635806944;3619.8733044343585;1064.8988346107108;1075.2043487818958;1092.8852470490563;1099.1481601758621;1101.8237082066912;11589.014933730643;11660.06719462441;11662.183647144704;11666.066714662807;11741.178117531861;4472.262076757299;4527.932081018787;4599.931001034987;4662.300654901792;4663.650226233046;9051.366404351633;9052.04953479875;9058.54707264634;9067.04795996424;9074.137956894085;5221.190715439113;5225.477452254744;5226.817061402909;5232.084385232645;5239.580833533309;3933.645971862546;4312.116016216668;4329.653627709776;4850.927236091461;4869.829555965524;3757.605451427615;3764.698824094066;3772.5472943246987;3844.7693138411223;3871.225754848985;1155.110564865066;1160.5763896177834;1165.5745652836654;1177.7467844413493;1184.1474138620179;824.3116997307196;827.983440331202;882.92495137914;950.9667161649447;952.376228292514;5003.5997120230295;5647.729260916343;5731.828045158652;5994.940050599525;6017.849553761103;424.420665791199;441.7300261747476;450.2818005281571;450.36273672752685;451.9231730605805;3911.8044097795564;3926.6269704378037;4028.4158796974275;4043.9797801010805;4102.487189101363;9833.557489912972;10625.415602141802;10753.354798711945;10799.028087472165;10835.620753273597;10422.635207767691;10750.516226769658;10754;10754.838677419844;10815.459227038617;8065.153158918347;8235.752927412068;8241.79395515105;8254.298384637254;8271.586420678943;5686.175504551734;5794.710264486843;5837.795677151449;5891.351951285441;5924.229850119457;8804.823903521765;9694.175995040501;10051.899481005097;10064.647737329158;10101.595936162472;9142.085791420805;9679.064187162736;9700.223982081276;10368.42973636427;10394.168466522811;6542.051402546699;6769.120014398097;6949.791506254618;6993.580385176906;7068.5758854468895;8030.5181689098845;8158.428909976482;8244.505329680238;8276.213759692819;8307.584620769057;7388.519746216693;7421.0723659542555;7426.18311985692;7449.515781474187;7494.3629791466155;7263.34629883313;7333.083364579427;7333.156686981066;7352.41180705553;7375.262473752797;6937.340952609696;6944.652767361511;6944.9929760185505;6952.165740111219;7047.6828542714675;4654.581087702123;4675.766211689333;4743.525647435229;4760.9285860711425;4825.686330388514;4214.557471465452;4701.162380770249;4989.176785830443;5039.319691841554;5119.795208191631 02:52:12 INFO - PROCESS | 1646 | |4;prototype.html;301.27092436304144;306.4820453433697;309.30560890800155;314.8583137588086;317.5332261575486;4227.915441691164;4894.779734911928;5348.368747532001;5351.919721204185;5384.315645388508;5285.339332583427;5302.681839089648;5304.70824104675;5316.867078323044;5344.732763361826;3313.337332533491;3854.8458061677497;3864.381698928172;4010.759354438728;4016.8594099206534;3035.620547431571;3569.375359312123;4033.9394909076386;4077.83688652455;4088.1823635272917;3142.4186525492855;4003.4795476588147;4017.5781542938007;4024.5774193709676;4026.604170518551;3771.924561508782;3844.5770965193947;3871.4076094206093;4043;4092.2224777292518;3528.077197927136;3534.134137136418;3550.982245088758;3555.8577656893954;3560.1989552350774;5185.377754669426;5204.959008198375;5209.661372010845;5210.895782084375;5262.789558401589;3796.981015094907;4181.477315335583;4612.192866248427;4659.347691323217;4684.227102528079;4541.545845415465;4815.31463987605;5379.031774280628;5444.537214336747;5607.8878422431735;3288.638249792521;3758.1919887224103;3787.3372159872188;3816.713746468998;3837.4435706822355;5605.607607467519;5681.755706495952;5765.971170144123;5826;5940.227770389865;3370.747193960463;3825.5218097737784;3837.808109594565;3841.6949830102276;3848.826802793881;3928.7642741435607;3932.46911666927;3938.9015274618364;3940.625640564142;3947.6447119758786;3820.6370394812448;4560.498345182027;4572.879644487122;4577.931331030038;4579.5878370946775;5325.786968521294;5532.86167845799;5541.9168712469345;5577.657383898772;5592.3009623797025;1298.2145801789782;1321.698127344565;1322.9218187177419;1335.318987316456;1335.9799603005963;651.6025224612982;800.1758189065272;800.6597196191595;807.9878801817978;808.8705807070841;4319.805608747614;4327.096077725791;4327.502337231196;5094.108531007059;5116.6463426824075;5361.812336568201;5561.916571251434;5597.801648763428;5612.410696876851;5612.831615051555;4545.227311357077;4683.695559788603;5062.417821950449;5138.152204886152;5170.612204084633;2497.438076432788;2510.4226028013295;2783.6381270435118;2932.310906936868;2995.8651860666323;5849.766009359578;5857.138574982754;5879.353271140171;5879.7060146993335;5912.733926973297;5222.824864405478;5438.129899216106;5586.441355864381;5787.421257874263;5815.82552523425;5396.87612351692;5824.417558244142;5852.94147058524;5870.649750557396;5886.676232807236;4831.371921650163;5692.260006199168;5783.826485205451;5799.681017544076;5832.533397328289;5216.791328346823;5649.802256920988;5691.715414229355;5701.572382071444;5746.741396637078;5956.13636022783;5983.461488466059;6003.609765365237;6059.424354686298;6097.4817140543555;3263.2657652028106;3870.7162773963496;3881.340172170794;3917.8628747993685;3925.3523168676847;5409.459054094559;5709.371969083314;5736.827895163152;5759.416160555881;5800.622959507618;4686.484486706528;5159;5532.336119665666;5545.584081193846;5569.35952365467;5098.260752190986;5197.350331208599;5592.804251851328;5604.8318550443555;5629.662220266797;5463.235147079333;5463.699496527729;5494;5494.456075831146;5502.2296878437855;5154.819581314636;5413.83758487246;5508.504234618743;5599.692016939108;5627.891639103395;4978.377702787152;5013.896942672606;5332.6000549958135;5444.292242008513;5511.972440137934 02:52:12 INFO - PROCESS | 1646 | |5;yui.html;659.8548319369738;666.5776577806128;760.6574396190723;795.16904834448;820.5117954816882;14753.778693319595;15664.373425062999;16628.33486660532;16646.417375391862;16662.83337166628;14846.55460336189;14954;15087.094774313522;15113.017653852492;15117.92441037796;830.7715378270979;856.8671855862351;856.8886044814166;857.1257317536109;858.7810108422343;682.0655701688694;856.5246288309985;860.2043110123142;861.2593169873903;861.5089399042548;726.9927300726978;730.5872182217057;909.2407839454064;919.2416256588308;919.3105171121658;905.5291248550783;906.9093090690911;908.4458480326995;908.6910450446879;909.5270459361161;519.5583753809269;670.1589505170979;672.5090683800804;674.1472037872096;675.5575098310615;12751.426185821567;14952.551423457313;15524.92237538805;15555.611109722235;15573.766393504107;12802.679933001657;13441.26073065981;13791.310434478337;14626.195559244237;14937.103773773608;9535.188086220214;11944.64166075019;11944.64166075019;11961.88038119625;12521.56174533896;3863.304605171068;3866.4973553438153;3917.196974620196;4064.878053658395;4067.1458993611377;105591;105788;107308;108259.99999999921;110567;11302.208845380908;11420.88579114215;11504.769904602028;11630.88369116315;11813.704657383634;23265.534689306118;23478.882605587205;23514.941827617797;23527.17654882071;23557.646635300483;8123.918760812435;8201.95899020501;8264.380171487042;8419.326453883674;8489.70286039998;4460.353248778909;4475.239209334421;4525.977370113128;4671.11248862715;4759.4764575896625;3591;3747.5315585551807;3753.136778540896;3765.604611515806;3771.5662698789442;678.5962352400314;685.9142607174103;692.6225207261954;694.8471336306004;695.5583204664973;545.8935507576064;558.600600570594;624.2290770897937;679.6567733294654;679.8980152977093;8641.524716140548;9000.819983600291;9245.058537707022;9403.341766076446;9438.52807359643;654.6203202142746;656.6651007985962;673.8787018336747;682.1813823411926;684.3396122741578;4188.8115034822895;4461.308497182942;4611.884702882455;4628.629709623222;4638.489766125724;149452.2527387356;163715;164560;166043;169977.15011424862;119268.80731192578;127253;148278.25860870842;149277;150105.24947375193;89469;121589.39205304094;124036;125828;127653;8911.688090916787;8921.732348029569;8983;9038.638454461749;9183.54082295896;64923;66420;68853;69408;69691.65154174197;76987;94943;98073;99001;107115;11988.100892432927;12120.454579543766;12174.93912530467;12285.877141228473;12476.937615311865;11615.709607259887;13315.866841331463;13340.332983350987;13542.322883855571;13618.250996195304;10352.741181470223;12032.639020829389;12068.396580171131;12276.570320038756;12402.317872517098;12118.697032574257;12171.634850954131;12203.633890983285;12213.122940177911;12350.07374446902;11839.28964262147;11986.880131198577;11990.880091199326;12096;12190.817137743119;10272.178225741773;10332.225083118647;10668.146548275965;10928.398938058483;11006.504707288032;10351.792964140524;10428.739281517719;10548.947255263674;10599.62901298451;10602.469876506299 02:52:12 INFO - PROCESS | 1646 | __end_tp_report 02:52:12 INFO - PROCESS | 1646 | __start_cc_report 02:52:12 INFO - PROCESS | 1646 | _x_x_mozilla_cycle_collect,1306 02:52:12 INFO - PROCESS | 1646 | __end_cc_report 02:52:12 INFO - PROCESS | 1646 | __startTimestamp1471341132576__endTimestamp 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | ------- Summary: start ------- 02:52:12 INFO - PROCESS | 1646 | Number of tests: 6 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#0] dojo.html Cycles:165 Average:5801.65 Median:5987.04 stddev:664.47 (11.1%) stddev-sans-first:628.30 02:52:12 INFO - PROCESS | 1646 | Values: 2970.9 3394.2 3432.7 3435.4 3464.4 5648.9 5722.2 5795.2 5798.0 5806.7 5675.2 5797.7 5851.1 5857.4 5890.6 5220.7 5293.3 5306.4 5325.9 5400.8 5390.5 5393.4 5406.1 5410.4 5430.5 5221.9 5275.0 5289.5 5297.0 5349.2 5312.9 5313.1 5320.4 5321.1 5328.0 5181.9 5201.7 5255.7 5261.2 5265.1 5726.2 5781.8 5786.8 5796.8 5807.7 6313.8 6320.2 6322.2 6332.2 6333.0 6188.6 6238.0 6317.4 6319.5 6370.7 5838.2 5870.3 5882.2 5888.1 5895.1 6364.7 6395.7 6398.6 6401.5 6420.3 6076.1 6084.1 6123.4 6160.3 6174.2 6226.7 6253.2 6279.1 6308.1 6309.8 5236.7 6189.6 6312.2 6313.0 6316.5 6244.6 6290.3 6329.1 6349.7 6359.7 4135.8 4887.7 4958.0 4990.7 5006.2 4424.8 4498.7 4527.3 4531.7 4538.3 5671.6 5715.5 5724.4 5729.1 5742.6 6254.8 6279.3 6300.9 6360.2 6362.4 5804.4 5813.1 5827.1 5830.8 5864.6 5466.8 5486.9 5492.7 5501.1 5519.3 6261.7 6336.6 6367.1 6403.8 6483.9 5247.4 6312.9 6342.5 6346.9 6347.4 6468.1 6471.4 6510.9 6513.4 6522.1 6106.2 6150.1 6189.3 6263.7 6285.7 6273.7 6351.3 6354.3 6358.7 6364.6 6189.8 6214.5 6229.7 6232.3 6241.3 5054.6 5971.6 6168.3 6220.8 6247.6 5840.6 5979.8 5987.0 6100.0 6100.2 6211.1 6218.1 6225.5 6237.8 6242.9 5615.5 6045.2 6074.3 6091.8 6094.2 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#1] ext.html Cycles:180 Average:70135.95 Median:19916.20 stddev:153171.19 (769.1%) stddev-sans-first:153511.67 02:52:12 INFO - PROCESS | 1646 | Values: 512.1 514.7 527.8 536.6 542.2 30808.0 34297.5 40475.6 42068.6 44107.8 42826.6 43218.8 43254.9 43304.0 43359.3 8867.8 9758.7 9913.4 9931.7 9932.5 8922.7 11896.4 12120.5 12146.9 12417.4 12092.4 12115.3 12126.9 12155.8 12224.0 12184.5 12275.0 12280.8 12330.3 12364.8 11782.4 11864.0 11875.5 11878.5 11910.2 40199.8 40457.6 40553.2 40559.6 40560.8 3614.1 3674.8 4034.9 4040.0 4196.1 31245.7 31897.5 31957.4 32095.0 32315.7 3209.8 3310.0 3385.7 3401.3 3412.0 461440.0 465485.0 465828.0 468695.0 472565.0 59393.4 59727.7 59805.1 59815.7 59925.1 62932.1 63073.7 63125.0 63128.7 63200.7 15410.2 15658.8 15692.8 15696.1 15697.8 11085.2 11339.5 11345.7 12073.3 12172.1 14287.4 15345.5 15443.7 15505.8 17840.9 4723.4 6364.2 6394.2 6403.2 6436.5 871.5 932.3 987.9 1038.8 1054.2 30157.0 30255.0 30522.2 30752.0 31220.7 890.9 904.0 1046.4 1064.6 1112.3 13358.5 13392.4 15598.9 15753.6 15962.1 642904.0 647150.0 649228.0 650206.0 680361.0 58706.0 59764.0 59795.7 60032.4 60063.7 1339.7 1374.3 1383.0 1390.2 1395.0 30613.5 31129.2 31307.8 31989.4 32139.0 51773.2 52222.5 52282.0 52300.0 52353.5 580257.2 581153.0 583840.0 583869.0 584132.0 22204.7 25867.2 26153.6 26598.9 26749.7 32821.6 35291.6 39871.0 43194.6 48542.0 34378.3 38192.8 38751.6 39328.2 40669.0 38181.4 38461.1 38983.4 39203.1 39822.2 35824.5 36218.3 36438.0 36508.9 36509.5 31387.0 33702.5 34510.5 34832.7 35019.0 24970.5 25412.9 25614.4 25913.6 26112.3 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#2] jquery.html Cycles:180 Average:34547.13 Median:354.00 stddev:103852.58 (29336.7%) stddev-sans-first:104112.17 02:52:12 INFO - PROCESS | 1646 | Values: 349.0 359.0 368.8 376.4 397.5 15690.5 16587.7 20501.6 20830.9 20940.7 19371.9 20199.9 20527.8 20739.1 21342.8 5314.4 5970.6 6176.3 6238.0 6256.9 6087.2 6201.5 6208.8 6222.5 6223.0 5631.0 5817.6 5880.1 6135.0 6214.0 6121.5 6196.1 6223.9 6227.4 6240.6 6009.3 6074.0 6093.6 6108.1 6137.1 13057.9 13109.9 13218.7 13297.7 13400.6 2179.5 2230.2 2359.1 2415.3 2909.8 9539.6 9843.1 10181.9 10269.5 10401.4 1734.7 1871.7 1884.9 1895.9 1932.3 99152.5 107775.0 113109.4 113944.4 118266.0 9322.7 11355.8 11384.7 11469.3 11678.5 14251.5 14264.0 14271.1 14295.5 14326.2 7966.3 8268.7 8312.2 8440.9 8459.7 8122.8 8147.5 8174.5 8321.2 8336.4 4370.8 4482.4 4491.8 4567.9 4571.7 1910.8 1926.5 1927.2 1949.5 1981.0 325.4 378.2 397.7 762.2 882.2 11653.4 11798.9 11817.7 12144.4 12182.3 412.6 422.8 424.5 431.4 452.9 4652.6 8618.1 10402.7 11293.9 11683.6 602491.0 621538.0 626828.0 630759.0 640179.0 78770.2 130858.2 132387.3 132409.8 137169.6 951.9 986.3 994.3 997.1 1011.0 16932.6 17201.3 17248.9 17268.9 17615.9 48834.5 52273.6 56609.7 58475.8 58767.7 54036.9 65232.0 79263.3 80895.0 81474.6 8097.0 8137.7 8227.8 8334.5 8412.2 11915.3 12792.0 12929.9 12946.4 13206.5 10731.5 11104.3 11113.4 11116.6 11688.7 10056.0 10552.9 10911.8 11201.3 11368.8 10229.3 10539.9 10985.6 11250.8 11362.5 8002.2 8042.4 8248.4 8287.7 8446.0 10514.7 10815.6 11024.9 11051.4 11316.7 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#3] mootools.html Cycles:180 Average:5580.36 Median:4722.34 stddev:3154.91 (66.8%) stddev-sans-first:3147.48 02:52:12 INFO - PROCESS | 1646 | Values: 1315.4 1447.1 1491.7 1621.6 1822.6 7758.1 8258.0 8265.5 8277.7 8286.3 8221.7 8596.9 8601.8 8724.7 8730.2 2361.1 2393.7 2401.2 2403.8 2404.8 2330.8 2388.3 2388.8 2389.1 2392.8 2338.9 2371.7 2376.2 2382.6 2385.2 2310.8 2328.8 2332.1 2339.4 2364.6 2291.8 2328.0 2329.7 2333.7 2336.0 8661.6 8701.0 8772.1 8783.4 8786.6 4997.9 5170.6 5173.0 5183.2 5183.9 3553.2 3608.0 3611.2 3616.2 3619.9 1064.9 1075.2 1092.9 1099.1 1101.8 11589.0 11660.1 11662.2 11666.1 11741.2 4472.3 4527.9 4599.9 4662.3 4663.7 9051.4 9052.0 9058.5 9067.0 9074.1 5221.2 5225.5 5226.8 5232.1 5239.6 3933.6 4312.1 4329.7 4850.9 4869.8 3757.6 3764.7 3772.5 3844.8 3871.2 1155.1 1160.6 1165.6 1177.7 1184.1 824.3 828.0 882.9 951.0 952.4 5003.6 5647.7 5731.8 5994.9 6017.8 424.4 441.7 450.3 450.4 451.9 3911.8 3926.6 4028.4 4044.0 4102.5 9833.6 10625.4 10753.4 10799.0 10835.6 10422.6 10750.5 10754.0 10754.8 10815.5 8065.2 8235.8 8241.8 8254.3 8271.6 5686.2 5794.7 5837.8 5891.4 5924.2 8804.8 9694.2 10051.9 10064.6 10101.6 9142.1 9679.1 9700.2 10368.4 10394.2 6542.1 6769.1 6949.8 6993.6 7068.6 8030.5 8158.4 8244.5 8276.2 8307.6 7388.5 7421.1 7426.2 7449.5 7494.4 7263.3 7333.1 7333.2 7352.4 7375.3 6937.3 6944.7 6945.0 6952.2 7047.7 4654.6 4675.8 4743.5 4760.9 4825.7 4214.6 4701.2 4989.2 5039.3 5119.8 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#4] prototype.html Cycles:180 Average:4503.77 Median:5177.99 stddev:1416.49 (27.4%) stddev-sans-first:1384.90 02:52:12 INFO - PROCESS | 1646 | Values: 301.3 306.5 309.3 314.9 317.5 4227.9 4894.8 5348.4 5351.9 5384.3 5285.3 5302.7 5304.7 5316.9 5344.7 3313.3 3854.8 3864.4 4010.8 4016.9 3035.6 3569.4 4033.9 4077.8 4088.2 3142.4 4003.5 4017.6 4024.6 4026.6 3771.9 3844.6 3871.4 4043.0 4092.2 3528.1 3534.1 3551.0 3555.9 3560.2 5185.4 5205.0 5209.7 5210.9 5262.8 3797.0 4181.5 4612.2 4659.3 4684.2 4541.5 4815.3 5379.0 5444.5 5607.9 3288.6 3758.2 3787.3 3816.7 3837.4 5605.6 5681.8 5766.0 5826.0 5940.2 3370.7 3825.5 3837.8 3841.7 3848.8 3928.8 3932.5 3938.9 3940.6 3947.6 3820.6 4560.5 4572.9 4577.9 4579.6 5325.8 5532.9 5541.9 5577.7 5592.3 1298.2 1321.7 1322.9 1335.3 1336.0 651.6 800.2 800.7 808.0 808.9 4319.8 4327.1 4327.5 5094.1 5116.6 5361.8 5561.9 5597.8 5612.4 5612.8 4545.2 4683.7 5062.4 5138.2 5170.6 2497.4 2510.4 2783.6 2932.3 2995.9 5849.8 5857.1 5879.4 5879.7 5912.7 5222.8 5438.1 5586.4 5787.4 5815.8 5396.9 5824.4 5852.9 5870.6 5886.7 4831.4 5692.3 5783.8 5799.7 5832.5 5216.8 5649.8 5691.7 5701.6 5746.7 5956.1 5983.5 6003.6 6059.4 6097.5 3263.3 3870.7 3881.3 3917.9 3925.4 5409.5 5709.4 5736.8 5759.4 5800.6 4686.5 5159.0 5532.3 5545.6 5569.4 5098.3 5197.4 5592.8 5604.8 5629.7 5463.2 5463.7 5494.0 5494.5 5502.2 5154.8 5413.8 5508.5 5599.7 5627.9 4978.4 5013.9 5332.6 5444.3 5512.0 02:52:12 INFO - PROCESS | 1646 | 02:52:12 INFO - PROCESS | 1646 | [#5] yui.html Cycles:180 Average:25801.81 Median:3750.33 stddev:42419.32 (1131.1%) stddev-sans-first:42496.31 02:52:12 INFO - PROCESS | 1646 | Values: 659.9 666.6 760.7 795.2 820.5 14753.8 15664.4 16628.3 16646.4 16662.8 14846.6 14954.0 15087.1 15113.0 15117.9 830.8 856.9 856.9 857.1 858.8 682.1 856.5 860.2 861.3 861.5 727.0 730.6 909.2 919.2 919.3 905.5 906.9 908.4 908.7 909.5 519.6 670.2 672.5 674.1 675.6 12751.4 14952.6 15524.9 15555.6 15573.8 12802.7 13441.3 13791.3 14626.2 14937.1 9535.2 11944.6 11944.6 11961.9 12521.6 3863.3 3866.5 3917.2 4064.9 4067.1 105591.0 105788.0 107308.0 108260.0 110567.0 11302.2 11420.9 11504.8 11630.9 11813.7 23265.5 23478.9 23514.9 23527.2 23557.6 8123.9 8202.0 8264.4 8419.3 8489.7 4460.4 4475.2 4526.0 4671.1 4759.5 3591.0 3747.5 3753.1 3765.6 3771.6 678.6 685.9 692.6 694.8 695.6 545.9 558.6 624.2 679.7 679.9 8641.5 9000.8 9245.1 9403.3 9438.5 654.6 656.7 673.9 682.2 684.3 4188.8 4461.3 4611.9 4628.6 4638.5 149452.3 163715.0 164560.0 166043.0 169977.2 119268.8 127253.0 148278.3 149277.0 150105.2 89469.0 121589.4 124036.0 125828.0 127653.0 8911.7 8921.7 8983.0 9038.6 9183.5 64923.0 66420.0 68853.0 69408.0 69691.7 76987.0 94943.0 98073.0 99001.0 107115.0 11988.1 12120.5 12174.9 12285.9 12476.9 11615.7 13315.9 13340.3 13542.3 13618.3 10352.7 12032.6 12068.4 12276.6 12402.3 12118.7 12171.6 12203.6 12213.1 12350.1 11839.3 11986.9 11990.9 12096.0 12190.8 10272.2 10332.2 10668.1 10928.4 11006.5 10351.8 10428.7 10548.9 10599.6 10602.5 02:52:12 INFO - PROCESS | 1646 | -------- Summary: end -------- 02:52:12 INFO - PROCESS | 1646 | 02:52:13 INFO - TEST-INFO | 1646: exit 0 02:52:13 INFO - TEST-OK | dromaeo_css | took 1118283ms 02:52:13 INFO - TEST-START | kraken 02:52:13 INFO - operating with platform_type : mac_ 02:52:13 INFO - Initialising browser for kraken test... 02:52:13 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpoLeyJq/profile http://localhost:49277/getInfo.html) 02:52:15 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 02:52:15 INFO - PROCESS | 1656 | colorDepth:24 02:52:15 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 02:52:15 INFO - PROCESS | 1656 | __metrics 02:52:15 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 02:52:15 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 02:52:16 INFO - TEST-INFO | 1656: exit 0 02:52:16 INFO - Browser initialized. 02:52:16 INFO - Running cycle 1/1 for kraken test... 02:52:16 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/hn/0wf8m37s2nbfv1c2zw6h8k5m00000w/T/tmpoLeyJq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 02:52:28 INFO - PROCESS | 1657 | RSS: Main: 228478976 02:52:28 INFO - PROCESS | 1657 | 02:52:31 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection) 02:52:32 INFO - PROCESS | 1657 | RSS: Main: 286044160 02:52:32 INFO - PROCESS | 1657 | 02:52:34 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-dft) 02:52:35 INFO - PROCESS | 1657 | RSS: Main: 295075840 02:52:35 INFO - PROCESS | 1657 | 02:52:39 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-fft) 02:52:39 INFO - PROCESS | 1657 | RSS: Main: 388288512 02:52:39 INFO - PROCESS | 1657 | 02:52:41 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator) 02:52:42 INFO - PROCESS | 1657 | RSS: Main: 290873344 02:52:42 INFO - PROCESS | 1657 | 02:52:44 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur) 02:52:44 INFO - PROCESS | 1657 | RSS: Main: 294797312 02:52:44 INFO - PROCESS | 1657 | 02:52:47 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom) 02:52:48 INFO - PROCESS | 1657 | RSS: Main: 332390400 02:52:48 INFO - PROCESS | 1657 | 02:52:51 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate) 02:52:52 INFO - PROCESS | 1657 | RSS: Main: 335077376 02:52:52 INFO - PROCESS | 1657 | 02:52:55 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial) 02:52:56 INFO - PROCESS | 1657 | RSS: Main: 331042816 02:52:56 INFO - PROCESS | 1657 | 02:52:57 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox) 02:52:58 INFO - PROCESS | 1657 | RSS: Main: 301907968 02:52:58 INFO - PROCESS | 1657 | 02:53:00 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes) 02:53:00 INFO - PROCESS | 1657 | RSS: Main: 309833728 02:53:00 INFO - PROCESS | 1657 | 02:53:03 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm) 02:53:03 INFO - PROCESS | 1657 | RSS: Main: 305414144 02:53:03 INFO - PROCESS | 1657 | 02:53:06 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 02:53:07 INFO - PROCESS | 1657 | RSS: Main: 313069568 02:53:07 INFO - PROCESS | 1657 | 02:53:10 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 02:53:10 INFO - PROCESS | 1657 | RSS: Main: 314343424 02:53:10 INFO - PROCESS | 1657 | 02:53:12 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49277/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49277/tests/kraken/driver.html?testName=ai-astar) 02:53:13 INFO - PROCESS | 1657 | RSS: Main: 357519360 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | __start_tp_report 02:53:13 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 02:53:13 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 02:53:13 INFO - PROCESS | 1657 | |i|pagename|runs| 02:53:13 INFO - PROCESS | 1657 | |0;ai-astar;102;122;168;153;113;113;106;112;112;112 02:53:13 INFO - PROCESS | 1657 | |1;audio-beat-detection;144;108;109;166;107;107;131;109;140;106 02:53:13 INFO - PROCESS | 1657 | |2;audio-dft;184;184;185;184;185;183;186;228;266;181 02:53:13 INFO - PROCESS | 1657 | |3;audio-fft;81;48;61;96;83;82;58;61;131;56 02:53:13 INFO - PROCESS | 1657 | |4;audio-oscillator;81;69;106;70;85;68;97;71;68;103 02:53:13 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;88;86;89;86;89;85;137;125;88;96 02:53:13 INFO - PROCESS | 1657 | |6;imaging-darkroom;94;94;95;95;95;109;150;104;95;94 02:53:13 INFO - PROCESS | 1657 | |7;imaging-desaturate;86;86;121;131;131;89;131;141;141;86 02:53:13 INFO - PROCESS | 1657 | |8;json-parse-financial;55;53;56;52;52;54;54;51;54;53 02:53:13 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;68;74;75;72;74;70;68;71;70;72 02:53:13 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;88;86;84;85;83;87;98;106;107;71 02:53:13 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;141;139;156;149;150;155;137;138;157;159 02:53:13 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;170;170;166;168;166;165;169;169;167 02:53:13 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;67;68;66;68;66;68;73;84;87;86 02:53:13 INFO - PROCESS | 1657 | __end_tp_report 02:53:13 INFO - PROCESS | 1657 | __start_cc_report 02:53:13 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,2213 02:53:13 INFO - PROCESS | 1657 | __end_cc_report 02:53:13 INFO - PROCESS | 1657 | __startTimestamp1471341193058__endTimestamp 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | ------- Summary: start ------- 02:53:13 INFO - PROCESS | 1657 | Number of tests: 14 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:121.30 Median:113.00 stddev:21.58 (19.1%) stddev-sans-first:21.73 02:53:13 INFO - PROCESS | 1657 | Values: 102.0 122.0 168.0 153.0 113.0 113.0 106.0 112.0 112.0 112.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:122.70 Median:120.00 stddev:21.24 (17.7%) stddev-sans-first:21.08 02:53:13 INFO - PROCESS | 1657 | Values: 144.0 108.0 109.0 166.0 107.0 107.0 131.0 109.0 140.0 106.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:196.60 Median:185.00 stddev:28.06 (15.2%) stddev-sans-first:29.39 02:53:13 INFO - PROCESS | 1657 | Values: 184.0 184.0 185.0 184.0 185.0 183.0 186.0 228.0 266.0 181.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:75.70 Median:71.00 stddev:24.70 (34.8%) stddev-sans-first:26.13 02:53:13 INFO - PROCESS | 1657 | Values: 81.0 48.0 61.0 96.0 83.0 82.0 58.0 61.0 131.0 56.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:81.80 Median:70.50 stddev:15.19 (21.6%) stddev-sans-first:16.11 02:53:13 INFO - PROCESS | 1657 | Values: 81.0 69.0 106.0 70.0 85.0 68.0 97.0 71.0 68.0 103.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:96.90 Median:88.00 stddev:18.44 (21.0%) stddev-sans-first:19.28 02:53:13 INFO - PROCESS | 1657 | Values: 88.0 86.0 89.0 86.0 89.0 85.0 137.0 125.0 88.0 96.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:102.50 Median:94.50 stddev:17.46 (18.5%) stddev-sans-first:18.24 02:53:13 INFO - PROCESS | 1657 | Values: 94.0 94.0 95.0 95.0 95.0 109.0 150.0 104.0 95.0 94.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:114.30 Median:113.50 stddev:24.38 (21.5%) stddev-sans-first:23.61 02:53:13 INFO - PROCESS | 1657 | Values: 86.0 86.0 121.0 131.0 131.0 89.0 131.0 141.0 141.0 86.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:53.40 Median:54.00 stddev:1.51 (2.8%) stddev-sans-first:1.48 02:53:13 INFO - PROCESS | 1657 | Values: 55.0 53.0 56.0 52.0 52.0 54.0 54.0 51.0 54.0 53.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:71.40 Median:72.00 stddev:2.46 (3.4%) stddev-sans-first:2.28 02:53:13 INFO - PROCESS | 1657 | Values: 68.0 74.0 75.0 72.0 74.0 70.0 68.0 71.0 70.0 72.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:89.50 Median:85.50 stddev:11.09 (13.0%) stddev-sans-first:11.75 02:53:13 INFO - PROCESS | 1657 | Values: 88.0 86.0 84.0 85.0 83.0 87.0 98.0 106.0 107.0 71.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:148.10 Median:152.50 stddev:8.63 (5.7%) stddev-sans-first:8.77 02:53:13 INFO - PROCESS | 1657 | Values: 141.0 139.0 156.0 149.0 150.0 155.0 137.0 138.0 157.0 159.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:167.80 Median:168.50 stddev:1.75 (1.0%) stddev-sans-first:1.86 02:53:13 INFO - PROCESS | 1657 | Values: 168.0 170.0 170.0 166.0 168.0 166.0 165.0 169.0 169.0 167.0 02:53:13 INFO - PROCESS | 1657 | 02:53:13 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.30 Median:70.50 stddev:8.78 (12.5%) stddev-sans-first:9.01 02:53:13 INFO - PROCESS | 1657 | Values: 67.0 68.0 66.0 68.0 66.0 68.0 73.0 84.0 87.0 86.0 02:53:13 INFO - PROCESS | 1657 | -------- Summary: end -------- 02:53:13 INFO - PROCESS | 1657 | 02:53:14 INFO - TEST-INFO | 1657: exit 0 02:53:14 INFO - TEST-OK | kraken | took 60819ms 02:53:14 INFO - SUITE-END | took 1179s 02:53:14 INFO - Completed test suite (00:19:39) 02:53:14 INFO - javascript benchmark 02:53:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2970.88116475341, 3394.2193295542024, 3432.673895979884, 3435.4159792835217, 3464.4283693190623, 5648.926703926251, 5722.1988921550965, 5795.246617939672, 5797.956367853784, 5806.651600903949, 5675.177099320596, 5797.739101740422, 5851.122331650244, 5857.3849745776715, 5890.617109887854, 5220.686758794484, 5293.258943747878, 5306.416294207634, 5325.866853328659, 5400.756965936523, 5390.4879036491475, 5393.352797664285, 5406.08096623575, 5410.377806552237, 5430.456954304557, 5221.921671174935, 5274.997750427445, 5289.523942845162, 5297.0, 5349.170878513836, 5312.946870531284, 5313.0967735485065, 5320.388155362144, 5321.122014867542, 5328.0, 5181.948180518222, 5201.739913004372, 5255.6583822051825, 5261.210818377236, 5265.104932161542, 5726.169705392695, 5781.768729250867, 5786.826395208151, 5796.797112101099, 5807.651540907559, 6313.842153946188, 6320.209973753281, 6322.241331040259, 6332.240131184241, 6333.0, 6188.597741146811, 6238.033104868752, 6317.399847014526, 6319.494440444753, 6370.681465926685, 5838.182654428383, 5870.26621672291, 5882.205902203234, 5888.146218798251, 5895.056790913433, 6364.745410183541, 6395.712192951329, 6398.584092034003, 6401.519886008568, 6420.293767685551, 6076.118962750376, 6084.11780291864, 6123.387661233841, 6160.260768707694, 6174.166487524217, 6226.657533835682, 6253.187085678924, 6279.120923070774, 6308.148399966038, 6309.810705678837, 5236.6857988520815, 6189.566730328924, 6312.210973628296, 6312.968435157794, 6316.463100636405, 6244.594101383395, 6290.261462675303, 6329.113924050636, 6349.714262858182, 6359.745610175633, 4135.793210339471, 4887.731174785421, 4958.033183529177, 4990.6506544541535, 5006.249062640633, 4424.778761061934, 4498.707584007029, 4527.343535187445, 4531.660125490602, 4538.341940418621, 5671.602987790898, 5715.5427565794635, 5724.398938111438, 5729.1119876419225, 5742.569307301969, 6254.781082662176, 6279.309275979638, 6300.905486417708, 6360.236771587439, 6362.363763623694, 5804.361520232771, 5813.09896965971, 5827.125931110368, 5830.76676932918, 5864.560157988082, 5466.808661696901, 5486.890262194734, 5492.697901615449, 5501.0648189807025, 5519.254900588449, 6261.686915654199, 6336.556441049174, 6367.076773867856, 6403.807885763435, 6483.902741458882, 5247.370315562004, 6312.905306420315, 6342.460890824332, 6346.873062538816, 6347.428731414195, 6468.094466774562, 6471.352864713491, 6510.934890651222, 6513.381228783258, 6522.05430212626, 6106.157323464254, 6150.138980542817, 6189.257289125334, 6263.718132684131, 6285.685715714105, 6273.65494897785, 6351.3331100235655, 6354.332795056453, 6358.682065896779, 6364.64994425311, 6189.752409903734, 6214.471769899609, 6229.688515574113, 6232.283287422005, 6241.251049874044, 5054.620903432331, 5971.641701497924, 6168.321484636776, 6220.782272620437, 6247.625142491283, 5840.620359676607, 5979.760809567569, 5987.042073268256, 6100.0, 6100.176476175659, 6211.099390588249, 6218.067289906369, 6225.470834979077, 6237.75048998035, 6242.875142497215, 5615.522680572034, 6045.153678485103, 6074.331823499323, 6091.756329746938, 6094.2077529920825], "value": 5758.602393548215, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [512.1396054628224, 514.7014731455756, 527.7941602774916, 536.5921899356488, 542.1677724692597, 30808.0, 34297.48553771695, 40475.59524404755, 42068.57931420692, 44107.77946110265, 42826.57173428265, 43218.783906080505, 43254.91862703426, 43304.0, 43359.3496097558, 8867.822643547126, 9758.65844695434, 9913.405195688245, 9931.652392166276, 9932.503374831265, 8922.687705930293, 11896.405179741021, 12120.515179392858, 12146.878531214707, 12417.441215145294, 12092.395380230997, 12115.333656648882, 12126.878731212708, 12155.817662735066, 12224.022078233764, 12184.451699673536, 12274.956628686483, 12280.815787763146, 12330.32183229922, 12364.814527782, 11782.410879455992, 11864.0, 11875.52497900083, 11878.465469053914, 11910.225835320676, 40199.79900100481, 40457.59542404568, 40553.18893622111, 40559.594404055875, 40560.79719601413, 3614.132608174046, 3674.8346324415465, 4034.9193016139807, 4039.9596004039877, 4196.118815048813, 31245.687543124506, 31897.52153717696, 31957.360852783047, 32095.0, 32315.676843231737, 3209.807411555314, 3310.0400883743687, 3385.712214461774, 3401.3367393358303, 3411.9829400852836, 461440.0, 465485.0, 465828.0, 468695.0, 472565.0, 59393.40606593965, 59727.70136149292, 59805.10292345619, 59815.70092149511, 59925.10112348318, 62932.05601915975, 63073.68463157655, 63125.0, 63128.68435657792, 63200.683996579726, 15410.152441615819, 15658.765118523232, 15692.76460853088, 15696.058236505845, 15697.843021569866, 11085.168612354106, 11339.546418143182, 11345.716357091138, 12073.27560346382, 12172.14794964344, 14287.357068931922, 15345.462908798361, 15443.691126177639, 15505.84494155044, 17840.929544227387, 4723.433091907006, 6364.236291644939, 6394.1687580614225, 6403.167588213502, 6436.517261205428, 871.4858233642182, 932.3007744191856, 987.9407235565889, 1038.849366841819, 1054.1935419404163, 30157.0, 30255.0, 30522.236944076576, 30752.0, 31220.68779312178, 890.8863920099875, 903.9819203615891, 1046.398320965448, 1064.6433444795925, 1112.3159257057014, 13358.532451364155, 13392.397342119628, 15598.9220053899, 15753.606159845865, 15962.12208328553, 642904.0, 647150.0, 649228.0, 650206.0, 680361.0, 58706.0, 59764.0, 59795.70102149461, 60032.39967600355, 60063.69968150131, 1339.7052648417332, 1374.2785037855006, 1382.9792553111913, 1390.2353705462158, 1394.9651258718613, 30613.54079688762, 31129.221769455944, 31307.84346078255, 31989.36021279608, 32139.0, 51773.223401648254, 52222.477775223284, 52282.0, 52300.0, 52353.47646523486, 580257.1974280203, 581153.0, 583840.0, 583869.0, 584132.0, 22204.666929996387, 25867.22398328053, 26153.60769588496, 26598.867005664848, 26749.732502674724, 32821.55851425365, 35291.647083528835, 39871.0, 43194.56805432031, 48542.0, 34378.31243375068, 38192.80903595464, 38751.6124838748, 39328.2134357317, 40668.98327541835, 38181.4272785914, 38461.119177043154, 38983.415248770725, 39203.09949846468, 39822.2035559293, 35824.46263306105, 36218.275634486636, 36438.0, 36508.90473285806, 36509.45235821412, 31387.0, 33702.49446258357, 34510.48234276438, 34832.65167348395, 35019.0, 24970.500589988464, 25412.872935635205, 25614.359641009123, 25913.611295830953, 26112.347191319608], "value": 20189.768301956403, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [349.00881496549823, 358.99641003589954, 368.7676763638907, 376.40527965814016, 397.5309135220439, 15690.52928412148, 16587.668246635076, 20501.589968200664, 20830.89584552079, 20940.685889711665, 19371.90314048428, 20199.899000505015, 20527.794722052775, 20739.06674199661, 21342.786572134275, 5314.362276526803, 5970.611910225822, 6176.320604733476, 6237.968810155943, 6256.874862502747, 6087.20866287382, 6201.503879689614, 6208.78269260576, 6222.502199824025, 6223.004319308911, 5631.042722737124, 5817.592768506206, 5880.059190529516, 6134.969325153368, 6213.980456351681, 6121.540884433641, 6196.101565273055, 6223.875522489525, 6227.3772622737815, 6240.563160578761, 6009.308929473123, 6073.969630151865, 6093.6039157454625, 6108.1143234230785, 6137.079438084323, 13057.86942130576, 13109.934450327688, 13218.735625287536, 13297.66755831112, 13400.597982060553, 2179.5422961178174, 2230.185982116532, 2359.1035406545575, 2415.275417374798, 2909.839958802265, 9539.56906464029, 9843.114119729258, 10181.949090254502, 10269.537870795757, 10401.427921464316, 1734.7224444088886, 1871.6724573199772, 1884.9340273090377, 1895.924163033477, 1932.3140285198692, 99152.50423747835, 107775.0, 113109.43445282803, 113944.43027784808, 118265.99999999914, 9322.673706420243, 11355.829662555068, 11384.715382115348, 11469.31184128955, 11678.532858685558, 14251.50119745804, 14264.0, 14271.14373137615, 14295.499657511937, 14326.212058336892, 7966.322862556747, 8268.710595129141, 8312.168783121639, 8440.915590844135, 8459.661613535389, 8122.75631731049, 8147.5111493309405, 8174.468659537111, 8321.209485098905, 8336.374771892133, 4370.781460926941, 4482.394876691605, 4491.752953587518, 4567.908641827211, 4571.6513628479015, 1910.7516022916932, 1926.4509614759727, 1927.1616846671743, 1949.483386902472, 1980.9900950495444, 325.3996376685012, 378.2340759961101, 397.7434554712194, 762.2187258060421, 882.2280504558511, 11653.359065251492, 11798.87910648487, 11817.704557385961, 12144.392780360946, 12182.329971851455, 412.57092623671645, 422.84777480107135, 424.51181141687306, 431.4111238159889, 452.87093178444644, 4652.62778977681, 8618.138186181457, 10402.739931501621, 11293.927076927679, 11683.591074312528, 602491.0, 621538.0, 626828.0, 630759.0, 640179.0, 78770.21229787743, 130858.22275995206, 132387.33806330906, 132409.77287611854, 137169.62830371768, 951.9190868776093, 986.2898712926682, 994.2741798487145, 997.1474389397106, 1011.0092109732503, 16932.57668558296, 17201.311947521957, 17248.913755431142, 17268.877522960967, 17615.854969426946, 48834.511654884416, 52273.647685853466, 56609.71695141662, 58475.83048339094, 58767.706161468916, 54036.91926161376, 65232.0, 79263.2810390845, 80895.0, 81474.59262703886, 8097.0, 8137.715179968909, 8227.83544329098, 8334.458260213309, 8412.242898139197, 11915.285082895054, 12791.976641868441, 12929.870701292866, 12946.417411216706, 13206.471741130246, 10731.463426828472, 11104.278221915549, 11113.388763618079, 11116.555337786722, 11688.70778230551, 10056.044675755791, 10552.894471055191, 10911.83632245501, 11201.271917325348, 11368.772624547628, 10229.283950123718, 10539.894601053891, 10985.61550345734, 11250.83123753128, 11362.488688008894, 8002.1997800219515, 8042.356611470951, 8248.422610417214, 8287.709930152416, 8446.028706698562, 10514.684559463229, 10815.56737730482, 11024.944875275572, 11051.447427628747, 11316.660500185008], "value": 8852.72625448636, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1315.3686230609314, 1447.0811034992782, 1491.664375515509, 1621.5864954436618, 1822.608139250061, 7758.069031716195, 8258.0, 8265.545395003272, 8277.710280140196, 8286.337093032558, 8221.712240071598, 8596.914030859689, 8601.827963440728, 8724.65101395945, 8730.170633789796, 2361.1263832382, 2393.676853624756, 2401.1715957994465, 2403.759624037591, 2404.7715467030694, 2330.8018818400374, 2388.2596395117484, 2388.8208384371155, 2389.0921449849034, 2392.808575313971, 2338.8947497362574, 2371.715394152704, 2376.2158487699016, 2382.5949588569983, 2385.1651921827397, 2310.8266879984067, 2328.7671232876746, 2332.0788288626068, 2339.391758142878, 2364.621660534306, 2291.8281128915232, 2327.9650805237934, 2329.6738456616085, 2333.708286464192, 2335.9649605255936, 8661.610227539713, 8700.956495217484, 8772.078931712205, 8783.38516303859, 8786.60460279289, 4997.875053123665, 5170.586353091744, 5173.042987047371, 5183.170692689152, 5183.922241166386, 3553.200529880782, 3607.981960090209, 3611.2235869287965, 3616.18635806944, 3619.8733044343585, 1064.8988346107108, 1075.2043487818958, 1092.8852470490563, 1099.1481601758621, 1101.8237082066912, 11589.014933730643, 11660.06719462441, 11662.183647144704, 11666.066714662807, 11741.178117531861, 4472.262076757299, 4527.932081018787, 4599.931001034987, 4662.300654901792, 4663.650226233046, 9051.366404351633, 9052.04953479875, 9058.54707264634, 9067.04795996424, 9074.137956894085, 5221.190715439113, 5225.477452254744, 5226.817061402909, 5232.084385232645, 5239.580833533309, 3933.645971862546, 4312.116016216668, 4329.653627709776, 4850.927236091461, 4869.829555965524, 3757.605451427615, 3764.698824094066, 3772.5472943246987, 3844.7693138411223, 3871.225754848985, 1155.110564865066, 1160.5763896177834, 1165.5745652836654, 1177.7467844413493, 1184.1474138620179, 824.3116997307196, 827.983440331202, 882.92495137914, 950.9667161649447, 952.376228292514, 5003.5997120230295, 5647.729260916343, 5731.828045158652, 5994.940050599525, 6017.849553761103, 424.420665791199, 441.7300261747476, 450.2818005281571, 450.36273672752685, 451.9231730605805, 3911.8044097795564, 3926.6269704378037, 4028.4158796974275, 4043.9797801010805, 4102.487189101363, 9833.557489912972, 10625.415602141802, 10753.354798711945, 10799.028087472165, 10835.620753273597, 10422.635207767691, 10750.516226769658, 10754.0, 10754.838677419844, 10815.459227038617, 8065.153158918347, 8235.752927412068, 8241.79395515105, 8254.298384637254, 8271.586420678943, 5686.175504551734, 5794.710264486843, 5837.795677151449, 5891.351951285441, 5924.229850119457, 8804.823903521765, 9694.175995040501, 10051.899481005097, 10064.647737329158, 10101.595936162472, 9142.085791420805, 9679.064187162736, 9700.223982081276, 10368.42973636427, 10394.168466522811, 6542.051402546699, 6769.120014398097, 6949.791506254618, 6993.580385176906, 7068.5758854468895, 8030.5181689098845, 8158.428909976482, 8244.505329680238, 8276.213759692819, 8307.584620769057, 7388.519746216693, 7421.0723659542555, 7426.18311985692, 7449.515781474187, 7494.3629791466155, 7263.34629883313, 7333.083364579427, 7333.156686981066, 7352.41180705553, 7375.262473752797, 6937.340952609696, 6944.652767361511, 6944.9929760185505, 6952.165740111219, 7047.6828542714675, 4654.581087702123, 4675.766211689333, 4743.525647435229, 4760.9285860711425, 4825.686330388514, 4214.557471465452, 4701.162380770249, 4989.176785830443, 5039.319691841554, 5119.795208191631], "value": 4410.217676682371, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [301.27092436304144, 306.4820453433697, 309.30560890800155, 314.8583137588086, 317.5332261575486, 4227.915441691164, 4894.779734911928, 5348.368747532001, 5351.919721204185, 5384.315645388508, 5285.339332583427, 5302.681839089648, 5304.70824104675, 5316.867078323044, 5344.732763361826, 3313.337332533491, 3854.8458061677497, 3864.381698928172, 4010.759354438728, 4016.8594099206534, 3035.620547431571, 3569.375359312123, 4033.9394909076386, 4077.83688652455, 4088.1823635272917, 3142.4186525492855, 4003.4795476588147, 4017.5781542938007, 4024.5774193709676, 4026.604170518551, 3771.924561508782, 3844.5770965193947, 3871.4076094206093, 4043.0, 4092.2224777292518, 3528.077197927136, 3534.134137136418, 3550.982245088758, 3555.8577656893954, 3560.1989552350774, 5185.377754669426, 5204.959008198375, 5209.661372010845, 5210.895782084375, 5262.789558401589, 3796.981015094907, 4181.477315335583, 4612.192866248427, 4659.347691323217, 4684.227102528079, 4541.545845415465, 4815.31463987605, 5379.031774280628, 5444.537214336747, 5607.8878422431735, 3288.638249792521, 3758.1919887224103, 3787.3372159872188, 3816.713746468998, 3837.4435706822355, 5605.607607467519, 5681.755706495952, 5765.971170144123, 5826.0, 5940.227770389865, 3370.747193960463, 3825.5218097737784, 3837.808109594565, 3841.6949830102276, 3848.826802793881, 3928.7642741435607, 3932.46911666927, 3938.9015274618364, 3940.625640564142, 3947.6447119758786, 3820.6370394812448, 4560.498345182027, 4572.879644487122, 4577.931331030038, 4579.5878370946775, 5325.786968521294, 5532.86167845799, 5541.9168712469345, 5577.657383898772, 5592.3009623797025, 1298.2145801789782, 1321.698127344565, 1322.9218187177419, 1335.318987316456, 1335.9799603005963, 651.6025224612982, 800.1758189065272, 800.6597196191595, 807.9878801817978, 808.8705807070841, 4319.805608747614, 4327.096077725791, 4327.502337231196, 5094.108531007059, 5116.6463426824075, 5361.812336568201, 5561.916571251434, 5597.801648763428, 5612.410696876851, 5612.831615051555, 4545.227311357077, 4683.695559788603, 5062.417821950449, 5138.152204886152, 5170.612204084633, 2497.438076432788, 2510.4226028013295, 2783.6381270435118, 2932.310906936868, 2995.8651860666323, 5849.766009359578, 5857.138574982754, 5879.353271140171, 5879.7060146993335, 5912.733926973297, 5222.824864405478, 5438.129899216106, 5586.441355864381, 5787.421257874263, 5815.82552523425, 5396.87612351692, 5824.417558244142, 5852.94147058524, 5870.649750557396, 5886.676232807236, 4831.371921650163, 5692.260006199168, 5783.826485205451, 5799.681017544076, 5832.533397328289, 5216.791328346823, 5649.802256920988, 5691.715414229355, 5701.572382071444, 5746.741396637078, 5956.13636022783, 5983.461488466059, 6003.609765365237, 6059.424354686298, 6097.4817140543555, 3263.2657652028106, 3870.7162773963496, 3881.340172170794, 3917.8628747993685, 3925.3523168676847, 5409.459054094559, 5709.371969083314, 5736.827895163152, 5759.416160555881, 5800.622959507618, 4686.484486706528, 5159.0, 5532.336119665666, 5545.584081193846, 5569.35952365467, 5098.260752190986, 5197.350331208599, 5592.804251851328, 5604.8318550443555, 5629.662220266797, 5463.235147079333, 5463.699496527729, 5494.0, 5494.456075831146, 5502.2296878437855, 5154.819581314636, 5413.83758487246, 5508.504234618743, 5599.692016939108, 5627.891639103395, 4978.377702787152, 5013.896942672606, 5332.6000549958135, 5444.292242008513, 5511.972440137934], "value": 4045.2318876410923, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [659.8548319369738, 666.5776577806128, 760.6574396190723, 795.16904834448, 820.5117954816882, 14753.778693319595, 15664.373425062999, 16628.33486660532, 16646.417375391862, 16662.83337166628, 14846.55460336189, 14954.0, 15087.094774313522, 15113.017653852492, 15117.92441037796, 830.7715378270979, 856.8671855862351, 856.8886044814166, 857.1257317536109, 858.7810108422343, 682.0655701688694, 856.5246288309985, 860.2043110123142, 861.2593169873903, 861.5089399042548, 726.9927300726978, 730.5872182217057, 909.2407839454064, 919.2416256588308, 919.3105171121658, 905.5291248550783, 906.9093090690911, 908.4458480326995, 908.6910450446879, 909.5270459361161, 519.5583753809269, 670.1589505170979, 672.5090683800804, 674.1472037872096, 675.5575098310615, 12751.426185821567, 14952.551423457313, 15524.92237538805, 15555.611109722235, 15573.766393504107, 12802.679933001657, 13441.26073065981, 13791.310434478337, 14626.195559244237, 14937.103773773608, 9535.188086220214, 11944.64166075019, 11944.64166075019, 11961.88038119625, 12521.56174533896, 3863.304605171068, 3866.4973553438153, 3917.196974620196, 4064.878053658395, 4067.1458993611377, 105591.0, 105788.0, 107308.0, 108259.99999999921, 110567.0, 11302.208845380908, 11420.88579114215, 11504.769904602028, 11630.88369116315, 11813.704657383634, 23265.534689306118, 23478.882605587205, 23514.941827617797, 23527.17654882071, 23557.646635300483, 8123.918760812435, 8201.95899020501, 8264.380171487042, 8419.326453883674, 8489.70286039998, 4460.353248778909, 4475.239209334421, 4525.977370113128, 4671.11248862715, 4759.4764575896625, 3591.0, 3747.5315585551807, 3753.136778540896, 3765.604611515806, 3771.5662698789442, 678.5962352400314, 685.9142607174103, 692.6225207261954, 694.8471336306004, 695.5583204664973, 545.8935507576064, 558.600600570594, 624.2290770897937, 679.6567733294654, 679.8980152977093, 8641.524716140548, 9000.819983600291, 9245.058537707022, 9403.341766076446, 9438.52807359643, 654.6203202142746, 656.6651007985962, 673.8787018336747, 682.1813823411926, 684.3396122741578, 4188.8115034822895, 4461.308497182942, 4611.884702882455, 4628.629709623222, 4638.489766125724, 149452.2527387356, 163715.0, 164560.0, 166043.0, 169977.15011424862, 119268.80731192578, 127253.0, 148278.25860870842, 149277.0, 150105.24947375193, 89469.0, 121589.39205304094, 124036.0, 125828.0, 127653.0, 8911.688090916787, 8921.732348029569, 8983.0, 9038.638454461749, 9183.54082295896, 64923.0, 66420.0, 68853.0, 69408.0, 69691.65154174197, 76987.0, 94943.0, 98073.0, 99001.0, 107115.0, 11988.100892432927, 12120.454579543766, 12174.93912530467, 12285.877141228473, 12476.937615311865, 11615.709607259887, 13315.866841331463, 13340.332983350987, 13542.322883855571, 13618.250996195304, 10352.741181470223, 12032.639020829389, 12068.396580171131, 12276.570320038756, 12402.317872517098, 12118.697032574257, 12171.634850954131, 12203.633890983285, 12213.122940177911, 12350.07374446902, 11839.28964262147, 11986.880131198577, 11990.880091199326, 12096.0, 12190.817137743119, 10272.178225741773, 10332.225083118647, 10668.146548275965, 10928.398938058483, 11006.504707288032, 10351.792964140524, 10428.739281517719, 10548.947255263674, 10599.62901298451, 10602.469876506299], "value": 7811.1529634061, "unit": "score"}], "name": "dromaeo_css", "value": 7235.166853643501, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 122.0, 168.0, 153.0, 113.0, 113.0, 106.0, 112.0, 112.0, 112.0], "value": 121.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 108.0, 109.0, 166.0, 107.0, 107.0, 131.0, 109.0, 140.0, 106.0], "value": 122.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 185.0, 184.0, 185.0, 183.0, 186.0, 228.0, 266.0, 181.0], "value": 196.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 48.0, 61.0, 96.0, 83.0, 82.0, 58.0, 61.0, 131.0, 56.0], "value": 75.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 69.0, 106.0, 70.0, 85.0, 68.0, 97.0, 71.0, 68.0, 103.0], "value": 81.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 89.0, 86.0, 89.0, 85.0, 137.0, 125.0, 88.0, 96.0], "value": 96.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 94.0, 95.0, 95.0, 95.0, 109.0, 150.0, 104.0, 95.0, 94.0], "value": 102.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 121.0, 131.0, 131.0, 89.0, 131.0, 141.0, 141.0, 86.0], "value": 114.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 56.0, 52.0, 52.0, 54.0, 54.0, 51.0, 54.0, 53.0], "value": 53.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 74.0, 75.0, 72.0, 74.0, 70.0, 68.0, 71.0, 70.0, 72.0], "value": 71.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 84.0, 85.0, 83.0, 87.0, 98.0, 106.0, 107.0, 71.0], "value": 89.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 139.0, 156.0, 149.0, 150.0, 155.0, 137.0, 138.0, 157.0, 159.0], "value": 148.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 170.0, 170.0, 166.0, 168.0, 166.0, 165.0, 169.0, 169.0, 167.0], "value": 167.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 66.0, 68.0, 66.0, 68.0, 73.0, 84.0, 87.0, 86.0], "value": 73.3, "unit": "score"}], "name": "kraken", "value": 1515.2999999999997, "alertThreshold": 2.0}]} 02:53:14 INFO - Return code: 0 02:53:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 02:53:14 INFO - # TBPL SUCCESS # 02:53:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 02:53:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 02:53:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 02:53:14 INFO - 2016-08-16 02:53:14.362 screenresolution[1661:10055] starting screenresolution argv=screenresolution get 02:53:14 INFO - 2016-08-16 02:53:14.367 screenresolution[1661:10055] Display 0: 1600x1200x32@60 02:53:14 INFO - 2016-08-16 02:53:14.373 screenresolution[1662:10058] starting screenresolution argv=screenresolution list 02:53:14 INFO - Available Modes on Display 0 02:53:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 02:53:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 02:53:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 02:53:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 02:53:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 02:53:14 INFO - Intel Iris: 02:53:14 INFO - Chipset Model: Intel Iris 02:53:14 INFO - Type: GPU 02:53:14 INFO - Bus: Built-In 02:53:14 INFO - VRAM (Dynamic, Max): 1536 MB 02:53:14 INFO - Vendor: Intel (0x8086) 02:53:14 INFO - Device ID: 0x0a2e 02:53:14 INFO - Revision ID: 0x0009 02:53:14 INFO - Displays: 02:53:14 INFO - Display: 02:53:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 02:53:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 02:53:14 INFO - Display Serial Number: 1600x1200 60 02:53:14 INFO - Main Display: Yes 02:53:14 INFO - Mirror: Off 02:53:14 INFO - Online: Yes 02:53:14 INFO - Rotation: Supported 02:53:14 INFO - Return code: 0 02:53:14 INFO - Running post-action listener: _resource_record_post_action 02:53:14 INFO - [mozharness: 2016-08-16 09:53:14.618720Z] Finished run-tests step (success) 02:53:14 INFO - Running post-run listener: _resource_record_post_run 02:53:14 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 38093824; Write bytes: 703095296; Read time: 372; Write time: 3470 02:53:14 INFO - TinderboxPrint: CPU usage
53.3% 02:53:14 INFO - TinderboxPrint: I/O read bytes / time
38,093,824 / 372 02:53:14 INFO - TinderboxPrint: I/O write bytes / time
703,095,296 / 3,470 02:53:14 INFO - TinderboxPrint: CPU idle
2,266.8 (46.7%) 02:53:14 INFO - TinderboxPrint: CPU system
970.6 (20.0%) 02:53:14 INFO - TinderboxPrint: CPU user
1,620.2 (33.4%) 02:53:14 INFO - TinderboxPrint: Swap in / out
443,228,160 / 0 02:53:14 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 385899008; Write bytes: 379913728; Read time: 29029; Write time: 758 02:53:14 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 37135360; Write bytes: 295582720; Read time: 358; Write time: 2656 02:53:15 INFO - Running post-run listener: _upload_blobber_files 02:53:15 INFO - Blob upload gear active. 02:53:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 02:53:15 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 02:53:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 02:53:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 02:53:15 INFO - (blobuploader) - INFO - Open directory for files ... 02:53:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 02:53:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:53:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:53:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 02:53:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:53:16 INFO - (blobuploader) - INFO - Done attempting. 02:53:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 02:53:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:53:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:53:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 02:53:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:53:17 INFO - (blobuploader) - INFO - Done attempting. 02:53:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 02:53:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 02:53:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 02:53:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 02:53:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 02:53:19 INFO - (blobuploader) - INFO - Done attempting. 02:53:19 INFO - (blobuploader) - INFO - Iteration through files over. 02:53:19 INFO - Return code: 0 02:53:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 02:53:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 02:53:19 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a99ffce659545fd3bc0a1d901c2928ca208efdb3c88b1c5c11fefedfae9903e678c56210d7ee87cea1640c4b914f2ae2e0cf366c5d3985647f27fd05a712ac5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea0e12fae9b424b6a954c85dc2ee42f3273e54e2dd6b52eaf6d9a90b18368a7caff9ce0d760fe133c6f406ea45528fb06b73dbe28425f6f421a620ad1f88d09e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c1328cfc109bb96f13fd7039754dce4740d09eaf194f49544597021c56e6b00f8e52b6e2df241b08964be72ddeb07e39d6e2d1954523afdfa4d915aa1f996ae"} 02:53:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 02:53:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 02:53:19 INFO - Contents: 02:53:19 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a99ffce659545fd3bc0a1d901c2928ca208efdb3c88b1c5c11fefedfae9903e678c56210d7ee87cea1640c4b914f2ae2e0cf366c5d3985647f27fd05a712ac5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea0e12fae9b424b6a954c85dc2ee42f3273e54e2dd6b52eaf6d9a90b18368a7caff9ce0d760fe133c6f406ea45528fb06b73dbe28425f6f421a620ad1f88d09e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c1328cfc109bb96f13fd7039754dce4740d09eaf194f49544597021c56e6b00f8e52b6e2df241b08964be72ddeb07e39d6e2d1954523afdfa4d915aa1f996ae"} 02:53:19 INFO - Running post-run listener: copy_logs_to_upload_dir 02:53:19 INFO - Copying logs to upload dir... 02:53:19 INFO - mkdir: /builds/slave/test/build/upload/logs 02:53:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.090885 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-16 02:53:19.977532) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 02:53:19.981033) ========= 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.uAdyogPOwz/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.SFENF2xQsy/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/Fx-Team/sha512/a99ffce659545fd3bc0a1d901c2928ca208efdb3c88b1c5c11fefedfae9903e678c56210d7ee87cea1640c4b914f2ae2e0cf366c5d3985647f27fd05a712ac5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea0e12fae9b424b6a954c85dc2ee42f3273e54e2dd6b52eaf6d9a90b18368a7caff9ce0d760fe133c6f406ea45528fb06b73dbe28425f6f421a620ad1f88d09e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c1328cfc109bb96f13fd7039754dce4740d09eaf194f49544597021c56e6b00f8e52b6e2df241b08964be72ddeb07e39d6e2d1954523afdfa4d915aa1f996ae"} build_url:https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011106 build_url: 'https://queue.taskcluster.net/v1/task/WRNo1BTWQ3SOxKCIPisutA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a99ffce659545fd3bc0a1d901c2928ca208efdb3c88b1c5c11fefedfae9903e678c56210d7ee87cea1640c4b914f2ae2e0cf366c5d3985647f27fd05a712ac5b", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ea0e12fae9b424b6a954c85dc2ee42f3273e54e2dd6b52eaf6d9a90b18368a7caff9ce0d760fe133c6f406ea45528fb06b73dbe28425f6f421a620ad1f88d09e", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1c1328cfc109bb96f13fd7039754dce4740d09eaf194f49544597021c56e6b00f8e52b6e2df241b08964be72ddeb07e39d6e2d1954523afdfa4d915aa1f996ae"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-16 02:53:20.013983) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:53:20.014375) ========= 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.uAdyogPOwz/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.SFENF2xQsy/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.005104 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-16 02:53:20.064204) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 02:53:20.064532) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-16 02:53:20.064894) ========= ========= Total master_lag: 0.17 =========