builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0349 starttime: 1472727217.06 results: success (0) buildid: 20160901012820 builduid: b08db1eff0204bbe9ff05da575f974fa revision: af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.058385) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.058788) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.059065) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.176726) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.177024) ========= 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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-09-01 03:53:37-- 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% 66.5M=0s 2016-09-01 03:53:37 (66.5 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.504263 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.707730) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.708471) ========= 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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.026306 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:37.762465) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 03:53:37.762870) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 --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 af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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-09-01 03:53:37,855 truncating revision to first 12 chars 2016-09-01 03:53:37,855 Setting DEBUG logging. 2016-09-01 03:53:37,855 attempt 1/10 2016-09-01 03:53:37,855 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/af5df7bd1d26?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-01 03:53:38,865 unpacking tar archive at: fx-team-af5df7bd1d26/testing/mozharness/ program finished with exit code 0 elapsedTime=1.280797 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-01 03:53:39.070479) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:39.070850) ========= script_repo_revision: af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:39.071289) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:39.071615) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-01 03:53:39.090573) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 03:53:39.090886) ========= /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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:53:39 INFO - MultiFileLogger online at 20160901 03:53:39 in /builds/slave/test 03:53:39 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 03:53:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 03:53:39 INFO - {'append_to_log': False, 03:53:39 INFO - 'base_work_dir': '/builds/slave/test', 03:53:39 INFO - 'blob_upload_branch': 'Fx-Team', 03:53:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 03:53:39 INFO - 'branch': 'Fx-Team', 03:53:39 INFO - 'buildbot_json_path': 'buildprops.json', 03:53:39 INFO - 'config_files': ('talos/mac_config.py',), 03:53:39 INFO - 'default_actions': ('clobber', 03:53:39 INFO - 'read-buildbot-config', 03:53:39 INFO - 'download-and-extract', 03:53:39 INFO - 'populate-webroot', 03:53:39 INFO - 'create-virtualenv', 03:53:39 INFO - 'install', 03:53:39 INFO - 'run-tests'), 03:53:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 03:53:39 INFO - 'download_minidump_stackwalk': True, 03:53:39 INFO - 'download_symbols': 'ondemand', 03:53:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 03:53:39 INFO - 'tooltool.py': '/tools/tooltool.py', 03:53:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 03:53:39 INFO - '/tools/misc-python/virtualenv.py')}, 03:53:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 03:53:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 03:53:39 INFO - 'installer_path': 'installer.exe', 03:53:39 INFO - 'log_level': 'info', 03:53:39 INFO - 'log_name': 'talos', 03:53:39 INFO - 'log_to_console': True, 03:53:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 03:53:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 03:53:39 INFO - 'opt_config_files': (), 03:53:39 INFO - 'pip_index': False, 03:53:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:53:39 INFO - 'cmd': ('bash', 03:53:39 INFO - '-c', 03:53:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:53:39 INFO - 'enabled': True, 03:53:39 INFO - 'halt_on_failure': False, 03:53:39 INFO - 'name': 'check_screen_resolution'},), 03:53:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 03:53:39 INFO - 'cmd': ('bash', 03:53:39 INFO - '-c', 03:53:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 03:53:39 INFO - 'enabled': True, 03:53:39 INFO - 'halt_on_failure': False, 03:53:39 INFO - 'name': 'check_screen_resolution'},), 03:53:39 INFO - 'run_cmd_checks_enabled': True, 03:53:39 INFO - 'sps_profile': False, 03:53:39 INFO - 'sps_profile_interval': 0, 03:53:39 INFO - 'suite': 'dromaeojs', 03:53:39 INFO - 'system_bits': '32', 03:53:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 03:53:39 INFO - 'title': 't-yosemite-r7-0349', 03:53:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 03:53:39 INFO - 'use_talos_json': True, 03:53:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 03:53:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 03:53:39 INFO - 'work_dir': 'build'} 03:53:39 INFO - [mozharness: 2016-09-01 10:53:39.290294Z] Running clobber step. 03:53:39 INFO - Running pre-action listener: _resource_record_pre_action 03:53:39 INFO - Running main action method: clobber 03:53:39 INFO - rmtree: /builds/slave/test/build 03:53:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 03:53:41 INFO - Running post-action listener: _resource_record_post_action 03:53:41 INFO - [mozharness: 2016-09-01 10:53:41.724031Z] Finished clobber step (success) 03:53:41 INFO - [mozharness: 2016-09-01 10:53:41.724200Z] Running read-buildbot-config step. 03:53:41 INFO - Running pre-action listener: _resource_record_pre_action 03:53:41 INFO - Running main action method: read_buildbot_config 03:53:41 INFO - Using buildbot properties: 03:53:41 INFO - { 03:53:41 INFO - "project": "", 03:53:41 INFO - "product": "firefox", 03:53:41 INFO - "script_repo_revision": "production", 03:53:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 03:53:41 INFO - "repository": "", 03:53:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 03:53:41 INFO - "buildid": "20160901012820", 03:53:41 INFO - "pgo_build": "False", 03:53:41 INFO - "basedir": "/builds/slave/test", 03:53:41 INFO - "buildnumber": 828, 03:53:41 INFO - "slavename": "t-yosemite-r7-0349", 03:53:41 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 03:53:41 INFO - "platform": "yosemite_r7", 03:53:41 INFO - "scheduler": "tests-fx-team-macosx64-talos", 03:53:41 INFO - "branch": "fx-team", 03:53:41 INFO - "repo_path": "integration/fx-team", 03:53:41 INFO - "revision": "af5df7bd1d265bb95c76b69fa4fbcc60a84094f9", 03:53:41 INFO - "stage_platform": "macosx64", 03:53:41 INFO - "builduid": "b08db1eff0204bbe9ff05da575f974fa", 03:53:41 INFO - "slavebuilddir": "test" 03:53:41 INFO - } 03:53:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 03:53:41 INFO - Running post-action listener: _resource_record_post_action 03:53:41 INFO - [mozharness: 2016-09-01 10:53:41.727308Z] Finished read-buildbot-config step (success) 03:53:41 INFO - [mozharness: 2016-09-01 10:53:41.727399Z] Running download-and-extract step. 03:53:41 INFO - Running pre-action listener: _resource_record_pre_action 03:53:41 INFO - Running main action method: download_and_extract 03:53:41 INFO - mkdir: /builds/slave/test/build/tests 03:53:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:53:41 INFO - trying https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 03:53:41 INFO - Downloading https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:43 INFO - Downloaded 1199 bytes. 03:53:43 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 03:53:43 INFO - Using the following test package requirements: 03:53:43 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:53:43 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 03:53:43 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 03:53:43 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'jsshell-mac.zip'], 03:53:43 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 03:53:43 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 03:53:43 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 03:53:43 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 03:53:43 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 03:53:43 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 03:53:43 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 03:53:43 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 03:53: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')]} 03:53:43 INFO - trying https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:53:43 INFO - Downloading https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:44 INFO - Downloaded 16399983 bytes. 03:53:44 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:53:50 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 03:53:50 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')]} 03:53:50 INFO - trying https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 03:53:50 INFO - Downloading https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:51 INFO - Downloaded 16399983 bytes. 03:53:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 03:53: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')]} 03:53:58 INFO - trying https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 03:53:58 INFO - Downloading https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:53:59 INFO - Downloaded 14413998 bytes. 03:53:59 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 03:54:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:00 INFO - trying https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:54:00 INFO - Downloading https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 03:54:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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 03:54:03 INFO - Downloaded 111594980 bytes. 03:54:03 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:54:03 INFO - mkdir: /builds/slave/test/properties 03:54:03 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 03:54:03 INFO - Writing to file /builds/slave/test/properties/build_url 03:54:03 INFO - Contents: 03:54:03 INFO - build_url:https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 03:54:03 INFO - Running post-action listener: _resource_record_post_action 03:54:03 INFO - Running post-action listener: set_extra_try_arguments 03:54:03 INFO - [mozharness: 2016-09-01 10:54:03.836205Z] Finished download-and-extract step (success) 03:54:03 INFO - [mozharness: 2016-09-01 10:54:03.836322Z] Running populate-webroot step. 03:54:03 INFO - Running pre-action listener: _resource_record_pre_action 03:54:03 INFO - Running main action method: populate_webroot 03:54:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 03:54:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 03:54:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 03:54:03 INFO - '--tppagecycles', 03:54:03 INFO - '7'], 03:54:03 INFO - 'tests': ['tsvgm']}}, 03:54:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 03:54:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 03:54:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 03:54:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 03:54:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 03:54:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['damp', 'tps']}, 03:54:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'tests': ['damp', 'tps']}, 03:54:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['dromaeo_dom']}, 03:54:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 03:54:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['basic_compositor_video']}, 03:54:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 03:54:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'other-e10s': {'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['a11yr', 03:54:03 INFO - 'ts_paint', 03:54:03 INFO - 'tpaint', 03:54:03 INFO - 'sessionrestore', 03:54:03 INFO - 'sessionrestore_no_auto_restore', 03:54:03 INFO - 'tabpaint']}, 03:54:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['tsvgx', 03:54:03 INFO - 'tsvgr_opacity', 03:54:03 INFO - 'tart', 03:54:03 INFO - 'tscrollx', 03:54:03 INFO - 'cart']}, 03:54:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 03:54:03 INFO - 'tsvgr_opacity', 03:54:03 INFO - 'tart', 03:54:03 INFO - 'tscrollx', 03:54:03 INFO - 'cart']}, 03:54:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'talos_options': ['--disable-e10s'], 03:54:03 INFO - 'tests': ['tp5o']}, 03:54:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'tests': ['tp5o']}, 03:54:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'talos_options': ['--disable-e10s', 03:54:03 INFO - '--xperf_path', 03:54:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:03 INFO - 'tests': ['tp5n']}, 03:54:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 03:54:03 INFO - 'talos_options': ['--xperf_path', 03:54:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 03:54:03 INFO - 'tests': ['tp5n']}}, 03:54:03 INFO - 'talos.zip': {'path': '', 03:54:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 03:54:03 INFO - Running post-action listener: _resource_record_post_action 03:54:03 INFO - [mozharness: 2016-09-01 10:54:03.855083Z] Finished populate-webroot step (success) 03:54:03 INFO - [mozharness: 2016-09-01 10:54:03.855188Z] Running create-virtualenv step. 03:54:03 INFO - Running pre-action listener: _resource_record_pre_action 03:54:03 INFO - Running main action method: create_virtualenv 03:54:03 INFO - Creating virtualenv /builds/slave/test/build/venv 03:54:03 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 03:54:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 03:54:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 03:54:03 INFO - Using real prefix '/tools/python27' 03:54:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 03:54:04 INFO - Installing distribute.............................................................................................................................................................................................done. 03:54:07 INFO - Installing pip.................done. 03:54:07 INFO - Return code: 0 03:54:07 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 03:54: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')]} 03:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54: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')]} 03:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:07 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:07 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 03:54:07 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 03:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:07 INFO - 'HOME': '/Users/cltbld', 03:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:07 INFO - 'LOGNAME': 'cltbld', 03:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:07 INFO - 'MOZ_NO_REMOTE': '1', 03:54:07 INFO - 'NO_EM_RESTART': '1', 03:54:07 INFO - 'PAGER': '/bin/cat', 03:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:07 INFO - 'PWD': '/builds/slave/test', 03:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:07 INFO - 'SHELL': '/bin/bash', 03:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:07 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:07 INFO - 'USER': 'cltbld', 03:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:07 INFO - 'XPC_FLAGS': '0x0', 03:54:07 INFO - 'XPC_SERVICE_NAME': '0', 03:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:07 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 03:54:07 INFO - Cleaning up... 03:54:07 INFO - Return code: 0 03:54:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 03:54: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')]} 03:54:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54: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')]} 03:54:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:07 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:07 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 03:54:07 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 03:54:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:07 INFO - 'HOME': '/Users/cltbld', 03:54:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:07 INFO - 'LOGNAME': 'cltbld', 03:54:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:07 INFO - 'MOZ_NO_REMOTE': '1', 03:54:07 INFO - 'NO_EM_RESTART': '1', 03:54:07 INFO - 'PAGER': '/bin/cat', 03:54:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:07 INFO - 'PWD': '/builds/slave/test', 03:54:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:07 INFO - 'SHELL': '/bin/bash', 03:54:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:07 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:07 INFO - 'USER': 'cltbld', 03:54:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:07 INFO - 'XPC_FLAGS': '0x0', 03:54:07 INFO - 'XPC_SERVICE_NAME': '0', 03:54:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:08 INFO - Downloading/unpacking psutil>=3.1.1 03:54:08 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 03:54:08 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 03:54:08 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 03:54:08 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 03:54:08 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 03:54:08 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 03:54:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 03:54:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:11 INFO - Installing collected packages: psutil 03:54:11 INFO - Running setup.py install for psutil 03:54:11 INFO - building 'psutil._psutil_osx' extension 03:54:11 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 03:54:12 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 03:54:12 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 03:54:12 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 03:54:12 INFO - building 'psutil._psutil_posix' extension 03:54:12 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 03:54:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 03:54:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 03:54:12 INFO - ^ 03:54:12 INFO - 1 warning generated. 03:54:12 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 03:54:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 03:54:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 03:54:12 INFO - Successfully installed psutil 03:54:12 INFO - Cleaning up... 03:54:13 INFO - Return code: 0 03:54:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 03:54:13 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')]} 03:54:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:13 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')]} 03:54:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:13 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:13 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 03:54:13 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 03:54:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:13 INFO - 'HOME': '/Users/cltbld', 03:54:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:13 INFO - 'LOGNAME': 'cltbld', 03:54:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:13 INFO - 'MOZ_NO_REMOTE': '1', 03:54:13 INFO - 'NO_EM_RESTART': '1', 03:54:13 INFO - 'PAGER': '/bin/cat', 03:54:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:13 INFO - 'PWD': '/builds/slave/test', 03:54:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:13 INFO - 'SHELL': '/bin/bash', 03:54:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:13 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:13 INFO - 'USER': 'cltbld', 03:54:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:13 INFO - 'XPC_FLAGS': '0x0', 03:54:13 INFO - 'XPC_SERVICE_NAME': '0', 03:54:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 03:54:13 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 03:54:13 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 03:54:13 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 03:54:13 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 03:54:13 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 03:54:13 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 03:54:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 03:54:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 03:54:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 03:54:15 INFO - Installing collected packages: mozsystemmonitor 03:54:15 INFO - Running setup.py install for mozsystemmonitor 03:54:16 INFO - Successfully installed mozsystemmonitor 03:54:16 INFO - Cleaning up... 03:54:16 INFO - Return code: 0 03:54:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:16 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:16 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 03:54:16 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 03:54:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:16 INFO - 'HOME': '/Users/cltbld', 03:54:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:16 INFO - 'LOGNAME': 'cltbld', 03:54:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:16 INFO - 'MOZ_NO_REMOTE': '1', 03:54:16 INFO - 'NO_EM_RESTART': '1', 03:54:16 INFO - 'PAGER': '/bin/cat', 03:54:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:16 INFO - 'PWD': '/builds/slave/test', 03:54:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:16 INFO - 'SHELL': '/bin/bash', 03:54:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:16 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:16 INFO - 'USER': 'cltbld', 03:54:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:16 INFO - 'XPC_FLAGS': '0x0', 03:54:16 INFO - 'XPC_SERVICE_NAME': '0', 03:54:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:16 INFO - Downloading/unpacking jsonschema==2.5.1 03:54:16 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 03:54:16 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 03:54:16 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 03:54:16 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 03:54:16 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 03:54:16 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 03:54:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 03:54:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 03:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:18 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:18 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:18 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:18 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:19 INFO - Downloading functools32-3.2.3-2.tar.gz 03:54:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 03:54:19 INFO - warning: no files found matching '*.txt' 03:54:19 INFO - no previously-included directories found matching 'build' 03:54:19 INFO - no previously-included directories found matching 'dist' 03:54:19 INFO - no previously-included directories found matching '.git*' 03:54:19 INFO - Installing collected packages: jsonschema, functools32 03:54:19 INFO - Running setup.py install for functools32 03:54:19 INFO - warning: no files found matching '*.txt' 03:54:19 INFO - no previously-included directories found matching 'build' 03:54:19 INFO - no previously-included directories found matching 'dist' 03:54:19 INFO - no previously-included directories found matching '.git*' 03:54:19 INFO - Successfully installed jsonschema functools32 03:54:19 INFO - Cleaning up... 03:54:19 INFO - Return code: 0 03:54:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 03:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 03:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:19 INFO - 'HOME': '/Users/cltbld', 03:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:19 INFO - 'LOGNAME': 'cltbld', 03:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:19 INFO - 'MOZ_NO_REMOTE': '1', 03:54:19 INFO - 'NO_EM_RESTART': '1', 03:54:19 INFO - 'PAGER': '/bin/cat', 03:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:19 INFO - 'PWD': '/builds/slave/test', 03:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:19 INFO - 'SHELL': '/bin/bash', 03:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:19 INFO - 'USER': 'cltbld', 03:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:19 INFO - 'XPC_FLAGS': '0x0', 03:54:19 INFO - 'XPC_SERVICE_NAME': '0', 03:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 03:54:19 INFO - Cleaning up... 03:54:19 INFO - Return code: 0 03:54:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:19 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 03:54:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 03:54:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:19 INFO - 'HOME': '/Users/cltbld', 03:54:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:19 INFO - 'LOGNAME': 'cltbld', 03:54:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:19 INFO - 'MOZ_NO_REMOTE': '1', 03:54:19 INFO - 'NO_EM_RESTART': '1', 03:54:19 INFO - 'PAGER': '/bin/cat', 03:54:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:19 INFO - 'PWD': '/builds/slave/test', 03:54:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:19 INFO - 'SHELL': '/bin/bash', 03:54:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:19 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:19 INFO - 'USER': 'cltbld', 03:54:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:19 INFO - 'XPC_FLAGS': '0x0', 03:54:19 INFO - 'XPC_SERVICE_NAME': '0', 03:54:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:20 INFO - Downloading/unpacking blobuploader==1.2.4 03:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:20 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:20 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 03:54:20 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 03:54:20 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 03:54:23 INFO - Downloading blobuploader-1.2.4.tar.gz 03:54:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 03:54:23 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 03:54:23 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 03:54:23 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 03:54:23 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 03:54:23 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 03:54:23 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 03:54:23 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 03:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 03:54:24 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 03:54:24 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 03:54:24 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 03:54:24 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 03:54:24 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 03:54:24 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 03:54:24 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 03:54:24 INFO - Downloading docopt-0.6.1.tar.gz 03:54:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 03:54:24 INFO - Installing collected packages: blobuploader, requests, docopt 03:54:24 INFO - Running setup.py install for blobuploader 03:54:24 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 03:54:24 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 03:54:24 INFO - Running setup.py install for requests 03:54:25 INFO - Running setup.py install for docopt 03:54:25 INFO - Successfully installed blobuploader requests docopt 03:54:25 INFO - Cleaning up... 03:54:25 INFO - Return code: 0 03:54:25 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:54:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:25 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:25 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 03:54:25 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 03:54:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:25 INFO - 'HOME': '/Users/cltbld', 03:54:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:25 INFO - 'LOGNAME': 'cltbld', 03:54:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:25 INFO - 'MOZ_NO_REMOTE': '1', 03:54:25 INFO - 'NO_EM_RESTART': '1', 03:54:25 INFO - 'PAGER': '/bin/cat', 03:54:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:25 INFO - 'PWD': '/builds/slave/test', 03:54:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:25 INFO - 'SHELL': '/bin/bash', 03:54:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:25 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:25 INFO - 'USER': 'cltbld', 03:54:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:25 INFO - 'XPC_FLAGS': '0x0', 03:54:25 INFO - 'XPC_SERVICE_NAME': '0', 03:54:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:25 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:54:25 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-VuVDVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-iwnXfU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-pKPvDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-xK66ah-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-bQSQtL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-uBBnZz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-5ZRJYR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-XCVwpw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-EylXsg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1JAXvK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:54:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:54:26 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-QNybId-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-byBanE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Fik3YR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-x3LYqn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-u4OQQ5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-PeVoY3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:54:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:54:27 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-PMz0lh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:54:27 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 03:54:27 INFO - Running setup.py install for manifestparser 03:54:27 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 03:54:27 INFO - Running setup.py install for mozcrash 03:54:27 INFO - Running setup.py install for mozdebug 03:54:28 INFO - Running setup.py install for mozdevice 03:54:28 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Installing dm script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Running setup.py install for mozfile 03:54:28 INFO - Running setup.py install for mozhttpd 03:54:28 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Running setup.py install for mozinfo 03:54:28 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Running setup.py install for mozInstall 03:54:28 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 03:54:28 INFO - Running setup.py install for mozleak 03:54:28 INFO - Running setup.py install for mozlog 03:54:29 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for moznetwork 03:54:29 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for mozprocess 03:54:29 INFO - Running setup.py install for mozprofile 03:54:29 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for mozrunner 03:54:29 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 03:54:29 INFO - Running setup.py install for mozscreenshot 03:54:29 INFO - Running setup.py install for moztest 03:54:30 INFO - Running setup.py install for mozversion 03:54:30 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 03:54:30 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 03:54:30 INFO - Cleaning up... 03:54:30 INFO - Return code: 0 03:54:30 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:30 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')]} 03:54:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:30 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')]} 03:54:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:30 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:30 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 03:54:30 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 03:54:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:30 INFO - 'HOME': '/Users/cltbld', 03:54:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:30 INFO - 'LOGNAME': 'cltbld', 03:54:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:30 INFO - 'MOZ_NO_REMOTE': '1', 03:54:30 INFO - 'NO_EM_RESTART': '1', 03:54:30 INFO - 'PAGER': '/bin/cat', 03:54:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:30 INFO - 'PWD': '/builds/slave/test', 03:54:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:30 INFO - 'SHELL': '/bin/bash', 03:54:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:30 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:30 INFO - 'USER': 'cltbld', 03:54:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:30 INFO - 'XPC_FLAGS': '0x0', 03:54:30 INFO - 'XPC_SERVICE_NAME': '0', 03:54:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 03:54:30 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-Y4huFs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 03:54:30 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)) 03:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 03:54:30 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-uUcw_a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 03:54:30 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)) 03:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 03:54:30 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-pSkM4H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 03:54:30 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)) 03:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 03:54:30 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-dbnZSg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 03:54:30 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)) 03:54:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 03:54:30 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-utlUiL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-HUm75d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-QDxAX6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-UiiwyE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-beP4Ds-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-EXLzxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-kFr0uW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-1mVWEH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-OpYIcH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 03:54:31 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)) 03:54:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 03:54:31 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-YsrvWg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:32 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-_wISjC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 03:54:32 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-JzO6Jb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 03:54:32 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)) 03:54:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 03:54:32 INFO - Running setup.py (path:/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/pip-OWZVmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 03:54:32 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)) 03:54:32 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)) 03:54:32 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)) 03:54:32 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)) 03:54:32 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)) 03:54:32 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)) 03:54:32 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54: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 03:54:34 INFO - Downloading blessings-1.6.tar.gz 03:54:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 03:54:34 INFO - Installing collected packages: blessings 03:54:34 INFO - Running setup.py install for blessings 03:54:35 INFO - Successfully installed blessings 03:54:35 INFO - Cleaning up... 03:54:35 INFO - Return code: 0 03:54:35 INFO - Done creating virtualenv /builds/slave/test/build/venv. 03:54:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:54:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:54:35 INFO - Reading from file tmpfile_stdout 03:54:35 INFO - Current package versions: 03:54:35 INFO - blessings == 1.6 03:54:35 INFO - blobuploader == 1.2.4 03:54:35 INFO - docopt == 0.6.1 03:54:35 INFO - functools32 == 3.2.3-2 03:54:35 INFO - jsonschema == 2.5.1 03:54:35 INFO - manifestparser == 1.1 03:54:35 INFO - mozInstall == 1.12 03:54:35 INFO - mozcrash == 0.17 03:54:35 INFO - mozdebug == 0.1 03:54:35 INFO - mozdevice == 0.48 03:54:35 INFO - mozfile == 1.2 03:54:35 INFO - mozhttpd == 0.7 03:54:35 INFO - mozinfo == 0.9 03:54:35 INFO - mozleak == 0.1 03:54:35 INFO - mozlog == 3.3 03:54:35 INFO - moznetwork == 0.27 03:54:35 INFO - mozprocess == 0.23 03:54:35 INFO - mozprofile == 0.28 03:54:35 INFO - mozrunner == 6.12 03:54:35 INFO - mozscreenshot == 0.1 03:54:35 INFO - mozsystemmonitor == 0.3 03:54:35 INFO - moztest == 0.7 03:54:35 INFO - mozversion == 1.4 03:54:35 INFO - psutil == 3.1.1 03:54:35 INFO - requests == 1.2.3 03:54:35 INFO - wsgiref == 0.1.2 03:54:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 03:54:35 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')]} 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 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')]} 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:35 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 03:54:35 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 03:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:35 INFO - 'HOME': '/Users/cltbld', 03:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:35 INFO - 'LOGNAME': 'cltbld', 03:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:35 INFO - 'MOZ_NO_REMOTE': '1', 03:54:35 INFO - 'NO_EM_RESTART': '1', 03:54:35 INFO - 'PAGER': '/bin/cat', 03:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:35 INFO - 'PWD': '/builds/slave/test', 03:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:35 INFO - 'SHELL': '/bin/bash', 03:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:35 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:35 INFO - 'USER': 'cltbld', 03:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:35 INFO - 'XPC_FLAGS': '0x0', 03:54:35 INFO - 'XPC_SERVICE_NAME': '0', 03:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 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)) 03:54:35 INFO - Cleaning up... 03:54:35 INFO - Return code: 0 03:54:35 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 03:54:35 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')]} 03:54:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 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')]} 03:54:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 03:54:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 03:54:35 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 0x103b89a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103c1e618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb200c80670>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, '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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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 03:54:35 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 03:54:35 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 03:54:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:54:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:54:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:54:35 INFO - 'HOME': '/Users/cltbld', 03:54:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:54:35 INFO - 'LOGNAME': 'cltbld', 03:54:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:54:35 INFO - 'MOZ_NO_REMOTE': '1', 03:54:35 INFO - 'NO_EM_RESTART': '1', 03:54:35 INFO - 'PAGER': '/bin/cat', 03:54:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:54:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:54:35 INFO - 'PWD': '/builds/slave/test', 03:54:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:54:35 INFO - 'SHELL': '/bin/bash', 03:54:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:54:35 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:54:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:54:35 INFO - 'USER': 'cltbld', 03:54:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:54:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:54:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:54:35 INFO - 'XPC_FLAGS': '0x0', 03:54:35 INFO - 'XPC_SERVICE_NAME': '0', 03:54:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:54:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 03:54:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 03:54:35 INFO - Cleaning up... 03:54:35 INFO - Return code: 0 03:54:35 INFO - Running post-action listener: _resource_record_post_action 03:54:35 INFO - Running post-action listener: _start_resource_monitoring 03:54:36 INFO - Starting resource monitoring. 03:54:36 INFO - [mozharness: 2016-09-01 10:54:36.022681Z] Finished create-virtualenv step (success) 03:54:36 INFO - [mozharness: 2016-09-01 10:54:36.023668Z] Running install step. 03:54:36 INFO - Running pre-action listener: _resource_record_pre_action 03:54:36 INFO - Running main action method: install 03:54:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 03:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 03:54:36 INFO - Reading from file tmpfile_stdout 03:54:36 INFO - Detecting whether we're running mozinstall >=1.0... 03:54:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 03:54:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 03:54:36 INFO - Reading from file tmpfile_stdout 03:54:36 INFO - Output received: 03:54:36 INFO - Usage: mozinstall [options] installer 03:54:36 INFO - Options: 03:54:36 INFO - -h, --help show this help message and exit 03:54:36 INFO - -d DEST, --destination=DEST 03:54:36 INFO - Directory to install application into. [default: 03:54:36 INFO - "/builds/slave/test"] 03:54:36 INFO - --app=APP Application being installed. [default: firefox] 03:54:36 INFO - mkdir: /builds/slave/test/build/application 03:54:36 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'] 03:54:36 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 03:55:10 INFO - Reading from file tmpfile_stdout 03:55:10 INFO - Output received: 03:55:10 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 03:55:10 INFO - Running post-action listener: _resource_record_post_action 03:55:10 INFO - [mozharness: 2016-09-01 10:55:10.234999Z] Finished install step (success) 03:55:10 INFO - [mozharness: 2016-09-01 10:55:10.235135Z] Running run-tests step. 03:55:10 INFO - Running pre-action listener: _resource_record_pre_action 03:55:10 INFO - Running main action method: run_tests 03:55:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 03:55:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 03:55:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 03:55:10 INFO - 2016-09-01 03:55:10.249 screenresolution[1648:7965] starting screenresolution argv=screenresolution get 03:55:10 INFO - 2016-09-01 03:55:10.252 screenresolution[1648:7965] Display 0: 1600x1200x32@60 03:55:10 INFO - 2016-09-01 03:55:10.258 screenresolution[1649:7967] starting screenresolution argv=screenresolution list 03:55:10 INFO - Available Modes on Display 0 03:55:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 03:55:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 03:55:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 03:55:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 03:55:10 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 03:55:10 INFO - Intel Iris: 03:55:10 INFO - Chipset Model: Intel Iris 03:55:10 INFO - Type: GPU 03:55:10 INFO - Bus: Built-In 03:55:10 INFO - VRAM (Dynamic, Max): 1536 MB 03:55:10 INFO - Vendor: Intel (0x8086) 03:55:10 INFO - Device ID: 0x0a2e 03:55:10 INFO - Revision ID: 0x0009 03:55:10 INFO - Displays: 03:55:10 INFO - Display: 03:55:10 INFO - Resolution: 1600 x 1200 @ 60 Hz 03:55:10 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 03:55:10 INFO - Display Serial Number: 1600x1200 60 03:55:10 INFO - Main Display: Yes 03:55:10 INFO - Mirror: Off 03:55:10 INFO - Online: Yes 03:55:10 INFO - Rotation: Supported 03:55:10 INFO - Return code: 0 03:55:10 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 03:55:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 03:55:10 INFO - Python 2.7.3 03:55:10 INFO - Return code: 0 03:55:10 INFO - grabbing minidump binary from tooltool 03:55:10 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 03:55:10 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 0x103aedd30>, '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 0x103852db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ae5b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 03:55:10 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 03:55:10 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 03:55:10 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 03:55:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 03:55:10 INFO - Return code: 0 03:55:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 03:55:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 03:55:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 03:55:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 03:55:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 03:55:10 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/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 03:55:10 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/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0349 --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 03:55:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.4VcIsFVjoD/Render', 03:55:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 03:55:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 03:55:10 INFO - 'HOME': '/Users/cltbld', 03:55:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 03:55:10 INFO - 'LOGNAME': 'cltbld', 03:55:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 03:55:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 03:55:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 03:55:10 INFO - 'MOZ_NO_REMOTE': '1', 03:55:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 03:55:10 INFO - 'NO_EM_RESTART': '1', 03:55:10 INFO - 'PAGER': '/bin/cat', 03:55:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 03:55:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 03:55:10 INFO - 'PWD': '/builds/slave/test', 03:55:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 03:55:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 03:55:10 INFO - 'SHELL': '/bin/bash', 03:55:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ljVAEUD4wL/Listeners', 03:55:10 INFO - 'TMPDIR': '/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/', 03:55:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 03:55:10 INFO - 'USER': 'cltbld', 03:55:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 03:55:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 03:55:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 03:55:10 INFO - 'XPC_FLAGS': '0x0', 03:55:10 INFO - 'XPC_SERVICE_NAME': '0', 03:55:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 03:55:10 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/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0349', '--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 03:55:10 INFO - mozversion application_buildid: 20160901012820 03:55:10 INFO - mozversion application_changeset: af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 03:55:10 INFO - mozversion application_display_name: Nightly 03:55:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 03:55:10 INFO - mozversion application_name: Firefox 03:55:10 INFO - mozversion application_remotingname: firefox 03:55:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 03:55:10 INFO - mozversion application_vendor: Mozilla 03:55:10 INFO - mozversion application_version: 51.0a1 03:55:10 INFO - mozversion platform_buildid: 20160901012820 03:55:10 INFO - mozversion platform_changeset: af5df7bd1d265bb95c76b69fa4fbcc60a84094f9 03:55:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 03:55:10 INFO - mozversion platform_version: 51.0a1 03:55:10 INFO - using testdate: 1472727310 03:55:10 INFO - actual date: 1472727310 03:55:10 INFO - starting webserver on 'localhost:49265' 03:55:10 INFO - SUITE-START | Running 2 tests 03:55:10 INFO - TEST-START | dromaeo_css 03:55:10 INFO - operating with platform_type : mac_ 03:55:10 INFO - Initialising browser for dromaeo_css test... 03:55:10 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpBlL2Dz/profile http://localhost:49265/getInfo.html) 03:55:13 INFO - PROCESS | 1659 | __metrics Screen width/height:1600/1200 03:55:13 INFO - PROCESS | 1659 | colorDepth:24 03:55:13 INFO - PROCESS | 1659 | Browser inner width/height: 1024/689 03:55:13 INFO - PROCESS | 1659 | __metrics 03:55:13 INFO - PROCESS | 1659 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 03:55:13 INFO - PROCESS | 1659 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 03:55:14 INFO - TEST-INFO | 1659: exit 0 03:55:14 INFO - Browser initialized. 03:55:14 INFO - Running cycle 1/1 for dromaeo_css test... 03:55:14 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpBlL2Dz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 03:55:26 INFO - PROCESS | 1662 | RSS: Main: 234065920 03:55:26 INFO - PROCESS | 1662 | 03:56:17 INFO - PROCESS | 1662 | 1472727377128 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] 03:58:29 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49265/tests/dromaeo/cssquery-ext.html) 03:58:31 INFO - PROCESS | 1662 | RSS: Main: 387788800 03:58:31 INFO - PROCESS | 1662 | 04:01:34 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-ext.html (next: http://localhost:49265/tests/dromaeo/cssquery-jquery.html) 04:01:35 INFO - PROCESS | 1662 | RSS: Main: 372240384 04:01:35 INFO - PROCESS | 1662 | 04:04:37 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49265/tests/dromaeo/cssquery-mootools.html) 04:04:38 INFO - PROCESS | 1662 | RSS: Main: 328318976 04:04:38 INFO - PROCESS | 1662 | 04:07:40 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49265/tests/dromaeo/cssquery-prototype.html) 04:07:41 INFO - PROCESS | 1662 | RSS: Main: 339812352 04:07:41 INFO - PROCESS | 1662 | 04:10:44 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49265/tests/dromaeo/cssquery-yui.html) 04:10:45 INFO - PROCESS | 1662 | RSS: Main: 325074944 04:10:45 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | Cycle 1(1): loaded http://localhost:49265/tests/dromaeo/cssquery-yui.html (next: http://localhost:49265/tests/dromaeo/cssquery-dojo.html) 04:13:48 INFO - PROCESS | 1662 | RSS: Main: 472240128 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | __start_tp_report 04:13:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 04:13:48 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 04:13:48 INFO - PROCESS | 1662 | |i|pagename|runs| 04:13:48 INFO - PROCESS | 1662 | |0;dojo.html;3345.197152683356;3348.6316505184427;3381.9661803381973;3419.179396944734;3423.1613254752588;5607.803726869561;5687.232223649806;5703.230063941367;5775.711214439282;5788.392218817026;5644.181593668915;5690.516306113975;5745.195672605828;5781.13283007549;5812.099124635678;5125.538701516863;5179.171332586787;5197.948020519803;5205.349331333583;5310.73446327684;5289.761960711778;5299.6820190788485;5302.125149350352;5309.991101690685;5347.385050719178;5111.258867464197;5168.431472538018;5175.146100893348;5198.948010519903;5231.398389185254;5221.503957124067;5233.371995360543;5233.921491177597;5239.764210610493;5241.528262456359;5111.08000559899;5196.92204616931;5203.6877787332505;5213.374395072616;5226.346706661668;5665.433456654343;5684.63049901755;5696.9745445819735;5697.515711164556;5775.682337471438;6236;6307.243130824285;6308.369163083655;6317.93682063178;6326.43062124411;6122.611623597858;6220.222472190976;6264.843378915518;6270.278917924452;6288.245410550718;5796.362400135981;5818.5636077294375;5831.650100993869;5842.269716285465;5865.35481097079;6241.937580624136;6243.375662433811;6250.343713910065;6263.185785847902;6278.995360742259;6032.788852390233;6117.816465506042;6147.354527774609;6156.445919867234;6206.379362063848;6300.747970081237;6307.810765677037;6309.337519560472;6314.336994615591;6329.715162817684;6262.248530176408;6265.874682506415;6270.31026587075;6281.685915704288;6301.558890877594;6074.362191969868;6183.412575805291;6189.566730328924;6232.594881332699;6258.468030217483;4916.8770780730765;4947.529984651453;4951.9752401237765;4964.553190212898;4984.077945580079;4409.228385032607;4410.69125161242;4420.7789610519985;4452.643788496913;4467.932981005288;5671.149327600893;5732.856678583069;5749.13762935563;5807.216025836543;5820.708964551755;6291.559590828598;6292;6309.369063093745;6332.52506062038;6335.3981371769605;5708.343540492897;5796.014677504834;5804.633312678637;5818.418158184148;5868.23712917318;5342.465753424704;5403.756830942617;5425.701586412785;5465.781368745285;5478.233047373371;6250.656213908279;6294.402031806971;6322.399372059647;6326.4622507087415;6342.90485642725;6278.937210627835;6281.811545653638;6282.434580887742;6290.276618188784;6304.968475157595;6183.258009038944;6210.472109870711;6300.84247893788;6313.1477250572425;6323.08315294289;6125.019996800491;6130.6628135452975;6162.630242185483;6172.598781079216;6189.1025801257665;6315.873682526416;6316.084167795736;6322.178116844781;6324.335944725737;6325.8734825304155;5812.070068788974;5943.316518600416;6088.330283668704;6090.390960903874;6096.02463605821;5777.884442311046;5978.342382338026;5993.0710739835395;5997.190379298913;5997.82006539787;5901.911471327848;5903.616264942765;5957.434043765836;5966.49284810796;5969.3732158122775;6008.399160083956;6046.879062418815;6063.878722425438;6064.818055458343;6065.211522502046;5847.8538036547725;5904.6457212565665;5919.4968427684125;5924.31870334917;5924.46679798803 04:13:48 INFO - PROCESS | 1662 | |1;ext.html;502.44981744989224;506.825145324863;506.8732816795801;530.4748299183807;551.6056019945743;30172.396552068945;30734.84632576837;31355.68644313559;40079.198416031664;40533.39199912004;37432.625673743256;38642.26109825058;41253;41620;41660.37509437361;9576.856347154799;9740.902590974107;9751.853722194173;9754.561044753003;9772.853407198898;9647.662331818388;9748.561314740818;9758.756031099243;9759.75600609987;9762.072603102693;9721.07649773274;9778.608855645765;9779.41323520591;9780.217582593375;9799.559019844088;9687.079727425884;9742.756431089208;9749.658761943369;9767.36512126713;9782.608695652165;8687.913120868774;9390.67132650354;9683.128518433305;9720.805583888283;9731.318807683465;38906.61093389058;39231.01922451933;39280.01799954996;39337.80331098326;39385;3286;3309.586301712286;3312.370649576597;3370.6460821613623;3427.057559171223;29699.85150074236;30088.398232035237;30628.38743225123;31222;31390.37219255625;2601.336659151923;3093.3349329893954;3101;3166.319241363095;3202.2794871153806;442697;447849;448690;449160;454952;55913.881722365324;56060.719696401255;56096;56133.999999999185;56283.592910176754;33681.98954031383;53664.73167634137;53725.164735174025;53934;54074.45925540773;15487.303071361815;15996.920015400081;16006.439774608065;16035.11806850611;16108.114053727157;12324.890759831658;12407.627771166879;12421.77033971174;12426.62720118398;12437.62687119388;18270.26918923228;18298.725519117223;18299.810512316657;18329.450116496526;18347.35784247545;7535.623218839036;7597.886031709529;7622.885656715153;7681.846363072707;7690.346320562815;850.2815121222557;902.7026218274507;926.2913870888775;967.901431874813;989.302541708094;28477.00330488423;28569.14292571226;29675.703242967294;29778.702212978027;29831.850840745657;1038.5274700011478;1054.2988912373187;1055.9577616895392;1076.316538997742;1100.273819279272;15103.697926041417;15149.394024239127;15233.6191595211;15547.84452155464;15557.533274001798;623809;723575;986785;987495;990018;55885;56598.71700641471;56652;56676.43323566794;56886.7155664219;1186.7567148734488;1248.17620370555;1310.4299629661127;1326.8673132686654;1352.4252192818012;28661.856690717246;29049.564256536592;29722.702772971992;30525;31239;51697;52467.73766131145;52479;52505.7374713124;52588.73705631601;588826.055869718;589141;593228;594347;597253;21477;23093.422664432852;26020.609690854275;26032.609510856975;26361.73638263593;37307.06732331713;40796.79601602092;44138;44726.77636611796;44852.77573612111;35831.10422239465;35967.907220875095;36377;36586.8170659145;37991;35935.281294373446;36258;36306.64320281583;37486.6251337494;37517.4372384409;35535.11162220965;36109.63890361063;36328;37201.4419783698;37566;32007.67992320047;33139.171520712174;33763.662363377036;33814.3237135251;34010;19973;22784.886075569517;24011.399715007265;24800.875995619906;24882 04:13:48 INFO - PROCESS | 1662 | |2;jquery.html;328.6910304313945;345.4766029465359;369.4550537956516;375.4743359296985;400.3874072668816;15446.69106617867;17172.656546869057;20570.588588228227;20590.999999999964;20754.89622551887;20240.797592024075;20553.280635177773;20860.791392086074;20957.47606309839;21441.46396340092;5206.739663016834;5692.402297758737;6013.1581578579035;6048.364921683204;6155.476784473302;6006.129111278862;6087.178230938811;6099.3290738019;6102.267727872661;6104.2674879014585;5832.854178645546;6055.788047418341;6057.273127224739;6118.235220597426;6176.382361763833;5935.762569497215;6134.478569321591;6148.231471066117;6156.538259630545;6164.321924588292;5829.242198514166;5940.881182376372;5946.464818166342;5947.316058653267;5948.048312270059;13277.136986095873;13279;13350.86649133506;13506.257155856423;13582.25297608631;2298.839081264312;2329.8019668328043;2367.6803631509697;2466.3217615155795;2472.61674440462;8633.525156116411;9620.37467564606;10510.369377837354;12802.423890924838;15116.697666046728;1820.7359932809668;1891.6122194950003;2081.7443582036035;2179.193559580823;2217.733871935378;130997;145779.27110364486;146066.24793256252;146478;155850.66224006648;21023;21055.578888422453;21422.78577214239;21424.678629820566;21663.566728665653;35298;35315.823420883244;35406.82296588552;35468.822655887074;35472.75845345401;10587.311824731369;10883.292585981886;11014.229003969805;11020.173486988508;11273.605423810128;8023.518588884685;8674.175953284428;9032.729018129467;9062.682806101886;9288.210502107262;7947.562884041433;8437.987441507059;9563.856542151872;9600.903990960142;9745.756356091155;3088.98455507721;3358.059743271888;3632.56409230894;3730.720195985312;3732.2722069196248;761.3186198352444;810.7162493127477;877.7015814622931;883.7613844262141;960.6397600899662;17895.552611184823;21020.57958840845;21674.458138546663;21679.566408672054;21701.34895953124;883.5008220355479;895.91936725695;902.1069141549949;905.2893478619384;937.1893312284825;9054.185123338799;9803.607855685692;11324.660260192208;11351.978321951065;11359.545618175345;564551;571796;576641;577819;580058;127788;130372.04441933379;131697.34151329182;131756;135151;950.729042222977;957.8419560772395;981.293468702533;981.4651015196735;982.9115379615869;18975.9563224024;19209.90395048072;19218.51953701113;19268.903655481634;19278;55812.72093639506;57081.714591426775;57174.142387865046;57309.71345143248;57959.420405797086;80100;80471;81009.59495202487;81262.59368703119;81759;8077.394195435483;8140.918590814253;8229.300509456763;8264;8340.207680270365;11463.828042579202;12966.35168241603;13012.349382531025;13173.40719667598;13249.536266230636;11206.43967801623;11261;11611.245269057485;11625.47685354178;11721.531138754695;8973.532590910412;11154.776904462027;11260.662180134608;11455.770884582094;11474.541018359172;9732;11519.424028798694;11612.012978896888;11623.6512904613;11784.175107742376;7116.146062472537;7166.928330716834;7402.222766609429;7416.332530072319;8477.788055298666;9435.339526233098;11595.710107247387;11789.587364442203;11853.52585896589;11888.108391870472 04:13:48 INFO - PROCESS | 1662 | |3;mootools.html;1318.386950068218;1349.1972276495483;1532.6551525906668;1712.982870171298;1937.2541571494962;7742.458027938046;8567.528785916773;8799;8805.603747831356;8877.60050797714;8640.697575584856;8724.127587241273;8726.301895848334;8734.03925568187;8736.606852691628;2355.4464700795343;2383.4041489627593;2384.0940442631777;2388.725296590897;2389.653500242464;1989.4727897107277;2036.643587372204;2369.5782530481747;2374.3351861478727;2375.596148654733;1970.8324792392664;2340.871252081135;2353.9293821185393;2365.9408514787096;2385.666006759055;2299.528596637702;2324.465372964219;2326.918557850484;2327.8836058197107;2329.6389059695775;1944.5041514413733;2314.618088015475;2321.4892723600783;2321.6981792367055;2332.0322066342446;8716.346274029473;8724.258438032775;8727.519986400745;8822.779430514225;8824.941007079127;5200.349956255468;5381.165919282517;5386.757595908193;5402.864928376783;5414.620976531644;3398.218409765743;3407.9829600851835;3444.5522082129414;3482.0424383294544;3488.2674638325984;1062.8458873463383;1091.0671375973507;1726.6978278801284;1804.9097545122822;1839.4481655503296;11693.70765730855;11754.471048802825;11755.941220293844;11756.764864702658;11798.587049453314;4171.707980441401;4245.893852653647;4851.58761505276;4919.0899683558655;4946.1344264753525;9103.954480227556;9194;9199.4940278284;9201.263898888074;9217.078292170729;5262.473752624707;5274.182501712164;5274.472552744772;5278.392984806719;5283.498067683641;4114.136031433372;4412.24991751403;4881.755912204376;4906.779194936236;4936.531029552259;3277;3744.8876533704033;3757.3800322946954;3855.575886652466;3858.5369755628944;1160.7736491384098;1172.7888979983513;1175.9941200293945;1186.9406529673481;1189.5776999165346;830.1656834880906;876.0932434929817;898.2589363774781;904.7964208053136;910.5993362920294;5276.815311464157;5828.795992140254;5905.409459054146;5928.170056192136;6124.081387791867;409.4268024765293;418.738288569644;439.71418577924607;441.2917267785223;444.34237328753625;3850.172212974168;3972.3445631471063;4059.1475790083823;4112.157007813391;4132.834686612502;10439.269251152498;10705.83941240882;10839.83740243897;10855.782884342427;10910.563577456971;10775.137988960865;10779.191560632984;10790.190735694854;10798.784024319626;10807.513661885234;8197.385196110195;8293.917060829315;8300.916990830136;8308.12764659714;8341.290990265774;5701.344345400332;5809.767609295581;5965.940340596539;6013.759449621966;6037.947097986706;9826.75433114178;10132.240081993732;10165.796684066425;10181.796364072528;10201.948990255298;9309.255259579353;9678.225741940487;10087.495625218562;10268.897311027093;10277;5764.711764411846;6949.791506254821;7030.753923612649;7056.294370562902;7077.929220707933;8180.5909704515725;8300.501969881827;8396.832063358821;8402.369822263478;8420.95789521073;7432.405407567489;7485.70057197706;7490.250974902466;7597.544147351176;7677.8464430709955;7279.417646588155;7292.270772922665;7342.853142937218;7389.778306650809;7493.175750667313;5963.493103086286;6671.833204169935;6917.446604271546;6992.825179370557;7047.682854271673;4994.925076123934;4995.125852975642;5051.4443411224;5068.82259120929;5132.358455193101;5009.724465154306;5054.140796064605;5100;5112.590992720499;5160.251763494197 04:13:48 INFO - PROCESS | 1662 | |4;prototype.html;291.83657151994885;295.8846050040484;305.07866243943283;308.3740007784199;308.50792985188633;3868.4777555030064;4609.9308510372375;5355.410904800468;5367;5487.707375574653;4534.5465453454635;4770.209427484917;5065.822696205633;5178.249153872686;5315.73421328933;3346.8995930122137;3984.9402258965993;3995.5005624296964;4054.4121102440126;4098.520395160929;4059.2084543514025;4085.2442298174783;4095.4061661059127;4101.384792281152;4104.686500319899;3833.7891415972113;3988.581198974095;4028.6978476614227;4031;4050.023232923799;3293.950590741141;3295.505674148873;3427.3830710472107;3649.981750091246;4015.4980627421573;2844.815087019337;3294.8352582371026;3419.751790596439;3451.5685539307588;3476.1483436558206;4453.799579018952;5197.662151960149;5222.268882356473;5226.588821018342;5252.002119597264;4100.671946244293;4222.239996800575;4284.957150428487;4566.150708962876;4612.123696497655;4652.371929789469;4872.3909511311085;5230.92153617696;5279.340082489689;5619.072852979253;3490.4240800267926;3793.2223894101917;3795.2409518096215;3811.2568049230413;3820.1977584706965;4632.652551058684;5547.39125653564;5701.828945131652;5838.445347692005;5853.209816674737;3278.016595021484;3821.063839359375;3838.2535946144435;3843.1737176506626;3857.556381016163;3136.8588413521447;3684.5762737285017;3698.9458004469134;3899.7855117968784;3950.822213000422;3435.7766745161484;3777.244551089793;4254.766117825858;4377.233984052778;4522.638188944877;5211.973940130275;5671.773129074873;5691.516221121168;5701.429857014348;5776.826695199151;1076.6769969009265;1176.1355403778216;1271.5867343113525;1353.3842101843834;1361.6723694397883;664.5680307799872;838.0598913515192;866.8786369908217;868.1795703060674;871.899731530882;5016.172331565251;5232.110541208004;5258.474152584711;5293.391260005072;5369.301990741257;5495.53287970519;5665.008623490955;5736.6844823534275;5743.712814359265;5774.364819869811;4934.210526315772;4956.04236326649;5201.739913004335;5260.9736951315;5332.573394128461;2524.457241693008;2851.928701782472;2911.937142942853;2961.400843544462;3030.4393687167944;5576.841421684925;5696.971515142481;5767.452092051249;5894.557908156906;5942.64344139353;5458.925608268833;5797.565182611321;5857.70711464425;5942.43546863056;6011.849703757353;5863.824085277449;5961.970190149108;5977.731002104829;5995.640261584319;6005.369436209223;4367.606915377504;4715.599174070242;5325.760340784752;5693.772249110155;5704.714764261687;4982.327385803015;5375.1243084949365;5608.158776183605;5625.071863142535;5634.549236061023;4561.885952851205;5308.522232999048;5751.670573417772;5940.495057920125;5960.374160713236;3630.2739452110213;3747.887563373103;3763.7929913855;3766.7551609145316;3775.0747118968975;4770.666053376231;5380.650257733235;5677.801276955287;5696.316442026818;5702.0306547887785;4695.906081878412;4769.451513076041;4810.615150787999;5293.5765138789575;5491.478309560585;4963.975180124076;5093.668911520739;5100.413452452867;5535.833924982257;5624.465675760796;4259.38238955356;5201.297824793604;5450.482204190595;5504.724763761876;5530.806421775219;5032.446430892525;5461.836144915659;5519.866524132595;5596.3284405871555;5604.551635869202;5030.622703297194;5080.831408775928;5485.424030476902;5500.012490944034;5531.806386776443 04:13:48 INFO - PROCESS | 1662 | |5;yui.html;723.1069629008174;723.4501778648228;736.4255880413276;795.2961628958371;800.987985180222;14490;15251.389944402239;15490.457833975814;16195.595110122253;16216.918915405422;14037.29813509323;14052;14076.436942522312;14077.648058798535;14419.351129199185;665.4676258992811;684.2712511175607;686.4028295382999;687.7455341523644;837.6733074101105;675.5608854244731;822.4037572759731;858.1118542308711;863.0980625246601;866.2853146154416;886.9911300886973;891.2513488669516;896.3859756067114;901.202435844276;901.7475106970026;891.3849443883764;892.7411050795262;894.2309613732185;894.4454438248328;895.4716717136888;511.83877078720457;645.1161908185769;656.4846595422571;658.3844105761128;660.4947215380238;14285;14286.428542858273;14324.498642547565;14358.641033974234;14414.351354188982;12851.871481285161;13125.86874131256;13146.35237953159;13308.800367994489;13508.662283442894;12152.878471215352;12388;12415.379231038502;12434.440450179764;12791.680207994781;4455.888602784924;4469.687121901468;4507.1211113832815;4625.606823420013;4690.530946905282;117454;118266;118903;119017.80982190154;119117;9006.459612423276;10004.599816007423;10678.145748340114;10690.412027338416;10782.892171078345;20169.899150504447;20210.79789202089;20407.183712651626;20471;20471.897640511703;7715.961420192975;7723.691052357955;7776.222377762178;7873.370130389555;7917.366610671133;4321.805518751664;4376.540463251313;4428.867133985986;4514.164879497303;4548.317752337176;3399.3201359728155;3465.878694245689;3472.8610855565494;3475.14858859581;3526.030341656065;648.0570769530322;653.1019847709399;654.1528720307214;657.4477438951304;662.5693299355457;546.8058839112058;589.584343038157;599;625.2902955145942;638.1034646321925;7939.761807145795;8090.919090809017;8770.473771573726;8785.34109941757;9104.726858194264;543.5977376741183;585.5624441994893;601.2034054877305;611.9194737891116;615.9414855588711;3709.5062915844824;4063.4514340563646;4114.382842573638;4328.870133895988;4411.889702757457;94872;96378.55432168522;141545.58454415324;154539;156325;98658.01341986489;109474.45262773635;124640;138079;138314;115731.42134289443;119150.40424797821;120586.39706801585;121276.39361803135;122043;8207.466514676527;8422.410431269753;8478.703245386381;8561.48631082137;8746.562671866508;61116.47208819815;66535.00197496945;66554.33445665675;66909;68715.65642171758;89371.21257574942;95539;99744.86250372109;101737;103953;10612.522436490532;11036.227464077758;11073.335599864035;11132.666020019413;11187.440627968406;11456.770864582495;11714.765704685688;11754.353510557;11786.705332366759;12386.62840114798;11497.367644779617;11526.94236528812;12659;13407.28117120614;13710.931445342709;11357.432128393382;13380.531681391105;13412.865871341162;13654.249016304198;13761.243131627856;13066;13189.538366157522;13480.662983425493;13544.59366219015;13894.791578126134;11067.83398249043;11375.488103035217;11471.254368466023;11534.348905102215;11845.696973333099;7654.961725191339;7993.080795708568;9281.46990614018;9531.270141898112;10017.799644006933 04:13:48 INFO - PROCESS | 1662 | __end_tp_report 04:13:48 INFO - PROCESS | 1662 | __start_cc_report 04:13:48 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,1520 04:13:48 INFO - PROCESS | 1662 | __end_cc_report 04:13:48 INFO - PROCESS | 1662 | __startTimestamp1472728428571__endTimestamp 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | ------- Summary: start ------- 04:13:48 INFO - PROCESS | 1662 | Number of tests: 6 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#0] dojo.html Cycles:165 Average:5758.78 Median:5924.32 stddev:634.51 (10.7%) stddev-sans-first:607.55 04:13:48 INFO - PROCESS | 1662 | Values: 3345.2 3348.6 3382.0 3419.2 3423.2 5607.8 5687.2 5703.2 5775.7 5788.4 5644.2 5690.5 5745.2 5781.1 5812.1 5125.5 5179.2 5197.9 5205.3 5310.7 5289.8 5299.7 5302.1 5310.0 5347.4 5111.3 5168.4 5175.1 5198.9 5231.4 5221.5 5233.4 5233.9 5239.8 5241.5 5111.1 5196.9 5203.7 5213.4 5226.3 5665.4 5684.6 5697.0 5697.5 5775.7 6236.0 6307.2 6308.4 6317.9 6326.4 6122.6 6220.2 6264.8 6270.3 6288.2 5796.4 5818.6 5831.7 5842.3 5865.4 6241.9 6243.4 6250.3 6263.2 6279.0 6032.8 6117.8 6147.4 6156.4 6206.4 6300.7 6307.8 6309.3 6314.3 6329.7 6262.2 6265.9 6270.3 6281.7 6301.6 6074.4 6183.4 6189.6 6232.6 6258.5 4916.9 4947.5 4952.0 4964.6 4984.1 4409.2 4410.7 4420.8 4452.6 4467.9 5671.1 5732.9 5749.1 5807.2 5820.7 6291.6 6292.0 6309.4 6332.5 6335.4 5708.3 5796.0 5804.6 5818.4 5868.2 5342.5 5403.8 5425.7 5465.8 5478.2 6250.7 6294.4 6322.4 6326.5 6342.9 6278.9 6281.8 6282.4 6290.3 6305.0 6183.3 6210.5 6300.8 6313.1 6323.1 6125.0 6130.7 6162.6 6172.6 6189.1 6315.9 6316.1 6322.2 6324.3 6325.9 5812.1 5943.3 6088.3 6090.4 6096.0 5777.9 5978.3 5993.1 5997.2 5997.8 5901.9 5903.6 5957.4 5966.5 5969.4 6008.4 6046.9 6063.9 6064.8 6065.2 5847.9 5904.6 5919.5 5924.3 5924.5 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#1] ext.html Cycles:180 Average:74543.64 Median:38316.63 stddev:179155.62 (467.6%) stddev-sans-first:179571.95 04:13:48 INFO - PROCESS | 1662 | Values: 502.4 506.8 506.9 530.5 551.6 30172.4 30734.8 31355.7 40079.2 40533.4 37432.6 38642.3 41253.0 41620.0 41660.4 9576.9 9740.9 9751.9 9754.6 9772.9 9647.7 9748.6 9758.8 9759.8 9762.1 9721.1 9778.6 9779.4 9780.2 9799.6 9687.1 9742.8 9749.7 9767.4 9782.6 8687.9 9390.7 9683.1 9720.8 9731.3 38906.6 39231.0 39280.0 39337.8 39385.0 3286.0 3309.6 3312.4 3370.6 3427.1 29699.9 30088.4 30628.4 31222.0 31390.4 2601.3 3093.3 3101.0 3166.3 3202.3 442697.0 447849.0 448690.0 449160.0 454952.0 55913.9 56060.7 56096.0 56134.0 56283.6 33682.0 53664.7 53725.2 53934.0 54074.5 15487.3 15996.9 16006.4 16035.1 16108.1 12324.9 12407.6 12421.8 12426.6 12437.6 18270.3 18298.7 18299.8 18329.5 18347.4 7535.6 7597.9 7622.9 7681.8 7690.3 850.3 902.7 926.3 967.9 989.3 28477.0 28569.1 29675.7 29778.7 29831.9 1038.5 1054.3 1056.0 1076.3 1100.3 15103.7 15149.4 15233.6 15547.8 15557.5 623809.0 723575.0 986785.0 987495.0 990018.0 55885.0 56598.7 56652.0 56676.4 56886.7 1186.8 1248.2 1310.4 1326.9 1352.4 28661.9 29049.6 29722.7 30525.0 31239.0 51697.0 52467.7 52479.0 52505.7 52588.7 588826.1 589141.0 593228.0 594347.0 597253.0 21477.0 23093.4 26020.6 26032.6 26361.7 37307.1 40796.8 44138.0 44726.8 44852.8 35831.1 35967.9 36377.0 36586.8 37991.0 35935.3 36258.0 36306.6 37486.6 37517.4 35535.1 36109.6 36328.0 37201.4 37566.0 32007.7 33139.2 33763.7 33814.3 34010.0 19973.0 22784.9 24011.4 24800.9 24882.0 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#2] jquery.html Cycles:180 Average:36209.32 Median:35470.79 stddev:96832.58 (273.0%) stddev-sans-first:97066.75 04:13:48 INFO - PROCESS | 1662 | Values: 328.7 345.5 369.5 375.5 400.4 15446.7 17172.7 20570.6 20591.0 20754.9 20240.8 20553.3 20860.8 20957.5 21441.5 5206.7 5692.4 6013.2 6048.4 6155.5 6006.1 6087.2 6099.3 6102.3 6104.3 5832.9 6055.8 6057.3 6118.2 6176.4 5935.8 6134.5 6148.2 6156.5 6164.3 5829.2 5940.9 5946.5 5947.3 5948.0 13277.1 13279.0 13350.9 13506.3 13582.3 2298.8 2329.8 2367.7 2466.3 2472.6 8633.5 9620.4 10510.4 12802.4 15116.7 1820.7 1891.6 2081.7 2179.2 2217.7 130997.0 145779.3 146066.2 146478.0 155850.7 21023.0 21055.6 21422.8 21424.7 21663.6 35298.0 35315.8 35406.8 35468.8 35472.8 10587.3 10883.3 11014.2 11020.2 11273.6 8023.5 8674.2 9032.7 9062.7 9288.2 7947.6 8438.0 9563.9 9600.9 9745.8 3089.0 3358.1 3632.6 3730.7 3732.3 761.3 810.7 877.7 883.8 960.6 17895.6 21020.6 21674.5 21679.6 21701.3 883.5 895.9 902.1 905.3 937.2 9054.2 9803.6 11324.7 11352.0 11359.5 564551.0 571796.0 576641.0 577819.0 580058.0 127788.0 130372.0 131697.3 131756.0 135151.0 950.7 957.8 981.3 981.5 982.9 18976.0 19209.9 19218.5 19268.9 19278.0 55812.7 57081.7 57174.1 57309.7 57959.4 80100.0 80471.0 81009.6 81262.6 81759.0 8077.4 8140.9 8229.3 8264.0 8340.2 11463.8 12966.4 13012.3 13173.4 13249.5 11206.4 11261.0 11611.2 11625.5 11721.5 8973.5 11154.8 11260.7 11455.8 11474.5 9732.0 11519.4 11612.0 11623.7 11784.2 7116.1 7166.9 7402.2 7416.3 8477.8 9435.3 11595.7 11789.6 11853.5 11888.1 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#3] mootools.html Cycles:180 Average:5653.18 Median:4941.33 stddev:3201.14 (64.8%) stddev-sans-first:3193.54 04:13:48 INFO - PROCESS | 1662 | Values: 1318.4 1349.2 1532.7 1713.0 1937.3 7742.5 8567.5 8799.0 8805.6 8877.6 8640.7 8724.1 8726.3 8734.0 8736.6 2355.4 2383.4 2384.1 2388.7 2389.7 1989.5 2036.6 2369.6 2374.3 2375.6 1970.8 2340.9 2353.9 2365.9 2385.7 2299.5 2324.5 2326.9 2327.9 2329.6 1944.5 2314.6 2321.5 2321.7 2332.0 8716.3 8724.3 8727.5 8822.8 8824.9 5200.3 5381.2 5386.8 5402.9 5414.6 3398.2 3408.0 3444.6 3482.0 3488.3 1062.8 1091.1 1726.7 1804.9 1839.4 11693.7 11754.5 11755.9 11756.8 11798.6 4171.7 4245.9 4851.6 4919.1 4946.1 9104.0 9194.0 9199.5 9201.3 9217.1 5262.5 5274.2 5274.5 5278.4 5283.5 4114.1 4412.2 4881.8 4906.8 4936.5 3277.0 3744.9 3757.4 3855.6 3858.5 1160.8 1172.8 1176.0 1186.9 1189.6 830.2 876.1 898.3 904.8 910.6 5276.8 5828.8 5905.4 5928.2 6124.1 409.4 418.7 439.7 441.3 444.3 3850.2 3972.3 4059.1 4112.2 4132.8 10439.3 10705.8 10839.8 10855.8 10910.6 10775.1 10779.2 10790.2 10798.8 10807.5 8197.4 8293.9 8300.9 8308.1 8341.3 5701.3 5809.8 5965.9 6013.8 6037.9 9826.8 10132.2 10165.8 10181.8 10201.9 9309.3 9678.2 10087.5 10268.9 10277.0 5764.7 6949.8 7030.8 7056.3 7077.9 8180.6 8300.5 8396.8 8402.4 8421.0 7432.4 7485.7 7490.3 7597.5 7677.8 7279.4 7292.3 7342.9 7389.8 7493.2 5963.5 6671.8 6917.4 6992.8 7047.7 4994.9 4995.1 5051.4 5068.8 5132.4 5009.7 5054.1 5100.0 5112.6 5160.3 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#4] prototype.html Cycles:180 Average:4443.80 Median:5023.40 stddev:1402.13 (27.9%) stddev-sans-first:1371.00 04:13:48 INFO - PROCESS | 1662 | Values: 291.8 295.9 305.1 308.4 308.5 3868.5 4609.9 5355.4 5367.0 5487.7 4534.5 4770.2 5065.8 5178.2 5315.7 3346.9 3984.9 3995.5 4054.4 4098.5 4059.2 4085.2 4095.4 4101.4 4104.7 3833.8 3988.6 4028.7 4031.0 4050.0 3294.0 3295.5 3427.4 3650.0 4015.5 2844.8 3294.8 3419.8 3451.6 3476.1 4453.8 5197.7 5222.3 5226.6 5252.0 4100.7 4222.2 4285.0 4566.2 4612.1 4652.4 4872.4 5230.9 5279.3 5619.1 3490.4 3793.2 3795.2 3811.3 3820.2 4632.7 5547.4 5701.8 5838.4 5853.2 3278.0 3821.1 3838.3 3843.2 3857.6 3136.9 3684.6 3698.9 3899.8 3950.8 3435.8 3777.2 4254.8 4377.2 4522.6 5212.0 5671.8 5691.5 5701.4 5776.8 1076.7 1176.1 1271.6 1353.4 1361.7 664.6 838.1 866.9 868.2 871.9 5016.2 5232.1 5258.5 5293.4 5369.3 5495.5 5665.0 5736.7 5743.7 5774.4 4934.2 4956.0 5201.7 5261.0 5332.6 2524.5 2851.9 2911.9 2961.4 3030.4 5576.8 5697.0 5767.5 5894.6 5942.6 5458.9 5797.6 5857.7 5942.4 6011.8 5863.8 5962.0 5977.7 5995.6 6005.4 4367.6 4715.6 5325.8 5693.8 5704.7 4982.3 5375.1 5608.2 5625.1 5634.5 4561.9 5308.5 5751.7 5940.5 5960.4 3630.3 3747.9 3763.8 3766.8 3775.1 4770.7 5380.7 5677.8 5696.3 5702.0 4695.9 4769.5 4810.6 5293.6 5491.5 4964.0 5093.7 5100.4 5535.8 5624.5 4259.4 5201.3 5450.5 5504.7 5530.8 5032.4 5461.8 5519.9 5596.3 5604.6 5030.6 5080.8 5485.4 5500.0 5531.8 04:13:48 INFO - PROCESS | 1662 | 04:13:48 INFO - PROCESS | 1662 | [#5] yui.html Cycles:180 Average:24537.53 Median:4088.92 stddev:39517.60 (966.5%) stddev-sans-first:39588.01 04:13:48 INFO - PROCESS | 1662 | Values: 723.1 723.5 736.4 795.3 801.0 14490.0 15251.4 15490.5 16195.6 16216.9 14037.3 14052.0 14076.4 14077.6 14419.4 665.5 684.3 686.4 687.7 837.7 675.6 822.4 858.1 863.1 866.3 887.0 891.3 896.4 901.2 901.7 891.4 892.7 894.2 894.4 895.5 511.8 645.1 656.5 658.4 660.5 14285.0 14286.4 14324.5 14358.6 14414.4 12851.9 13125.9 13146.4 13308.8 13508.7 12152.9 12388.0 12415.4 12434.4 12791.7 4455.9 4469.7 4507.1 4625.6 4690.5 117454.0 118266.0 118903.0 119017.8 119117.0 9006.5 10004.6 10678.1 10690.4 10782.9 20169.9 20210.8 20407.2 20471.0 20471.9 7716.0 7723.7 7776.2 7873.4 7917.4 4321.8 4376.5 4428.9 4514.2 4548.3 3399.3 3465.9 3472.9 3475.1 3526.0 648.1 653.1 654.2 657.4 662.6 546.8 589.6 599.0 625.3 638.1 7939.8 8090.9 8770.5 8785.3 9104.7 543.6 585.6 601.2 611.9 615.9 3709.5 4063.5 4114.4 4328.9 4411.9 94872.0 96378.6 141545.6 154539.0 156325.0 98658.0 109474.5 124640.0 138079.0 138314.0 115731.4 119150.4 120586.4 121276.4 122043.0 8207.5 8422.4 8478.7 8561.5 8746.6 61116.5 66535.0 66554.3 66909.0 68715.7 89371.2 95539.0 99744.9 101737.0 103953.0 10612.5 11036.2 11073.3 11132.7 11187.4 11456.8 11714.8 11754.4 11786.7 12386.6 11497.4 11526.9 12659.0 13407.3 13710.9 11357.4 13380.5 13412.9 13654.2 13761.2 13066.0 13189.5 13480.7 13544.6 13894.8 11067.8 11375.5 11471.3 11534.3 11845.7 7655.0 7993.1 9281.5 9531.3 10017.8 04:13:48 INFO - PROCESS | 1662 | -------- Summary: end -------- 04:13:48 INFO - PROCESS | 1662 | 04:13:49 INFO - TEST-INFO | 1662: exit 0 04:13:49 INFO - TEST-OK | dromaeo_css | took 1118363ms 04:13:49 INFO - TEST-START | kraken 04:13:49 INFO - operating with platform_type : mac_ 04:13:49 INFO - Initialising browser for kraken test... 04:13:49 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpwtQacy/profile http://localhost:49265/getInfo.html) 04:13:51 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 04:13:51 INFO - PROCESS | 1667 | colorDepth:24 04:13:51 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 04:13:51 INFO - PROCESS | 1667 | __metrics 04:13:51 INFO - PROCESS | 1667 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 04:13:51 INFO - PROCESS | 1667 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 04:13:52 INFO - TEST-INFO | 1667: exit 0 04:13:52 INFO - Browser initialized. 04:13:52 INFO - Running cycle 1/1 for kraken test... 04:13:52 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/T/tmpwtQacy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:14:04 INFO - PROCESS | 1668 | RSS: Main: 233799680 04:14:04 INFO - PROCESS | 1668 | 04:14:07 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection) 04:14:07 INFO - PROCESS | 1668 | RSS: Main: 293462016 04:14:07 INFO - PROCESS | 1668 | 04:14:10 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-dft) 04:14:11 INFO - PROCESS | 1668 | RSS: Main: 289075200 04:14:11 INFO - PROCESS | 1668 | 04:14:14 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-fft) 04:14:15 INFO - PROCESS | 1668 | RSS: Main: 378556416 04:14:15 INFO - PROCESS | 1668 | 04:14:17 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator) 04:14:18 INFO - PROCESS | 1668 | RSS: Main: 281874432 04:14:18 INFO - PROCESS | 1668 | 04:14:20 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:14:20 INFO - PROCESS | 1668 | RSS: Main: 284962816 04:14:20 INFO - PROCESS | 1668 | 04:14:23 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom) 04:14:24 INFO - PROCESS | 1668 | RSS: Main: 325046272 04:14:24 INFO - PROCESS | 1668 | 04:14:26 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate) 04:14:27 INFO - PROCESS | 1668 | RSS: Main: 327118848 04:14:27 INFO - PROCESS | 1668 | 04:14:30 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial) 04:14:31 INFO - PROCESS | 1668 | RSS: Main: 325451776 04:14:31 INFO - PROCESS | 1668 | 04:14:32 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:14:33 INFO - PROCESS | 1668 | RSS: Main: 298586112 04:14:33 INFO - PROCESS | 1668 | 04:14:35 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:14:35 INFO - PROCESS | 1668 | RSS: Main: 306028544 04:14:35 INFO - PROCESS | 1668 | 04:14:37 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:14:38 INFO - PROCESS | 1668 | RSS: Main: 307023872 04:14:38 INFO - PROCESS | 1668 | 04:14:41 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:14:42 INFO - PROCESS | 1668 | RSS: Main: 324050944 04:14:42 INFO - PROCESS | 1668 | 04:14:44 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:14:45 INFO - PROCESS | 1668 | RSS: Main: 320020480 04:14:45 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | Cycle 1(1): loaded http://localhost:49265/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49265/tests/kraken/driver.html?testName=ai-astar) 04:14:47 INFO - PROCESS | 1668 | RSS: Main: 363294720 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | __start_tp_report 04:14:47 INFO - PROCESS | 1668 | _x_x_mozilla_page_load 04:14:47 INFO - PROCESS | 1668 | _x_x_mozilla_page_load_details 04:14:47 INFO - PROCESS | 1668 | |i|pagename|runs| 04:14:47 INFO - PROCESS | 1668 | |0;ai-astar;112;114;115;113;112;113;111;113;113;122 04:14:47 INFO - PROCESS | 1668 | |1;audio-beat-detection;151;120;162;204;111;131;129;109;160;110 04:14:47 INFO - PROCESS | 1668 | |2;audio-dft;186;187;182;184;182;182;222;180;181;183 04:14:47 INFO - PROCESS | 1668 | |3;audio-fft;75;77;77;158;58;58;58;59;145;60 04:14:47 INFO - PROCESS | 1668 | |4;audio-oscillator;83;82;130;69;71;76;121;68;70;115 04:14:47 INFO - PROCESS | 1668 | |5;imaging-gaussian-blur;89;85;90;87;89;86;91;86;88;85 04:14:47 INFO - PROCESS | 1668 | |6;imaging-darkroom;95;97;96;97;97;97;97;107;97;97 04:14:47 INFO - PROCESS | 1668 | |7;imaging-desaturate;84;84;83;84;85;83;84;83;84;85 04:14:47 INFO - PROCESS | 1668 | |8;json-parse-financial;58;53;55;52;53;53;54;53;53;53 04:14:47 INFO - PROCESS | 1668 | |9;json-stringify-tinderbox;67;66;69;74;74;80;73;69;70;73 04:14:47 INFO - PROCESS | 1668 | |10;stanford-crypto-aes;83;84;87;84;88;87;83;89;86;86 04:14:47 INFO - PROCESS | 1668 | |11;stanford-crypto-ccm;142;153;136;154;139;138;135;156;187;139 04:14:47 INFO - PROCESS | 1668 | |12;stanford-crypto-pbkdf2;167;167;169;168;169;172;168;169;168;169 04:14:47 INFO - PROCESS | 1668 | |13;stanford-crypto-sha256-iterative;62;82;84;67;68;67;67;64;66;67 04:14:47 INFO - PROCESS | 1668 | __end_tp_report 04:14:47 INFO - PROCESS | 1668 | __start_cc_report 04:14:47 INFO - PROCESS | 1668 | _x_x_mozilla_cycle_collect,2006 04:14:47 INFO - PROCESS | 1668 | __end_cc_report 04:14:47 INFO - PROCESS | 1668 | __startTimestamp1472728487625__endTimestamp 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | ------- Summary: start ------- 04:14:47 INFO - PROCESS | 1668 | Number of tests: 14 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#0] ai-astar Cycles:10 Average:113.80 Median:113.00 stddev:3.08 (2.7%) stddev-sans-first:3.20 04:14:47 INFO - PROCESS | 1668 | Values: 112.0 114.0 115.0 113.0 112.0 113.0 111.0 113.0 113.0 122.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#1] audio-beat-detection Cycles:10 Average:138.70 Median:141.00 stddev:30.53 (21.7%) stddev-sans-first:32.05 04:14:47 INFO - PROCESS | 1668 | Values: 151.0 120.0 162.0 204.0 111.0 131.0 129.0 109.0 160.0 110.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#2] audio-dft Cycles:10 Average:186.90 Median:183.50 stddev:12.52 (6.8%) stddev-sans-first:13.28 04:14:47 INFO - PROCESS | 1668 | Values: 186.0 187.0 182.0 184.0 182.0 182.0 222.0 180.0 181.0 183.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#3] audio-fft Cycles:10 Average:82.50 Median:59.50 stddev:37.39 (62.8%) stddev-sans-first:39.56 04:14:47 INFO - PROCESS | 1668 | Values: 75.0 77.0 77.0 158.0 58.0 58.0 58.0 59.0 145.0 60.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#4] audio-oscillator Cycles:10 Average:88.50 Median:70.50 stddev:23.94 (34.0%) stddev-sans-first:25.31 04:14:47 INFO - PROCESS | 1668 | Values: 83.0 82.0 130.0 69.0 71.0 76.0 121.0 68.0 70.0 115.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.50 stddev:2.12 (2.4%) stddev-sans-first:2.19 04:14:47 INFO - PROCESS | 1668 | Values: 89.0 85.0 90.0 87.0 89.0 86.0 91.0 86.0 88.0 85.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#6] imaging-darkroom Cycles:10 Average:97.70 Median:97.00 stddev:3.33 (3.4%) stddev-sans-first:3.39 04:14:47 INFO - PROCESS | 1668 | Values: 95.0 97.0 96.0 97.0 97.0 97.0 97.0 107.0 97.0 97.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#7] imaging-desaturate Cycles:10 Average:83.90 Median:84.00 stddev:0.74 (0.9%) stddev-sans-first:0.78 04:14:47 INFO - PROCESS | 1668 | Values: 84.0 84.0 83.0 84.0 85.0 83.0 84.0 83.0 84.0 85.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#8] json-parse-financial Cycles:10 Average:53.70 Median:53.00 stddev:1.70 (3.2%) stddev-sans-first:0.83 04:14:47 INFO - PROCESS | 1668 | Values: 58.0 53.0 55.0 52.0 53.0 53.0 54.0 53.0 53.0 53.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#9] json-stringify-tinderbox Cycles:10 Average:71.50 Median:73.00 stddev:4.14 (5.7%) stddev-sans-first:4.06 04:14:47 INFO - PROCESS | 1668 | Values: 67.0 66.0 69.0 74.0 74.0 80.0 73.0 69.0 70.0 73.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:86.50 stddev:2.11 (2.4%) stddev-sans-first:2.00 04:14:47 INFO - PROCESS | 1668 | Values: 83.0 84.0 87.0 84.0 88.0 87.0 83.0 89.0 86.0 86.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#11] stanford-crypto-ccm Cycles:10 Average:147.90 Median:147.50 stddev:15.84 (10.7%) stddev-sans-first:16.65 04:14:47 INFO - PROCESS | 1668 | Values: 142.0 153.0 136.0 154.0 139.0 138.0 135.0 156.0 187.0 139.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.60 Median:169.00 stddev:1.43 (0.8%) stddev-sans-first:1.39 04:14:47 INFO - PROCESS | 1668 | Values: 167.0 167.0 169.0 168.0 169.0 172.0 168.0 169.0 168.0 169.0 04:14:47 INFO - PROCESS | 1668 | 04:14:47 INFO - PROCESS | 1668 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:69.40 Median:67.00 stddev:7.40 (11.0%) stddev-sans-first:7.34 04:14:47 INFO - PROCESS | 1668 | Values: 62.0 82.0 84.0 67.0 68.0 67.0 67.0 64.0 66.0 67.0 04:14:47 INFO - PROCESS | 1668 | -------- Summary: end -------- 04:14:47 INFO - PROCESS | 1668 | 04:14:48 INFO - TEST-INFO | 1668: exit 0 04:14:48 INFO - TEST-OK | kraken | took 59374ms 04:14:48 INFO - SUITE-END | took 1177s 04:14:48 INFO - Completed test suite (00:19:37) 04:14:48 INFO - javascript benchmark 04:14:48 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3345.197152683356, 3348.6316505184427, 3381.9661803381973, 3419.179396944734, 3423.1613254752588, 5607.803726869561, 5687.232223649806, 5703.230063941367, 5775.711214439282, 5788.392218817026, 5644.181593668915, 5690.516306113975, 5745.195672605828, 5781.13283007549, 5812.099124635678, 5125.538701516863, 5179.171332586787, 5197.948020519803, 5205.349331333583, 5310.73446327684, 5289.761960711778, 5299.6820190788485, 5302.125149350352, 5309.991101690685, 5347.385050719178, 5111.258867464197, 5168.431472538018, 5175.146100893348, 5198.948010519903, 5231.398389185254, 5221.503957124067, 5233.371995360543, 5233.921491177597, 5239.764210610493, 5241.528262456359, 5111.08000559899, 5196.92204616931, 5203.6877787332505, 5213.374395072616, 5226.346706661668, 5665.433456654343, 5684.63049901755, 5696.9745445819735, 5697.515711164556, 5775.682337471438, 6236.0, 6307.243130824285, 6308.369163083655, 6317.93682063178, 6326.43062124411, 6122.611623597858, 6220.222472190976, 6264.843378915518, 6270.278917924452, 6288.245410550718, 5796.362400135981, 5818.5636077294375, 5831.650100993869, 5842.269716285465, 5865.35481097079, 6241.937580624136, 6243.375662433811, 6250.343713910065, 6263.185785847902, 6278.995360742259, 6032.788852390233, 6117.816465506042, 6147.354527774609, 6156.445919867234, 6206.379362063848, 6300.747970081237, 6307.810765677037, 6309.337519560472, 6314.336994615591, 6329.715162817684, 6262.248530176408, 6265.874682506415, 6270.31026587075, 6281.685915704288, 6301.558890877594, 6074.362191969868, 6183.412575805291, 6189.566730328924, 6232.594881332699, 6258.468030217483, 4916.8770780730765, 4947.529984651453, 4951.9752401237765, 4964.553190212898, 4984.077945580079, 4409.228385032607, 4410.69125161242, 4420.7789610519985, 4452.643788496913, 4467.932981005288, 5671.149327600893, 5732.856678583069, 5749.13762935563, 5807.216025836543, 5820.708964551755, 6291.559590828598, 6292.0, 6309.369063093745, 6332.52506062038, 6335.3981371769605, 5708.343540492897, 5796.014677504834, 5804.633312678637, 5818.418158184148, 5868.23712917318, 5342.465753424704, 5403.756830942617, 5425.701586412785, 5465.781368745285, 5478.233047373371, 6250.656213908279, 6294.402031806971, 6322.399372059647, 6326.4622507087415, 6342.90485642725, 6278.937210627835, 6281.811545653638, 6282.434580887742, 6290.276618188784, 6304.968475157595, 6183.258009038944, 6210.472109870711, 6300.84247893788, 6313.1477250572425, 6323.08315294289, 6125.019996800491, 6130.6628135452975, 6162.630242185483, 6172.598781079216, 6189.1025801257665, 6315.873682526416, 6316.084167795736, 6322.178116844781, 6324.335944725737, 6325.8734825304155, 5812.070068788974, 5943.316518600416, 6088.330283668704, 6090.390960903874, 6096.02463605821, 5777.884442311046, 5978.342382338026, 5993.0710739835395, 5997.190379298913, 5997.82006539787, 5901.911471327848, 5903.616264942765, 5957.434043765836, 5966.49284810796, 5969.3732158122775, 6008.399160083956, 6046.879062418815, 6063.878722425438, 6064.818055458343, 6065.211522502046, 5847.8538036547725, 5904.6457212565665, 5919.4968427684125, 5924.31870334917, 5924.46679798803], "value": 5717.5743921360045, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [502.44981744989224, 506.825145324863, 506.8732816795801, 530.4748299183807, 551.6056019945743, 30172.396552068945, 30734.84632576837, 31355.68644313559, 40079.198416031664, 40533.39199912004, 37432.625673743256, 38642.26109825058, 41253.0, 41620.0, 41660.37509437361, 9576.856347154799, 9740.902590974107, 9751.853722194173, 9754.561044753003, 9772.853407198898, 9647.662331818388, 9748.561314740818, 9758.756031099243, 9759.75600609987, 9762.072603102693, 9721.07649773274, 9778.608855645765, 9779.41323520591, 9780.217582593375, 9799.559019844088, 9687.079727425884, 9742.756431089208, 9749.658761943369, 9767.36512126713, 9782.608695652165, 8687.913120868774, 9390.67132650354, 9683.128518433305, 9720.805583888283, 9731.318807683465, 38906.61093389058, 39231.01922451933, 39280.01799954996, 39337.80331098326, 39385.0, 3286.0, 3309.586301712286, 3312.370649576597, 3370.6460821613623, 3427.057559171223, 29699.85150074236, 30088.398232035237, 30628.38743225123, 31222.0, 31390.37219255625, 2601.336659151923, 3093.3349329893954, 3101.0, 3166.319241363095, 3202.2794871153806, 442697.0, 447849.0, 448690.0, 449160.0, 454952.0, 55913.881722365324, 56060.719696401255, 56096.0, 56133.999999999185, 56283.592910176754, 33681.98954031383, 53664.73167634137, 53725.164735174025, 53934.0, 54074.45925540773, 15487.303071361815, 15996.920015400081, 16006.439774608065, 16035.11806850611, 16108.114053727157, 12324.890759831658, 12407.627771166879, 12421.77033971174, 12426.62720118398, 12437.62687119388, 18270.26918923228, 18298.725519117223, 18299.810512316657, 18329.450116496526, 18347.35784247545, 7535.623218839036, 7597.886031709529, 7622.885656715153, 7681.846363072707, 7690.346320562815, 850.2815121222557, 902.7026218274507, 926.2913870888775, 967.901431874813, 989.302541708094, 28477.00330488423, 28569.14292571226, 29675.703242967294, 29778.702212978027, 29831.850840745657, 1038.5274700011478, 1054.2988912373187, 1055.9577616895392, 1076.316538997742, 1100.273819279272, 15103.697926041417, 15149.394024239127, 15233.6191595211, 15547.84452155464, 15557.533274001798, 623809.0, 723575.0, 986785.0, 987495.0, 990018.0, 55885.0, 56598.71700641471, 56652.0, 56676.43323566794, 56886.7155664219, 1186.7567148734488, 1248.17620370555, 1310.4299629661127, 1326.8673132686654, 1352.4252192818012, 28661.856690717246, 29049.564256536592, 29722.702772971992, 30525.0, 31239.0, 51697.0, 52467.73766131145, 52479.0, 52505.7374713124, 52588.73705631601, 588826.055869718, 589141.0, 593228.0, 594347.0, 597253.0, 21477.0, 23093.422664432852, 26020.609690854275, 26032.609510856975, 26361.73638263593, 37307.06732331713, 40796.79601602092, 44138.0, 44726.77636611796, 44852.77573612111, 35831.10422239465, 35967.907220875095, 36377.0, 36586.8170659145, 37991.0, 35935.281294373446, 36258.0, 36306.64320281583, 37486.6251337494, 37517.4372384409, 35535.11162220965, 36109.63890361063, 36328.0, 37201.4419783698, 37566.0, 32007.67992320047, 33139.171520712174, 33763.662363377036, 33814.3237135251, 34010.0, 19973.0, 22784.886075569517, 24011.399715007265, 24800.875995619906, 24882.0], "value": 19475.937750588844, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [328.6910304313945, 345.4766029465359, 369.4550537956516, 375.4743359296985, 400.3874072668816, 15446.69106617867, 17172.656546869057, 20570.588588228227, 20590.999999999964, 20754.89622551887, 20240.797592024075, 20553.280635177773, 20860.791392086074, 20957.47606309839, 21441.46396340092, 5206.739663016834, 5692.402297758737, 6013.1581578579035, 6048.364921683204, 6155.476784473302, 6006.129111278862, 6087.178230938811, 6099.3290738019, 6102.267727872661, 6104.2674879014585, 5832.854178645546, 6055.788047418341, 6057.273127224739, 6118.235220597426, 6176.382361763833, 5935.762569497215, 6134.478569321591, 6148.231471066117, 6156.538259630545, 6164.321924588292, 5829.242198514166, 5940.881182376372, 5946.464818166342, 5947.316058653267, 5948.048312270059, 13277.136986095873, 13279.0, 13350.86649133506, 13506.257155856423, 13582.25297608631, 2298.839081264312, 2329.8019668328043, 2367.6803631509697, 2466.3217615155795, 2472.61674440462, 8633.525156116411, 9620.37467564606, 10510.369377837354, 12802.423890924838, 15116.697666046728, 1820.7359932809668, 1891.6122194950003, 2081.7443582036035, 2179.193559580823, 2217.733871935378, 130997.0, 145779.27110364486, 146066.24793256252, 146478.0, 155850.66224006648, 21023.0, 21055.578888422453, 21422.78577214239, 21424.678629820566, 21663.566728665653, 35298.0, 35315.823420883244, 35406.82296588552, 35468.822655887074, 35472.75845345401, 10587.311824731369, 10883.292585981886, 11014.229003969805, 11020.173486988508, 11273.605423810128, 8023.518588884685, 8674.175953284428, 9032.729018129467, 9062.682806101886, 9288.210502107262, 7947.562884041433, 8437.987441507059, 9563.856542151872, 9600.903990960142, 9745.756356091155, 3088.98455507721, 3358.059743271888, 3632.56409230894, 3730.720195985312, 3732.2722069196248, 761.3186198352444, 810.7162493127477, 877.7015814622931, 883.7613844262141, 960.6397600899662, 17895.552611184823, 21020.57958840845, 21674.458138546663, 21679.566408672054, 21701.34895953124, 883.5008220355479, 895.91936725695, 902.1069141549949, 905.2893478619384, 937.1893312284825, 9054.185123338799, 9803.607855685692, 11324.660260192208, 11351.978321951065, 11359.545618175345, 564551.0, 571796.0, 576641.0, 577819.0, 580058.0, 127788.0, 130372.04441933379, 131697.34151329182, 131756.0, 135151.0, 950.729042222977, 957.8419560772395, 981.293468702533, 981.4651015196735, 982.9115379615869, 18975.9563224024, 19209.90395048072, 19218.51953701113, 19268.903655481634, 19278.0, 55812.72093639506, 57081.714591426775, 57174.142387865046, 57309.71345143248, 57959.420405797086, 80100.0, 80471.0, 81009.59495202487, 81262.59368703119, 81759.0, 8077.394195435483, 8140.918590814253, 8229.300509456763, 8264.0, 8340.207680270365, 11463.828042579202, 12966.35168241603, 13012.349382531025, 13173.40719667598, 13249.536266230636, 11206.43967801623, 11261.0, 11611.245269057485, 11625.47685354178, 11721.531138754695, 8973.532590910412, 11154.776904462027, 11260.662180134608, 11455.770884582094, 11474.541018359172, 9732.0, 11519.424028798694, 11612.012978896888, 11623.6512904613, 11784.175107742376, 7116.146062472537, 7166.928330716834, 7402.222766609429, 7416.332530072319, 8477.788055298666, 9435.339526233098, 11595.710107247387, 11789.587364442203, 11853.52585896589, 11888.108391870472], "value": 10381.399388522623, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1318.386950068218, 1349.1972276495483, 1532.6551525906668, 1712.982870171298, 1937.2541571494962, 7742.458027938046, 8567.528785916773, 8799.0, 8805.603747831356, 8877.60050797714, 8640.697575584856, 8724.127587241273, 8726.301895848334, 8734.03925568187, 8736.606852691628, 2355.4464700795343, 2383.4041489627593, 2384.0940442631777, 2388.725296590897, 2389.653500242464, 1989.4727897107277, 2036.643587372204, 2369.5782530481747, 2374.3351861478727, 2375.596148654733, 1970.8324792392664, 2340.871252081135, 2353.9293821185393, 2365.9408514787096, 2385.666006759055, 2299.528596637702, 2324.465372964219, 2326.918557850484, 2327.8836058197107, 2329.6389059695775, 1944.5041514413733, 2314.618088015475, 2321.4892723600783, 2321.6981792367055, 2332.0322066342446, 8716.346274029473, 8724.258438032775, 8727.519986400745, 8822.779430514225, 8824.941007079127, 5200.349956255468, 5381.165919282517, 5386.757595908193, 5402.864928376783, 5414.620976531644, 3398.218409765743, 3407.9829600851835, 3444.5522082129414, 3482.0424383294544, 3488.2674638325984, 1062.8458873463383, 1091.0671375973507, 1726.6978278801284, 1804.9097545122822, 1839.4481655503296, 11693.70765730855, 11754.471048802825, 11755.941220293844, 11756.764864702658, 11798.587049453314, 4171.707980441401, 4245.893852653647, 4851.58761505276, 4919.0899683558655, 4946.1344264753525, 9103.954480227556, 9194.0, 9199.4940278284, 9201.263898888074, 9217.078292170729, 5262.473752624707, 5274.182501712164, 5274.472552744772, 5278.392984806719, 5283.498067683641, 4114.136031433372, 4412.24991751403, 4881.755912204376, 4906.779194936236, 4936.531029552259, 3277.0, 3744.8876533704033, 3757.3800322946954, 3855.575886652466, 3858.5369755628944, 1160.7736491384098, 1172.7888979983513, 1175.9941200293945, 1186.9406529673481, 1189.5776999165346, 830.1656834880906, 876.0932434929817, 898.2589363774781, 904.7964208053136, 910.5993362920294, 5276.815311464157, 5828.795992140254, 5905.409459054146, 5928.170056192136, 6124.081387791867, 409.4268024765293, 418.738288569644, 439.71418577924607, 441.2917267785223, 444.34237328753625, 3850.172212974168, 3972.3445631471063, 4059.1475790083823, 4112.157007813391, 4132.834686612502, 10439.269251152498, 10705.83941240882, 10839.83740243897, 10855.782884342427, 10910.563577456971, 10775.137988960865, 10779.191560632984, 10790.190735694854, 10798.784024319626, 10807.513661885234, 8197.385196110195, 8293.917060829315, 8300.916990830136, 8308.12764659714, 8341.290990265774, 5701.344345400332, 5809.767609295581, 5965.940340596539, 6013.759449621966, 6037.947097986706, 9826.75433114178, 10132.240081993732, 10165.796684066425, 10181.796364072528, 10201.948990255298, 9309.255259579353, 9678.225741940487, 10087.495625218562, 10268.897311027093, 10277.0, 5764.711764411846, 6949.791506254821, 7030.753923612649, 7056.294370562902, 7077.929220707933, 8180.5909704515725, 8300.501969881827, 8396.832063358821, 8402.369822263478, 8420.95789521073, 7432.405407567489, 7485.70057197706, 7490.250974902466, 7597.544147351176, 7677.8464430709955, 7279.417646588155, 7292.270772922665, 7342.853142937218, 7389.778306650809, 7493.175750667313, 5963.493103086286, 6671.833204169935, 6917.446604271546, 6992.825179370557, 7047.682854271673, 4994.925076123934, 4995.125852975642, 5051.4443411224, 5068.82259120929, 5132.358455193101, 5009.724465154306, 5054.140796064605, 5100.0, 5112.590992720499, 5160.251763494197], "value": 4473.188450899132, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [291.83657151994885, 295.8846050040484, 305.07866243943283, 308.3740007784199, 308.50792985188633, 3868.4777555030064, 4609.9308510372375, 5355.410904800468, 5367.0, 5487.707375574653, 4534.5465453454635, 4770.209427484917, 5065.822696205633, 5178.249153872686, 5315.73421328933, 3346.8995930122137, 3984.9402258965993, 3995.5005624296964, 4054.4121102440126, 4098.520395160929, 4059.2084543514025, 4085.2442298174783, 4095.4061661059127, 4101.384792281152, 4104.686500319899, 3833.7891415972113, 3988.581198974095, 4028.6978476614227, 4031.0, 4050.023232923799, 3293.950590741141, 3295.505674148873, 3427.3830710472107, 3649.981750091246, 4015.4980627421573, 2844.815087019337, 3294.8352582371026, 3419.751790596439, 3451.5685539307588, 3476.1483436558206, 4453.799579018952, 5197.662151960149, 5222.268882356473, 5226.588821018342, 5252.002119597264, 4100.671946244293, 4222.239996800575, 4284.957150428487, 4566.150708962876, 4612.123696497655, 4652.371929789469, 4872.3909511311085, 5230.92153617696, 5279.340082489689, 5619.072852979253, 3490.4240800267926, 3793.2223894101917, 3795.2409518096215, 3811.2568049230413, 3820.1977584706965, 4632.652551058684, 5547.39125653564, 5701.828945131652, 5838.445347692005, 5853.209816674737, 3278.016595021484, 3821.063839359375, 3838.2535946144435, 3843.1737176506626, 3857.556381016163, 3136.8588413521447, 3684.5762737285017, 3698.9458004469134, 3899.7855117968784, 3950.822213000422, 3435.7766745161484, 3777.244551089793, 4254.766117825858, 4377.233984052778, 4522.638188944877, 5211.973940130275, 5671.773129074873, 5691.516221121168, 5701.429857014348, 5776.826695199151, 1076.6769969009265, 1176.1355403778216, 1271.5867343113525, 1353.3842101843834, 1361.6723694397883, 664.5680307799872, 838.0598913515192, 866.8786369908217, 868.1795703060674, 871.899731530882, 5016.172331565251, 5232.110541208004, 5258.474152584711, 5293.391260005072, 5369.301990741257, 5495.53287970519, 5665.008623490955, 5736.6844823534275, 5743.712814359265, 5774.364819869811, 4934.210526315772, 4956.04236326649, 5201.739913004335, 5260.9736951315, 5332.573394128461, 2524.457241693008, 2851.928701782472, 2911.937142942853, 2961.400843544462, 3030.4393687167944, 5576.841421684925, 5696.971515142481, 5767.452092051249, 5894.557908156906, 5942.64344139353, 5458.925608268833, 5797.565182611321, 5857.70711464425, 5942.43546863056, 6011.849703757353, 5863.824085277449, 5961.970190149108, 5977.731002104829, 5995.640261584319, 6005.369436209223, 4367.606915377504, 4715.599174070242, 5325.760340784752, 5693.772249110155, 5704.714764261687, 4982.327385803015, 5375.1243084949365, 5608.158776183605, 5625.071863142535, 5634.549236061023, 4561.885952851205, 5308.522232999048, 5751.670573417772, 5940.495057920125, 5960.374160713236, 3630.2739452110213, 3747.887563373103, 3763.7929913855, 3766.7551609145316, 3775.0747118968975, 4770.666053376231, 5380.650257733235, 5677.801276955287, 5696.316442026818, 5702.0306547887785, 4695.906081878412, 4769.451513076041, 4810.615150787999, 5293.5765138789575, 5491.478309560585, 4963.975180124076, 5093.668911520739, 5100.413452452867, 5535.833924982257, 5624.465675760796, 4259.38238955356, 5201.297824793604, 5450.482204190595, 5504.724763761876, 5530.806421775219, 5032.446430892525, 5461.836144915659, 5519.866524132595, 5596.3284405871555, 5604.551635869202, 5030.622703297194, 5080.831408775928, 5485.424030476902, 5500.012490944034, 5531.806386776443], "value": 3995.0386473576245, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [723.1069629008174, 723.4501778648228, 736.4255880413276, 795.2961628958371, 800.987985180222, 14490.0, 15251.389944402239, 15490.457833975814, 16195.595110122253, 16216.918915405422, 14037.29813509323, 14052.0, 14076.436942522312, 14077.648058798535, 14419.351129199185, 665.4676258992811, 684.2712511175607, 686.4028295382999, 687.7455341523644, 837.6733074101105, 675.5608854244731, 822.4037572759731, 858.1118542308711, 863.0980625246601, 866.2853146154416, 886.9911300886973, 891.2513488669516, 896.3859756067114, 901.202435844276, 901.7475106970026, 891.3849443883764, 892.7411050795262, 894.2309613732185, 894.4454438248328, 895.4716717136888, 511.83877078720457, 645.1161908185769, 656.4846595422571, 658.3844105761128, 660.4947215380238, 14285.0, 14286.428542858273, 14324.498642547565, 14358.641033974234, 14414.351354188982, 12851.871481285161, 13125.86874131256, 13146.35237953159, 13308.800367994489, 13508.662283442894, 12152.878471215352, 12388.0, 12415.379231038502, 12434.440450179764, 12791.680207994781, 4455.888602784924, 4469.687121901468, 4507.1211113832815, 4625.606823420013, 4690.530946905282, 117454.0, 118266.0, 118903.0, 119017.80982190154, 119117.0, 9006.459612423276, 10004.599816007423, 10678.145748340114, 10690.412027338416, 10782.892171078345, 20169.899150504447, 20210.79789202089, 20407.183712651626, 20471.0, 20471.897640511703, 7715.961420192975, 7723.691052357955, 7776.222377762178, 7873.370130389555, 7917.366610671133, 4321.805518751664, 4376.540463251313, 4428.867133985986, 4514.164879497303, 4548.317752337176, 3399.3201359728155, 3465.878694245689, 3472.8610855565494, 3475.14858859581, 3526.030341656065, 648.0570769530322, 653.1019847709399, 654.1528720307214, 657.4477438951304, 662.5693299355457, 546.8058839112058, 589.584343038157, 599.0, 625.2902955145942, 638.1034646321925, 7939.761807145795, 8090.919090809017, 8770.473771573726, 8785.34109941757, 9104.726858194264, 543.5977376741183, 585.5624441994893, 601.2034054877305, 611.9194737891116, 615.9414855588711, 3709.5062915844824, 4063.4514340563646, 4114.382842573638, 4328.870133895988, 4411.889702757457, 94872.0, 96378.55432168522, 141545.58454415324, 154539.0, 156325.0, 98658.01341986489, 109474.45262773635, 124640.0, 138079.0, 138314.0, 115731.42134289443, 119150.40424797821, 120586.39706801585, 121276.39361803135, 122043.0, 8207.466514676527, 8422.410431269753, 8478.703245386381, 8561.48631082137, 8746.562671866508, 61116.47208819815, 66535.00197496945, 66554.33445665675, 66909.0, 68715.65642171758, 89371.21257574942, 95539.0, 99744.86250372109, 101737.0, 103953.0, 10612.522436490532, 11036.227464077758, 11073.335599864035, 11132.666020019413, 11187.440627968406, 11456.770864582495, 11714.765704685688, 11754.353510557, 11786.705332366759, 12386.62840114798, 11497.367644779617, 11526.94236528812, 12659.0, 13407.28117120614, 13710.931445342709, 11357.432128393382, 13380.531681391105, 13412.865871341162, 13654.249016304198, 13761.243131627856, 13066.0, 13189.538366157522, 13480.662983425493, 13544.59366219015, 13894.791578126134, 11067.83398249043, 11375.488103035217, 11471.254368466023, 11534.348905102215, 11845.696973333099, 7654.961725191339, 7993.080795708568, 9281.46990614018, 9531.270141898112, 10017.799644006933], "value": 7539.521769648455, "unit": "score"}], "name": "dromaeo_css", "value": 7335.269138647265, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 115.0, 113.0, 112.0, 113.0, 111.0, 113.0, 113.0, 122.0], "value": 113.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 120.0, 162.0, 204.0, 111.0, 131.0, 129.0, 109.0, 160.0, 110.0], "value": 138.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [186.0, 187.0, 182.0, 184.0, 182.0, 182.0, 222.0, 180.0, 181.0, 183.0], "value": 186.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 77.0, 77.0, 158.0, 58.0, 58.0, 58.0, 59.0, 145.0, 60.0], "value": 82.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 82.0, 130.0, 69.0, 71.0, 76.0, 121.0, 68.0, 70.0, 115.0], "value": 88.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 90.0, 87.0, 89.0, 86.0, 91.0, 86.0, 88.0, 85.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 97.0, 96.0, 97.0, 97.0, 97.0, 97.0, 107.0, 97.0, 97.0], "value": 97.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 83.0, 84.0, 85.0, 83.0, 84.0, 83.0, 84.0, 85.0], "value": 83.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 53.0, 55.0, 52.0, 53.0, 53.0, 54.0, 53.0, 53.0, 53.0], "value": 53.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 69.0, 74.0, 74.0, 80.0, 73.0, 69.0, 70.0, 73.0], "value": 71.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 87.0, 84.0, 88.0, 87.0, 83.0, 89.0, 86.0, 86.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 153.0, 136.0, 154.0, 139.0, 138.0, 135.0, 156.0, 187.0, 139.0], "value": 147.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [167.0, 167.0, 169.0, 168.0, 169.0, 172.0, 168.0, 169.0, 168.0, 169.0], "value": 168.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 82.0, 84.0, 67.0, 68.0, 67.0, 67.0, 64.0, 66.0, 67.0], "value": 69.4, "unit": "score"}], "name": "kraken", "value": 1476.4, "alertThreshold": 2.0}]} 04:14:48 INFO - Return code: 0 04:14:48 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:14:48 INFO - # TBPL SUCCESS # 04:14:48 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:14:48 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:14:48 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:14:48 INFO - 2016-09-01 04:14:48.951 screenresolution[1672:10193] starting screenresolution argv=screenresolution get 04:14:48 INFO - 2016-09-01 04:14:48.955 screenresolution[1672:10193] Display 0: 1600x1200x32@60 04:14:48 INFO - 2016-09-01 04:14:48.962 screenresolution[1673:10196] starting screenresolution argv=screenresolution list 04:14:48 INFO - Available Modes on Display 0 04:14:48 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:14:48 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:14:48 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:14:48 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:14:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:14:49 INFO - Intel Iris: 04:14:49 INFO - Chipset Model: Intel Iris 04:14:49 INFO - Type: GPU 04:14:49 INFO - Bus: Built-In 04:14:49 INFO - VRAM (Dynamic, Max): 1536 MB 04:14:49 INFO - Vendor: Intel (0x8086) 04:14:49 INFO - Device ID: 0x0a2e 04:14:49 INFO - Revision ID: 0x0009 04:14:49 INFO - Displays: 04:14:49 INFO - Display: 04:14:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:14:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:14:49 INFO - Display Serial Number: 1600x1200 60 04:14:49 INFO - Main Display: Yes 04:14:49 INFO - Mirror: Off 04:14:49 INFO - Online: Yes 04:14:49 INFO - Rotation: Supported 04:14:49 INFO - Return code: 0 04:14:49 INFO - Running post-action listener: _resource_record_post_action 04:14:49 INFO - [mozharness: 2016-09-01 11:14:49.199075Z] Finished run-tests step (success) 04:14:49 INFO - Running post-run listener: _resource_record_post_run 04:14:49 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 31824896; Write bytes: 706417152; Read time: 195; Write time: 2010 04:14:49 INFO - TinderboxPrint: CPU usage
53.5% 04:14:49 INFO - TinderboxPrint: I/O read bytes / time
31,824,896 / 195 04:14:49 INFO - TinderboxPrint: I/O write bytes / time
706,417,152 / 2,010 04:14:49 INFO - TinderboxPrint: CPU idle
2,252.7 (46.5%) 04:14:49 INFO - TinderboxPrint: CPU system
973.0 (20.1%) 04:14:49 INFO - TinderboxPrint: CPU user
1,623.9 (33.5%) 04:14:49 INFO - TinderboxPrint: Swap in / out
433,971,200 / 0 04:14:49 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405193216; Write bytes: 400383488; Read time: 29862; Write time: 884 04:14:49 INFO - run-tests - Wall time: 1179s; CPU: 54.0%; Read bytes: 29035520; Write bytes: 295212032; Read time: 170; Write time: 1094 04:14:49 INFO - Running post-run listener: _upload_blobber_files 04:14:49 INFO - Blob upload gear active. 04:14:49 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:14:49 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 04:14:49 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'] 04:14:49 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 04:14:50 INFO - (blobuploader) - INFO - Open directory for files ... 04:14:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 04:14:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:14:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:14:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 04:14:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:14:51 INFO - (blobuploader) - INFO - Done attempting. 04:14:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 04:14:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:14:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:14:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 04:14:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:14:52 INFO - (blobuploader) - INFO - Done attempting. 04:14:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:14:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:14:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:14:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:14:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:14:54 INFO - (blobuploader) - INFO - Done attempting. 04:14:54 INFO - (blobuploader) - INFO - Iteration through files over. 04:14:54 INFO - Return code: 0 04:14:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:14:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:14:54 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e64ec956e48811bf25108dabf776e0faa2ee010f32b4ca248d16ec20d9cf5ebe9c37de1d0a809825136707fa8a85bd386a3419ac184885de95767c952e9e27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22627d86d148e65cc0bbfa7fb7ca0f02a16e8712f464b14a912598463b6718068ff9940af579d945c1362dd3f3b202f752589c939beef8d0e0c8c84f8d5cba77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b74a9eddcc2c2e3b9ca157594265a102ba51fb481a76026e34c9f3beec23a24f27ebb5165a7e930a100774e46b0e073e32c3b4f77d9a0fb8339680c4ac9a79eb"} 04:14:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:14:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:14:54 INFO - Contents: 04:14:54 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/a6e64ec956e48811bf25108dabf776e0faa2ee010f32b4ca248d16ec20d9cf5ebe9c37de1d0a809825136707fa8a85bd386a3419ac184885de95767c952e9e27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22627d86d148e65cc0bbfa7fb7ca0f02a16e8712f464b14a912598463b6718068ff9940af579d945c1362dd3f3b202f752589c939beef8d0e0c8c84f8d5cba77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b74a9eddcc2c2e3b9ca157594265a102ba51fb481a76026e34c9f3beec23a24f27ebb5165a7e930a100774e46b0e073e32c3b4f77d9a0fb8339680c4ac9a79eb"} 04:14:54 INFO - Running post-run listener: copy_logs_to_upload_dir 04:14:54 INFO - Copying logs to upload dir... 04:14:54 INFO - mkdir: /builds/slave/test/build/upload/logs 04:14:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.558309 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-01 04:14:54.691809) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 04:14:54.695959) ========= 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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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/a6e64ec956e48811bf25108dabf776e0faa2ee010f32b4ca248d16ec20d9cf5ebe9c37de1d0a809825136707fa8a85bd386a3419ac184885de95767c952e9e27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22627d86d148e65cc0bbfa7fb7ca0f02a16e8712f464b14a912598463b6718068ff9940af579d945c1362dd3f3b202f752589c939beef8d0e0c8c84f8d5cba77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b74a9eddcc2c2e3b9ca157594265a102ba51fb481a76026e34c9f3beec23a24f27ebb5165a7e930a100774e46b0e073e32c3b4f77d9a0fb8339680c4ac9a79eb"} build_url:https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010956 build_url: 'https://queue.taskcluster.net/v1/task/NFLdQPgYT2yTwnmOoV0kbA/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/a6e64ec956e48811bf25108dabf776e0faa2ee010f32b4ca248d16ec20d9cf5ebe9c37de1d0a809825136707fa8a85bd386a3419ac184885de95767c952e9e27", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/22627d86d148e65cc0bbfa7fb7ca0f02a16e8712f464b14a912598463b6718068ff9940af579d945c1362dd3f3b202f752589c939beef8d0e0c8c84f8d5cba77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b74a9eddcc2c2e3b9ca157594265a102ba51fb481a76026e34c9f3beec23a24f27ebb5165a7e930a100774e46b0e073e32c3b4f77d9a0fb8339680c4ac9a79eb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-01 04:14:54.730783) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:14:54.731312) ========= 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.4VcIsFVjoD/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.ljVAEUD4wL/Listeners TMPDIR=/var/folders/4x/n21ccs5d3x9dfwd381rpmnrr00000w/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.004961 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-01 04:14:54.782407) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 04:14:54.782886) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-01 04:14:54.783216) ========= ========= Total master_lag: 0.19 =========