builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0014 starttime: 1470702822.01 results: success (0) buildid: 20160808145420 builduid: 5ecc12caa96d41eb9b72f16d80f46e6b revision: 638fd37c122a6d6c2c5d182e2bcd91256e39867c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:42.011474) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:42.011975) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-08-08 17:33:42.012314) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-08-08 17:33:44.235801) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 17:33:44.236108) ========= 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.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-08-08 17:33:44-- 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% 8.02M=0.001s 2016-08-08 17:33:45 (8.02 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.553696 ========= master_lag: 0.60 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 17:33:45.389250) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:45.389647) ========= 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.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.020405 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:45.441367) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 17:33:45.441913) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 638fd37c122a6d6c2c5d182e2bcd91256e39867c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 638fd37c122a6d6c2c5d182e2bcd91256e39867c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-08-08 17:33:45,519 truncating revision to first 12 chars 2016-08-08 17:33:45,519 Setting DEBUG logging. 2016-08-08 17:33:45,519 attempt 1/10 2016-08-08 17:33:45,519 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/638fd37c122a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-08 17:33:47,012 unpacking tar archive at: autoland-638fd37c122a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.765076 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-08 17:33:47.233680) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:47.233999) ========= script_repo_revision: 638fd37c122a6d6c2c5d182e2bcd91256e39867c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:47.234452) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:47.234743) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-08 17:33:47.258757) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-08 17:33:47.259170) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:33:47 INFO - MultiFileLogger online at 20160808 17:33:47 in /builds/slave/test 17:33:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 17:33:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 17:33:47 INFO - {'append_to_log': False, 17:33:47 INFO - 'base_work_dir': '/builds/slave/test', 17:33:47 INFO - 'blob_upload_branch': 'Autoland', 17:33:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 17:33:47 INFO - 'branch': 'Autoland', 17:33:47 INFO - 'buildbot_json_path': 'buildprops.json', 17:33:47 INFO - 'config_files': ('talos/mac_config.py',), 17:33:47 INFO - 'default_actions': ('clobber', 17:33:47 INFO - 'read-buildbot-config', 17:33:47 INFO - 'download-and-extract', 17:33:47 INFO - 'populate-webroot', 17:33:47 INFO - 'create-virtualenv', 17:33:47 INFO - 'install', 17:33:47 INFO - 'run-tests'), 17:33:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 17:33:47 INFO - 'download_minidump_stackwalk': True, 17:33:47 INFO - 'download_symbols': 'ondemand', 17:33:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 17:33:47 INFO - 'tooltool.py': '/tools/tooltool.py', 17:33:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 17:33:47 INFO - '/tools/misc-python/virtualenv.py')}, 17:33:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 17:33:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 17:33:47 INFO - 'installer_path': 'installer.exe', 17:33:47 INFO - 'log_level': 'info', 17:33:47 INFO - 'log_name': 'talos', 17:33:47 INFO - 'log_to_console': True, 17:33:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 17:33:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 17:33:47 INFO - 'opt_config_files': (), 17:33:47 INFO - 'pip_index': False, 17:33:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:47 INFO - 'cmd': ('bash', 17:33:47 INFO - '-c', 17:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:47 INFO - 'enabled': True, 17:33:47 INFO - 'halt_on_failure': False, 17:33:47 INFO - 'name': 'check_screen_resolution'},), 17:33:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 17:33:47 INFO - 'cmd': ('bash', 17:33:47 INFO - '-c', 17:33:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 17:33:47 INFO - 'enabled': True, 17:33:47 INFO - 'halt_on_failure': False, 17:33:47 INFO - 'name': 'check_screen_resolution'},), 17:33:47 INFO - 'run_cmd_checks_enabled': True, 17:33:47 INFO - 'sps_profile': False, 17:33:47 INFO - 'sps_profile_interval': 0, 17:33:47 INFO - 'suite': 'dromaeojs-e10s', 17:33:47 INFO - 'system_bits': '32', 17:33:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 17:33:47 INFO - 'title': 't-yosemite-r7-0014', 17:33:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 17:33:47 INFO - 'use_talos_json': True, 17:33:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 17:33:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 17:33:47 INFO - 'work_dir': 'build'} 17:33:47 INFO - [mozharness: 2016-08-09 00:33:47.450910Z] Running clobber step. 17:33:47 INFO - Running pre-action listener: _resource_record_pre_action 17:33:47 INFO - Running main action method: clobber 17:33:47 INFO - rmtree: /builds/slave/test/build 17:33:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 17:33:49 INFO - Running post-action listener: _resource_record_post_action 17:33:49 INFO - [mozharness: 2016-08-09 00:33:49.681795Z] Finished clobber step (success) 17:33:49 INFO - [mozharness: 2016-08-09 00:33:49.682014Z] Running read-buildbot-config step. 17:33:49 INFO - Running pre-action listener: _resource_record_pre_action 17:33:49 INFO - Running main action method: read_buildbot_config 17:33:49 INFO - Using buildbot properties: 17:33:49 INFO - { 17:33:49 INFO - "project": "", 17:33:49 INFO - "product": "firefox", 17:33:49 INFO - "script_repo_revision": "production", 17:33:49 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 17:33:49 INFO - "repository": "", 17:33:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 17:33:49 INFO - "buildid": "20160808145420", 17:33:49 INFO - "pgo_build": "False", 17:33:49 INFO - "basedir": "/builds/slave/test", 17:33:49 INFO - "buildnumber": 105, 17:33:49 INFO - "slavename": "t-yosemite-r7-0014", 17:33:49 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 17:33:49 INFO - "platform": "yosemite_r7", 17:33:49 INFO - "scheduler": "tests-autoland-macosx64-talos", 17:33:49 INFO - "branch": "autoland", 17:33:49 INFO - "repo_path": "integration/autoland", 17:33:49 INFO - "revision": "638fd37c122a6d6c2c5d182e2bcd91256e39867c", 17:33:49 INFO - "stage_platform": "macosx64", 17:33:49 INFO - "builduid": "5ecc12caa96d41eb9b72f16d80f46e6b", 17:33:49 INFO - "slavebuilddir": "test" 17:33:49 INFO - } 17:33:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 17:33:49 INFO - Running post-action listener: _resource_record_post_action 17:33:49 INFO - [mozharness: 2016-08-09 00:33:49.687862Z] Finished read-buildbot-config step (success) 17:33:49 INFO - [mozharness: 2016-08-09 00:33:49.688090Z] Running download-and-extract step. 17:33:49 INFO - Running pre-action listener: _resource_record_pre_action 17:33:49 INFO - Running main action method: download_and_extract 17:33:49 INFO - mkdir: /builds/slave/test/build/tests 17:33:49 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')]} 17:33:49 INFO - trying https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:49 INFO - Downloading https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:51 INFO - Downloaded 1199 bytes. 17:33:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 17:33:51 INFO - Using the following test package requirements: 17:33:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 17:33:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 17:33:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'jsshell-mac.zip'], 17:33:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 17:33:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 17:33:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 17:33:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 17:33:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 17:33:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 17:33:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 17:33:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 17:33:51 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:51 INFO - trying https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:51 INFO - Downloading https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:52 INFO - Downloaded 16324638 bytes. 17:33:52 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:33:52 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:33:55 INFO - Return code: 0 17:33:55 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 17:33:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:55 INFO - trying https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 17:33:55 INFO - Downloading https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:55 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:56 INFO - Downloaded 16324638 bytes. 17:33:56 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:33:56 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip -d /builds/slave/test/build/tests 17:33:59 INFO - Return code: 0 17:33:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:33:59 INFO - trying https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 17:33:59 INFO - Downloading https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:33:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:34:00 INFO - Downloaded 14413723 bytes. 17:34:00 INFO - Running command: ['unzip', '-q', '-o', u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip', '-d', '/builds/slave/test/build/tests'] 17:34:00 INFO - Copy/paste: unzip -q -o /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip -d /builds/slave/test/build/tests 17:34:00 INFO - Return code: 0 17:34: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')]} 17:34:00 INFO - trying https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:00 INFO - Downloading https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 17:34:00 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/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 17:34:06 INFO - Downloaded 111981685 bytes. 17:34:06 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:06 INFO - mkdir: /builds/slave/test/properties 17:34:06 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 17:34:06 INFO - Writing to file /builds/slave/test/properties/build_url 17:34:06 INFO - Contents: 17:34:06 INFO - build_url:https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 17:34:07 INFO - Running post-action listener: _resource_record_post_action 17:34:07 INFO - Running post-action listener: set_extra_try_arguments 17:34:07 INFO - [mozharness: 2016-08-09 00:34:07.449461Z] Finished download-and-extract step (success) 17:34:07 INFO - [mozharness: 2016-08-09 00:34:07.449587Z] Running populate-webroot step. 17:34:07 INFO - Running pre-action listener: _resource_record_pre_action 17:34:07 INFO - Running main action method: populate_webroot 17:34:07 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 17:34:07 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 17:34:07 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 17:34:07 INFO - '--tppagecycles', 17:34:07 INFO - '7'], 17:34:07 INFO - 'tests': ['tsvgm']}}, 17:34:07 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tresize', 'tcanvasmark']}, 17:34:07 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 17:34:07 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['dromaeo_css', 'kraken']}, 17:34:07 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 17:34:07 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:07 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 17:34:07 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['damp', 'tps']}, 17:34:07 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['damp', 'tps']}, 17:34:07 INFO - 'g3': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['dromaeo_dom']}, 17:34:07 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 17:34:07 INFO - 'g4': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['basic_compositor_video']}, 17:34:07 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 17:34:07 INFO - 'other': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s_l64': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['a11yr', 17:34:07 INFO - 'ts_paint', 17:34:07 INFO - 'tpaint', 17:34:07 INFO - 'sessionrestore', 17:34:07 INFO - 'sessionrestore_no_auto_restore', 17:34:07 INFO - 'tabpaint']}, 17:34:07 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tsvgx', 17:34:07 INFO - 'tsvgr_opacity', 17:34:07 INFO - 'tart', 17:34:07 INFO - 'tscrollx', 17:34:07 INFO - 'cart']}, 17:34:07 INFO - 'svgr-e10s': {'tests': ['tsvgx', 17:34:07 INFO - 'tsvgr_opacity', 17:34:07 INFO - 'tart', 17:34:07 INFO - 'tscrollx', 17:34:07 INFO - 'cart']}, 17:34:07 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s'], 17:34:07 INFO - 'tests': ['tp5o']}, 17:34:07 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'tests': ['tp5o']}, 17:34:07 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--disable-e10s', 17:34:07 INFO - '--xperf_path', 17:34:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:07 INFO - 'tests': ['tp5n']}, 17:34:07 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 17:34:07 INFO - 'talos_options': ['--xperf_path', 17:34:07 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 17:34:07 INFO - 'tests': ['tp5n']}}, 17:34:07 INFO - 'talos.zip': {'path': '', 17:34:07 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 17:34:07 INFO - Running post-action listener: _resource_record_post_action 17:34:07 INFO - [mozharness: 2016-08-09 00:34:07.469458Z] Finished populate-webroot step (success) 17:34:07 INFO - [mozharness: 2016-08-09 00:34:07.469595Z] Running create-virtualenv step. 17:34:07 INFO - Running pre-action listener: _resource_record_pre_action 17:34:07 INFO - Running main action method: create_virtualenv 17:34:07 INFO - Creating virtualenv /builds/slave/test/build/venv 17:34:07 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 17:34:07 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 17:34:07 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 17:34:07 INFO - Using real prefix '/tools/python27' 17:34:07 INFO - New python executable in /builds/slave/test/build/venv/bin/python 17:34:08 INFO - Installing distribute.............................................................................................................................................................................................done. 17:34:11 INFO - Installing pip.................done. 17:34:11 INFO - Return code: 0 17:34:11 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 17:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 17:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:11 INFO - 'HOME': '/Users/cltbld', 17:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:11 INFO - 'LOGNAME': 'cltbld', 17:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:11 INFO - 'MOZ_NO_REMOTE': '1', 17:34:11 INFO - 'NO_EM_RESTART': '1', 17:34:11 INFO - 'PAGER': '/bin/cat', 17:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:11 INFO - 'PWD': '/builds/slave/test', 17:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:11 INFO - 'SHELL': '/bin/bash', 17:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:11 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:11 INFO - 'USER': 'cltbld', 17:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:11 INFO - 'XPC_FLAGS': '0x0', 17:34:11 INFO - 'XPC_SERVICE_NAME': '0', 17:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:11 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 17:34:11 INFO - Cleaning up... 17:34:11 INFO - Return code: 0 17:34:11 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:11 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:11 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 17:34:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 17:34:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:11 INFO - 'HOME': '/Users/cltbld', 17:34:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:11 INFO - 'LOGNAME': 'cltbld', 17:34:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:11 INFO - 'MOZ_NO_REMOTE': '1', 17:34:11 INFO - 'NO_EM_RESTART': '1', 17:34:11 INFO - 'PAGER': '/bin/cat', 17:34:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:11 INFO - 'PWD': '/builds/slave/test', 17:34:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:11 INFO - 'SHELL': '/bin/bash', 17:34:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:11 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:11 INFO - 'USER': 'cltbld', 17:34:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:11 INFO - 'XPC_FLAGS': '0x0', 17:34:11 INFO - 'XPC_SERVICE_NAME': '0', 17:34:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:11 INFO - Downloading/unpacking psutil>=3.1.1 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:11 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 17:34:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 17:34:14 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:14 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:14 INFO - Installing collected packages: psutil 17:34:14 INFO - Running setup.py install for psutil 17:34:14 INFO - building 'psutil._psutil_osx' extension 17:34:14 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 17:34:14 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 17:34:15 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 17:34:15 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 17:34:15 INFO - building 'psutil._psutil_posix' extension 17:34:15 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 17:34:15 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 17:34:15 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 17:34:15 INFO - ^ 17:34:15 INFO - 1 warning generated. 17:34:15 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 17:34:15 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 17:34:15 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 17:34:15 INFO - Successfully installed psutil 17:34:15 INFO - Cleaning up... 17:34:15 INFO - Return code: 0 17:34:15 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 17:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:15 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:15 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 17:34:15 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 17:34:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:15 INFO - 'HOME': '/Users/cltbld', 17:34:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:15 INFO - 'LOGNAME': 'cltbld', 17:34:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:15 INFO - 'MOZ_NO_REMOTE': '1', 17:34:15 INFO - 'NO_EM_RESTART': '1', 17:34:15 INFO - 'PAGER': '/bin/cat', 17:34:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:15 INFO - 'PWD': '/builds/slave/test', 17:34:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:15 INFO - 'SHELL': '/bin/bash', 17:34:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:15 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:15 INFO - 'USER': 'cltbld', 17:34:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:15 INFO - 'XPC_FLAGS': '0x0', 17:34:15 INFO - 'XPC_SERVICE_NAME': '0', 17:34:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:15 INFO - Downloading/unpacking mozsystemmonitor==0.3 17:34:15 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 17:34:15 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 17:34:15 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 17:34:15 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 17:34:15 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 17:34:15 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 17:34:18 INFO - Downloading mozsystemmonitor-0.3.tar.gz 17:34:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 17:34:18 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 17:34:18 INFO - Installing collected packages: mozsystemmonitor 17:34:18 INFO - Running setup.py install for mozsystemmonitor 17:34:18 INFO - Successfully installed mozsystemmonitor 17:34:18 INFO - Cleaning up... 17:34:18 INFO - Return code: 0 17:34:18 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 17:34:18 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')]} 17:34:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:18 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')]} 17:34:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:18 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:18 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 17:34:18 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 17:34:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:18 INFO - 'HOME': '/Users/cltbld', 17:34:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:18 INFO - 'LOGNAME': 'cltbld', 17:34:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:18 INFO - 'MOZ_NO_REMOTE': '1', 17:34:18 INFO - 'NO_EM_RESTART': '1', 17:34:18 INFO - 'PAGER': '/bin/cat', 17:34:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:18 INFO - 'PWD': '/builds/slave/test', 17:34:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:18 INFO - 'SHELL': '/bin/bash', 17:34:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:18 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:18 INFO - 'USER': 'cltbld', 17:34:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:18 INFO - 'XPC_FLAGS': '0x0', 17:34:18 INFO - 'XPC_SERVICE_NAME': '0', 17:34:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:18 INFO - Downloading/unpacking jsonschema==2.5.1 17:34: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 17:34: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 17:34: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 17:34: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 17:34: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 17:34: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 17:34:21 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 17:34:21 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 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 17:34:21 INFO - Downloading functools32-3.2.3-2.tar.gz 17:34:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 17:34:21 INFO - warning: no files found matching '*.txt' 17:34:21 INFO - no previously-included directories found matching 'build' 17:34:21 INFO - no previously-included directories found matching 'dist' 17:34:21 INFO - no previously-included directories found matching '.git*' 17:34:21 INFO - Installing collected packages: jsonschema, functools32 17:34:21 INFO - Running setup.py install for functools32 17:34:21 INFO - warning: no files found matching '*.txt' 17:34:21 INFO - no previously-included directories found matching 'build' 17:34:21 INFO - no previously-included directories found matching 'dist' 17:34:21 INFO - no previously-included directories found matching '.git*' 17:34:21 INFO - Successfully installed jsonschema functools32 17:34:21 INFO - Cleaning up... 17:34:22 INFO - Return code: 0 17:34:22 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 17:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 17:34:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:22 INFO - 'HOME': '/Users/cltbld', 17:34:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:22 INFO - 'LOGNAME': 'cltbld', 17:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:22 INFO - 'MOZ_NO_REMOTE': '1', 17:34:22 INFO - 'NO_EM_RESTART': '1', 17:34:22 INFO - 'PAGER': '/bin/cat', 17:34:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:22 INFO - 'PWD': '/builds/slave/test', 17:34:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:22 INFO - 'SHELL': '/bin/bash', 17:34:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:22 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:22 INFO - 'USER': 'cltbld', 17:34:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:22 INFO - 'XPC_FLAGS': '0x0', 17:34:22 INFO - 'XPC_SERVICE_NAME': '0', 17:34:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:22 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 17:34:22 INFO - Cleaning up... 17:34:22 INFO - Return code: 0 17:34:22 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:22 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:22 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 17:34:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 17:34:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:22 INFO - 'HOME': '/Users/cltbld', 17:34:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:22 INFO - 'LOGNAME': 'cltbld', 17:34:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:22 INFO - 'MOZ_NO_REMOTE': '1', 17:34:22 INFO - 'NO_EM_RESTART': '1', 17:34:22 INFO - 'PAGER': '/bin/cat', 17:34:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:22 INFO - 'PWD': '/builds/slave/test', 17:34:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:22 INFO - 'SHELL': '/bin/bash', 17:34:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:22 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:22 INFO - 'USER': 'cltbld', 17:34:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:22 INFO - 'XPC_FLAGS': '0x0', 17:34:22 INFO - 'XPC_SERVICE_NAME': '0', 17:34:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:22 INFO - Downloading/unpacking blobuploader==1.2.4 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:22 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 17:34:24 INFO - Downloading blobuploader-1.2.4.tar.gz 17:34:24 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 17:34:25 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 17:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 17:34:25 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 17:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 17:34:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 17:34:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 17:34:26 INFO - Downloading docopt-0.6.1.tar.gz 17:34:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 17:34:26 INFO - Installing collected packages: blobuploader, requests, docopt 17:34:26 INFO - Running setup.py install for blobuploader 17:34:26 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 17:34:26 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 17:34:26 INFO - Running setup.py install for requests 17:34:26 INFO - Running setup.py install for docopt 17:34:27 INFO - Successfully installed blobuploader requests docopt 17:34:27 INFO - Cleaning up... 17:34:27 INFO - Return code: 0 17:34:27 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:27 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:27 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 17:34:27 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 17:34:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:27 INFO - 'HOME': '/Users/cltbld', 17:34:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:27 INFO - 'LOGNAME': 'cltbld', 17:34:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:27 INFO - 'MOZ_NO_REMOTE': '1', 17:34:27 INFO - 'NO_EM_RESTART': '1', 17:34:27 INFO - 'PAGER': '/bin/cat', 17:34:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:27 INFO - 'PWD': '/builds/slave/test', 17:34:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:27 INFO - 'SHELL': '/bin/bash', 17:34:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:27 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:27 INFO - 'USER': 'cltbld', 17:34:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:27 INFO - 'XPC_FLAGS': '0x0', 17:34:27 INFO - 'XPC_SERVICE_NAME': '0', 17:34:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-2V7zvD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-VruX0Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Nbdh_0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-5IBYq3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-9yWOoI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:27 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:27 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-chtH9w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-vr0ZfR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-68mEYd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-cRCcRG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-v5dwQl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-6uDThw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Q8rDCm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-uAE0ns-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-2KsPg4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Tg1ixe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:28 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-r6XOyq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:29 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-soX6ue-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:29 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 17:34:29 INFO - Running setup.py install for manifestparser 17:34:29 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 17:34:29 INFO - Running setup.py install for mozcrash 17:34:29 INFO - Running setup.py install for mozdebug 17:34:29 INFO - Running setup.py install for mozdevice 17:34:29 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 17:34:29 INFO - Installing dm script to /builds/slave/test/build/venv/bin 17:34:29 INFO - Running setup.py install for mozfile 17:34:29 INFO - Running setup.py install for mozhttpd 17:34:30 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozinfo 17:34:30 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozInstall 17:34:30 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozleak 17:34:30 INFO - Running setup.py install for mozlog 17:34:30 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for moznetwork 17:34:30 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 17:34:30 INFO - Running setup.py install for mozprocess 17:34:30 INFO - Running setup.py install for mozprofile 17:34:31 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozrunner 17:34:31 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Running setup.py install for mozscreenshot 17:34:31 INFO - Running setup.py install for moztest 17:34:31 INFO - Running setup.py install for mozversion 17:34:31 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 17:34:31 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 17:34:31 INFO - Cleaning up... 17:34:31 INFO - Return code: 0 17:34:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:31 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:31 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 17:34:31 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 17:34:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:31 INFO - 'HOME': '/Users/cltbld', 17:34:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:31 INFO - 'LOGNAME': 'cltbld', 17:34:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:31 INFO - 'MOZ_NO_REMOTE': '1', 17:34:31 INFO - 'NO_EM_RESTART': '1', 17:34:31 INFO - 'PAGER': '/bin/cat', 17:34:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:31 INFO - 'PWD': '/builds/slave/test', 17:34:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:31 INFO - 'SHELL': '/bin/bash', 17:34:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:31 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:31 INFO - 'USER': 'cltbld', 17:34:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:31 INFO - 'XPC_FLAGS': '0x0', 17:34:31 INFO - 'XPC_SERVICE_NAME': '0', 17:34:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-gvxtRV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-2fNKtS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-xGHwXX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-n4W6gT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ReASE8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-UgSgC2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-xh0Iaa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-lEeOY7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 17:34:32 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-oHSqZC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 17:34:32 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)) 17:34:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-aWivj6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-_8qEjy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-l5Du78-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-Tno6wz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-U_Z3ru-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-j7awro-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-5EvhyX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 17:34:33 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)) 17:34:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 17:34:33 INFO - Running setup.py (path:/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/pip-ZNMjh6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 17:34:33 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)) 17:34:33 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)) 17:34:33 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)) 17:34:33 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)) 17:34:33 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)) 17:34:33 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)) 17:34:33 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:33 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 17:34:36 INFO - Downloading blessings-1.6.tar.gz 17:34:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 17:34:36 INFO - Installing collected packages: blessings 17:34:36 INFO - Running setup.py install for blessings 17:34:36 INFO - Successfully installed blessings 17:34:36 INFO - Cleaning up... 17:34:36 INFO - Return code: 0 17:34:36 INFO - Done creating virtualenv /builds/slave/test/build/venv. 17:34:36 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:36 INFO - Reading from file tmpfile_stdout 17:34:36 INFO - Current package versions: 17:34:36 INFO - blessings == 1.6 17:34:36 INFO - blobuploader == 1.2.4 17:34:36 INFO - docopt == 0.6.1 17:34:36 INFO - functools32 == 3.2.3-2 17:34:36 INFO - jsonschema == 2.5.1 17:34:36 INFO - manifestparser == 1.1 17:34:36 INFO - mozInstall == 1.12 17:34:36 INFO - mozcrash == 0.17 17:34:36 INFO - mozdebug == 0.1 17:34:36 INFO - mozdevice == 0.48 17:34:36 INFO - mozfile == 1.2 17:34:36 INFO - mozhttpd == 0.7 17:34:36 INFO - mozinfo == 0.9 17:34:36 INFO - mozleak == 0.1 17:34:36 INFO - mozlog == 3.3 17:34:36 INFO - moznetwork == 0.27 17:34:36 INFO - mozprocess == 0.23 17:34:36 INFO - mozprofile == 0.28 17:34:36 INFO - mozrunner == 6.12 17:34:36 INFO - mozscreenshot == 0.1 17:34:36 INFO - mozsystemmonitor == 0.3 17:34:36 INFO - moztest == 0.7 17:34:36 INFO - mozversion == 1.4 17:34:36 INFO - psutil == 3.1.1 17:34:36 INFO - requests == 1.2.3 17:34:36 INFO - wsgiref == 0.1.2 17:34:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 17:34:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:37 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:37 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 17:34:37 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 17:34:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:37 INFO - 'HOME': '/Users/cltbld', 17:34:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:37 INFO - 'LOGNAME': 'cltbld', 17:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:37 INFO - 'MOZ_NO_REMOTE': '1', 17:34:37 INFO - 'NO_EM_RESTART': '1', 17:34:37 INFO - 'PAGER': '/bin/cat', 17:34:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:37 INFO - 'PWD': '/builds/slave/test', 17:34:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:37 INFO - 'SHELL': '/bin/bash', 17:34:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:37 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:37 INFO - 'USER': 'cltbld', 17:34:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:37 INFO - 'XPC_FLAGS': '0x0', 17:34:37 INFO - 'XPC_SERVICE_NAME': '0', 17:34:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 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)) 17:34:37 INFO - Cleaning up... 17:34:37 INFO - Return code: 0 17:34:37 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 17:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 17:34:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:34:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 17:34:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 17:34:37 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 0x10d82ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d8c3618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdca581320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, '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.CnkabucJp7/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.4klplnEt9u/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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 17:34:37 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 17:34:37 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 17:34:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:34:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:34:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:34:37 INFO - 'HOME': '/Users/cltbld', 17:34:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:34:37 INFO - 'LOGNAME': 'cltbld', 17:34:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:34:37 INFO - 'MOZ_NO_REMOTE': '1', 17:34:37 INFO - 'NO_EM_RESTART': '1', 17:34:37 INFO - 'PAGER': '/bin/cat', 17:34:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:34:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:34:37 INFO - 'PWD': '/builds/slave/test', 17:34:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:34:37 INFO - 'SHELL': '/bin/bash', 17:34:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:34:37 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:34:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:34:37 INFO - 'USER': 'cltbld', 17:34:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:34:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:34:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:34:37 INFO - 'XPC_FLAGS': '0x0', 17:34:37 INFO - 'XPC_SERVICE_NAME': '0', 17:34:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:34:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 17:34:37 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 17:34:37 INFO - Cleaning up... 17:34:37 INFO - Return code: 0 17:34:37 INFO - Running post-action listener: _resource_record_post_action 17:34:37 INFO - Running post-action listener: _start_resource_monitoring 17:34:37 INFO - Starting resource monitoring. 17:34:37 INFO - [mozharness: 2016-08-09 00:34:37.570827Z] Finished create-virtualenv step (success) 17:34:37 INFO - [mozharness: 2016-08-09 00:34:37.571851Z] Running install step. 17:34:37 INFO - Running pre-action listener: _resource_record_pre_action 17:34:37 INFO - Running main action method: install 17:34:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 17:34:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 17:34:37 INFO - Reading from file tmpfile_stdout 17:34:37 INFO - Detecting whether we're running mozinstall >=1.0... 17:34:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 17:34:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 17:34:37 INFO - Reading from file tmpfile_stdout 17:34:37 INFO - Output received: 17:34:37 INFO - Usage: mozinstall [options] installer 17:34:37 INFO - Options: 17:34:37 INFO - -h, --help show this help message and exit 17:34:37 INFO - -d DEST, --destination=DEST 17:34:37 INFO - Directory to install application into. [default: 17:34:37 INFO - "/builds/slave/test"] 17:34:37 INFO - --app=APP Application being installed. [default: firefox] 17:34:37 INFO - mkdir: /builds/slave/test/build/application 17:34:37 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'] 17:34:37 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 17:35:12 INFO - Reading from file tmpfile_stdout 17:35:12 INFO - Output received: 17:35:12 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 17:35:12 INFO - Running post-action listener: _resource_record_post_action 17:35:12 INFO - [mozharness: 2016-08-09 00:35:12.397679Z] Finished install step (success) 17:35:12 INFO - [mozharness: 2016-08-09 00:35:12.397822Z] Running run-tests step. 17:35:12 INFO - Running pre-action listener: _resource_record_pre_action 17:35:12 INFO - Running main action method: run_tests 17:35:12 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:35:12 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:35:12 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:35:12 INFO - 2016-08-08 17:35:12.413 screenresolution[1940:65508] starting screenresolution argv=screenresolution get 17:35:12 INFO - 2016-08-08 17:35:12.416 screenresolution[1940:65508] Display 0: 1600x1200x32@60 17:35:12 INFO - 2016-08-08 17:35:12.421 screenresolution[1941:65510] starting screenresolution argv=screenresolution list 17:35:12 INFO - Available Modes on Display 0 17:35:12 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:35:12 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:35:12 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:35:12 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:35:12 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:35:12 INFO - Intel Iris: 17:35:12 INFO - Chipset Model: Intel Iris 17:35:12 INFO - Type: GPU 17:35:12 INFO - Bus: Built-In 17:35:12 INFO - VRAM (Dynamic, Max): 1536 MB 17:35:12 INFO - Vendor: Intel (0x8086) 17:35:12 INFO - Device ID: 0x0a2e 17:35:12 INFO - Revision ID: 0x0009 17:35:12 INFO - Displays: 17:35:12 INFO - Display: 17:35:12 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:35:12 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:35:12 INFO - Display Serial Number: 1600x1200 60 17:35:12 INFO - Main Display: Yes 17:35:12 INFO - Mirror: Off 17:35:12 INFO - Online: Yes 17:35:12 INFO - Rotation: Supported 17:35:12 INFO - Return code: 0 17:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 17:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 17:35:12 INFO - Python 2.7.3 17:35:12 INFO - Return code: 0 17:35:12 INFO - grabbing minidump binary from tooltool 17:35:12 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 17:35:12 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 0x10d792d30>, '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 0x10d4f7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10d78ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 17:35:12 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 17:35:12 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 17:35:12 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 17:35:12 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 17:35:12 INFO - Return code: 0 17:35:12 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 17:35:12 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 17:35:12 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 17:35:12 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 17:35:12 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 17:35:12 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 17:35:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0014 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 17:35:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.CnkabucJp7/Render', 17:35:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 17:35:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 17:35:12 INFO - 'HOME': '/Users/cltbld', 17:35:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 17:35:12 INFO - 'LOGNAME': 'cltbld', 17:35:12 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 17:35:12 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 17:35:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 17:35:12 INFO - 'MOZ_NO_REMOTE': '1', 17:35:12 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 17:35:12 INFO - 'NO_EM_RESTART': '1', 17:35:12 INFO - 'PAGER': '/bin/cat', 17:35:12 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 17:35:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 17:35:12 INFO - 'PWD': '/builds/slave/test', 17:35:12 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 17:35:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 17:35:12 INFO - 'SHELL': '/bin/bash', 17:35:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.4klplnEt9u/Listeners', 17:35:12 INFO - 'TMPDIR': '/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/', 17:35:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 17:35:12 INFO - 'USER': 'cltbld', 17:35:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 17:35:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 17:35:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 17:35:12 INFO - 'XPC_FLAGS': '0x0', 17:35:12 INFO - 'XPC_SERVICE_NAME': '0', 17:35:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 17:35:12 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0014', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 17:35:13 INFO - mozversion application_buildid: 20160808145420 17:35:13 INFO - mozversion application_changeset: 638fd37c122a6d6c2c5d182e2bcd91256e39867c 17:35:13 INFO - mozversion application_display_name: Nightly 17:35:13 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 17:35:13 INFO - mozversion application_name: Firefox 17:35:13 INFO - mozversion application_remotingname: firefox 17:35:13 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 17:35:13 INFO - mozversion application_vendor: Mozilla 17:35:13 INFO - mozversion application_version: 51.0a1 17:35:13 INFO - mozversion platform_buildid: 20160808145420 17:35:13 INFO - mozversion platform_changeset: 638fd37c122a6d6c2c5d182e2bcd91256e39867c 17:35:13 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 17:35:13 INFO - mozversion platform_version: 51.0a1 17:35:13 INFO - using testdate: 1470702913 17:35:13 INFO - actual date: 1470702913 17:35:13 INFO - starting webserver on 'localhost:53677' 17:35:13 INFO - SUITE-START | Running 2 tests 17:35:13 INFO - TEST-START | dromaeo_css 17:35:13 INFO - operating with platform_type : mac_ 17:35:13 INFO - Initialising browser for dromaeo_css test... 17:35:13 INFO - TEST-INFO | started process 1951 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp2W_QaM/profile http://localhost:53677/getInfo.html) 17:35:15 INFO - PROCESS | 1951 | __metrics Screen width/height:1600/1200 17:35:15 INFO - PROCESS | 1951 | colorDepth:24 17:35:15 INFO - PROCESS | 1951 | Browser inner width/height: 1024/689 17:35:15 INFO - PROCESS | 1951 | __metrics 17:35:16 ERROR - PROCESS | 1951 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 17:35:16 INFO - TEST-INFO | 1951: exit 0 17:35:16 INFO - Browser initialized. 17:35:16 INFO - Running cycle 1/1 for dromaeo_css test... 17:35:16 INFO - TEST-INFO | started process 1954 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmp2W_QaM/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:35:28 INFO - PROCESS | 1954 | RSS: Main: 254812160 17:35:28 INFO - PROCESS | 1954 | 17:36:18 INFO - PROCESS | 1954 | 1470702978577 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] 17:38:31 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-dojo.html (next: http://localhost:53677/tests/dromaeo/cssquery-ext.html) 17:38:32 INFO - PROCESS | 1954 | RSS: Main: 267362304 17:38:32 INFO - PROCESS | 1954 | 17:41:34 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-ext.html (next: http://localhost:53677/tests/dromaeo/cssquery-jquery.html) 17:41:35 INFO - PROCESS | 1954 | RSS: Main: 280158208 17:41:35 INFO - PROCESS | 1954 | 17:44:37 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-jquery.html (next: http://localhost:53677/tests/dromaeo/cssquery-mootools.html) 17:44:38 INFO - PROCESS | 1954 | RSS: Main: 287522816 17:44:38 INFO - PROCESS | 1954 | 17:47:40 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-mootools.html (next: http://localhost:53677/tests/dromaeo/cssquery-prototype.html) 17:47:40 INFO - PROCESS | 1954 | RSS: Main: 287035392 17:47:40 INFO - PROCESS | 1954 | 17:50:43 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-prototype.html (next: http://localhost:53677/tests/dromaeo/cssquery-yui.html) 17:50:44 INFO - PROCESS | 1954 | RSS: Main: 292941824 17:50:44 INFO - PROCESS | 1954 | 17:53:46 INFO - PROCESS | 1954 | Cycle 1(1): loaded http://localhost:53677/tests/dromaeo/cssquery-yui.html (next: http://localhost:53677/tests/dromaeo/cssquery-dojo.html) 17:53:47 INFO - PROCESS | 1954 | RSS: Main: 292376576 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | __start_tp_report 17:53:47 INFO - PROCESS | 1954 | _x_x_mozilla_page_load 17:53:47 INFO - PROCESS | 1954 | _x_x_mozilla_page_load_details 17:53:47 INFO - PROCESS | 1954 | |i|pagename|runs| 17:53:47 INFO - PROCESS | 1954 | |0;dojo.html;3215.646278909318;3284.6386897441275;3388.2545839915215;3430.7598468107212;3455.118944669109;5704.914426283599;5744.655320680762;5805.651660900344;5904.763809447622;5913.467787899088;4748.335233067373;4859.829905953292;5850.444207800263;5874.823755287348;5933.3473317934995;4678.204705200107;5052.848414547551;5317.813876514323;5397.946020539784;5409.486098820606;5435.728213589325;5458.727063646822;5495.8351249462385;5506.366767821712;5519.751611177507;5245.554127899133;5247.003069416837;5310.468953104697;5357.705326207057;5404.243405923174;5331.493508116722;5345.705986170759;5375.193720941851;5398.865028374322;5450.236966824647;5218.634695571312;5242.370915490127;5299.231611416361;5339.946600533984;5348.679079255257;5644.887102257973;5680.630759000652;5755.740991655385;5811.4479124483105;5857.970710146465;6228.065790131472;6247.625142491465;6262.937370626327;6270.498360131179;6280.842978925563;5170.405403378622;6296.024116261987;6313.084602732624;6345.904811427832;6461;5808.215890854765;5915.7633694652595;5967.612105213234;6003.6097653652805;6020.367861374581;6385.138006369174;6404.647744374104;6420.390062944013;6425.389587989133;6433.420992110732;6186.381361863778;6223.657698826518;6278.46633036197;6280.905786413207;6295.464885484694;6150.769846030812;6183.319834818257;6215.036669316263;6270.153529273581;6282.026285925689;6252.499800016079;6285.497160227171;6288.937110628926;6294.874102518015;6316.178896743394;6222.12890195373;6228.688565571703;6279.309275979638;6302.149209856702;6306.716197771111;4914.36113305268;5023.070731914608;5041.445441001487;5065.797368105308;5151.871203219949;4507.211238033331;4537.546245375502;4546.045330480636;4568.383268258743;4607.516210797885;5776.422357764274;5788.479036886616;5887.4701276884425;5910.674912879746;5928.140419639214;5463.262459567987;6111.37903775109;6208.348123447064;6240.188775459162;6256.468200203034;5917.52659787216;5918.674472903946;5949.405059494016;5963.075723262901;5984.311804142492;4790.880227994258;5564.527015203753;5565.443455654483;5571.66570005801;5575.968445837533;6223.937760622336;6374.681265936591;6396.872062558816;6465.935340646627;6489.415952564197;6281.309056003927;6284.4344009039405;6284.842878928063;6301.4328710416285;6311.526635502264;6391.456726178327;6457.741690332522;6469.385408386196;6480.513961452815;6482.57863238888;6130.398136741914;6187.195664563578;6188.350223226496;6215.409536094064;6219.533534984804;5036.722980236123;5343.732813359395;6293.559450838397;6295.433410993032;6299.275583307978;6261.154744109669;6265.655388953469;6279.623222606476;6281.842953926189;6291.748330066929;5183.948160518346;6144.477719393902;6160.661163635864;6166.321704612399;6233.283172435046;5198.584113271005;5918.260217472816;6114.715909658844;6172.135900973777;6202.193714817045;6216.9877948095755;6257.092721555258;6279.686015699288;6283.2460104787715;6288.748450061947;5568;5956.672383018976;6055.6063855849225;6117.418845209698;6140.140380346845 17:53:47 INFO - PROCESS | 1954 | |1;ext.html;456.8058575105579;488.1092007087067;515.6673945305279;536.4849744245524;546.903458565576;28289.858550707242;34788.130296742595;35169.59321627138;42417.36373954393;42460;42934.57065429352;43446.56553434473;43515.564844351626;43534;43563.346549801776;9543.618255269797;9612.759681007961;9700.708978730649;9708.223342132613;9779.46212958287;8692.782680432976;9258.351915365927;9699.272554558402;9738.561764720569;9745.853812192823;9720.125188733013;9754.46350450725;9758.951205243999;9764.853527197061;9773.120419162275;9705.75735606612;9716.757081072994;9727;9738.026197380275;9762.658306959293;9616.951915240448;9673.177779888718;9700.466474343908;9706.951465242699;9734.075262850018;40918.1816363671;41363.58636413657;41485.585144148776;41596;42220.788896055325;3106.3476669899123;3322;3381.746369022333;3518.0501264658656;3563.1092226943265;30605;31120.844395778102;31349.529757053664;31699.732010719545;31913.680863191305;3067.7239048485744;3112.5331200319906;3129.358481511285;3129.749620030415;3238.5789847319697;449992;451985.99999999674;453098;454804;455671;33687.66312336894;33857.83071084629;33876.49185262223;33934.15164620855;33934.66065339315;34871.4769278461;35527.822360888546;35776.642233577855;35979.64020359815;36117;16499.422520211974;16540.83459165417;16618.6676266474;16728.83271167297;16735.748963765553;12590.685232869251;12702.745945081047;12707.555235566711;12714.554990575285;12735.617931462071;19801.504962376057;19998.90000549988;20055.899720501304;20062.996850157433;20186.19255229804;6905.654717264117;6939.861202775916;8038.397120216007;8192.75421737349;8235.011798584088;868.0624925080898;953.947532885684;1015.8323876560432;1060.7242117049468;1072.6353039966448;31183.532247016312;31322.37355252882;31386.21534461611;31708.365832683216;31889.362212755615;1042.6142327338932;1069.8181309177457;1071.9571217151226;1084.980118688446;1157.7858096252053;15993.1203783793;16273.755893661604;16358.83641163597;16406;16614.91692541554;703511;713971;715178.9374743822;715793;716169;53048.73475632675;60494;60916.78166436735;61564.38435615676;61627.6918615413;1197.736497970445;1339.450825161679;1393.9684633371232;1395.1210737235476;1402.8246469191351;30550.388992219585;30835.229119272197;30895.536566951963;31519.36961260808;31677.8416107918;44079;54287.45712542824;54379;54394.7280263612;54558.727206363714;603872;606822.9658851854;607376;609996;610478;26723.599146012435;28136.29659258535;28156.71843281541;28173.859130704215;28227.294317642223;49399;49997.50002499928;50460.49539504558;50819.74590127174;51617.48382516127;40849.795751021054;41903.952401188995;42103.6970914228;42418;43068.56931430646;34280.65719342874;38729.6127038726;41745.582544175384;41818.95452613709;42027.51527932542;34769.826150870096;39770.80687579377;40525;40572.79713601413;40920.59079409168;34413.65586344204;36444.72443464466;36584.73195453722;36891.63108368882;37266.8136659315;26689;26795.06217282386;26887.327816804736;26893.86553067222;27098.869972566597 17:53:47 INFO - PROCESS | 1954 | |2;jquery.html;362.4237462434728;370.137579439905;375.1746158451407;388.76673995602624;397.0490674833773;19177.42467725969;20694.172233110658;21996.780032199673;22033.779662203375;22183.44541386466;20371.08330125144;20637.690434643453;20883;21206.25778097767;21337.466563335885;5336.6531175473665;5384.946150538493;5402.216678581623;5409.648372855771;5488.835334939959;3196.5205219217187;4685.8594242172785;5390.407055223922;5415.756290966916;5426.728663566825;6050.092486127094;6155.938440615604;6156.722947467375;6157.6305421674615;6160.599561028519;5661.2923384576725;5971.910421343683;6036.637801731867;6146;6170.7531698732;5828;5843.18195452637;5898.793542226002;5929.73316200772;5945.256842894638;24343.269701908972;24957.00171993118;24967.750322496726;25216.243512694648;25856.612150817753;2290.541891621682;2307.861528308307;2670.612761149622;3024.5916801231774;3108.0675797260956;9953.601855925754;10094.343867648578;13812.618738126208;15133.394664213418;16101.516954491384;1904.381076150243;1907.2943011085988;1917.9232830686756;1923.240320073563;1972.339266345776;107008.46495767549;123720;125240.37379813043;125669;125731;11184.608538701106;11587.768244635061;11673;11784.469698863571;11952.64142075739;14597.92701036488;14765;14775.556733298019;14809.185494797674;14816.40734370613;7159.890217016371;7767.92232077672;8266.628001739937;8547.401681882333;8680.69617563395;8396.79008024792;8403.747887563382;8426;8456.02755683092;8666.913330866737;3536.9646303536633;4614.976925115353;4616.122936642095;4676.602488788423;4681.6020638245445;1605.189379363414;1918.4628304074881;1958.2558627721376;1980.7127966445073;1983.097690550796;811.7889348769362;827.0447632983913;853.6756032707656;874.9850173798363;928.5589345061042;19141.61716765657;21442;21465.03407346673;21554.784452155276;21652.783472165393;793.0126991895128;812.858851377846;873.9606717697719;964.3394274921701;1015.5125539740818;11143.609973651046;11528.884711152949;11597;11601.12991525639;11629.709257268636;566834;585337;617873;620000;630269;137959.93060104107;141270;142299;142650.2867485656;144321;828.3000864269683;901.9549022548846;962.2686758063921;964.4406244378243;988.7577543501889;18718.906405467886;19186.712199317302;19212.903935480794;19272.32546860853;19319.323823666105;54592.178547501295;54669.72665136649;55917;56030.719846400505;57829;79375.60312198402;79709;82981.58509207415;83852;93620;7126.536775109601;7327.010853534704;7483.962580187064;7766.417518686234;8385.454945428528;10393.636222732168;12304.108534494208;12407.25556466615;12505.249685018927;13461.798073029107;8813.25087367581;11434.82847757301;11532.019778318936;11566;11685;11352.602658906899;11459.942700286778;11509.194356394972;11644.35956022427;11772.352520611448;11612.88387116118;11667.6499705009;11949.940250298692;12077.03383729282;12168.93915530452;8194.30348420367;8204.056533498724;8262.917370826215;8391.370647201362;8432.789180270542;8466.365022623451;9873.45695986699;10947.781044379228;11313;11325.263857849213 17:53:47 INFO - PROCESS | 1954 | |3;mootools.html;1495.7382458069837;1531.4639876043382;1733.566608347913;1862.1527205121672;1869.1308541528188;7993.400494962886;8475.279601233895;8568.828623427527;9018.233450156727;9063.229625481834;8661.740147795575;8811.251043661281;8858.689945851896;8865;8871.556422178897;2332.3352844439382;2355.3522781235124;2364.47981444082;2366.124533922451;2369.7511761265077;2324.3027091872455;2331.347222777625;2386.78518933296;2388.34320561846;2390.5099454611764;2353.8940747666397;2365.8698771567556;2367.2188177901253;2371.063429945171;2388.4387169015267;2345.566070277004;2354.5290941811622;2360.8583484990954;2362.1024010875844;2364.645303204516;2264.6489794081945;2330.93007209784;2331;2347.812175025994;2370.8103351731993;7916.247956444128;8903.777405564848;9033.141851524095;9035.683751068747;9046;5090.592752579785;5162.045021670965;5428.4842939920645;5440.156775699733;5450.809221677222;2825.9858700706573;3149.2126968257935;3566.946495802565;3638.108663377463;3685.870994515206;812.4353574265888;953.423178976716;1077.1705786544317;1079.266099052644;1094.1684319916842;11502.252353597072;11814.763704725858;11816.763664726743;11838.171328007043;11848.348340841336;4021.939670904939;4259.637930775919;4350.977520282731;4759.571638552477;4881.926771098437;10059.245556583286;10062.849057264148;10066.14437772783;10077.891431942628;10078.193744500422;5333.2266821310695;5349.384820745586;5349.983503134392;5357.089294819812;5381.919271210935;4457.598816106566;4794.400699912511;4861.927071093936;4920.852374428773;4953.876153096201;3982.761034337949;4020.0552870075508;4089.8568550100604;4101.507819061731;4133.793310334471;1086.5002099034375;1227.6930767308172;1259.6850787303174;1269.847618285793;1280.8783165599252;810.8986376702912;912.2519533982204;918.7381596245039;929.340168480373;950.3395140377371;5371.187036294604;5518.66887986714;5846.41535846412;6128.908066379008;6243.594166379171;436.46968932746864;462.98611041668806;463.19404236628026;953.5708930981086;1034.3535290443472;3969.106950936074;4007.178528401729;4080.510613626005;4150.543440221573;4162.41726158338;10811.729706757395;11027.117830573536;11056.557737690402;11066.833997490045;11101.944490277498;10901.727456813485;10981.6156434526;11020.338779673246;11035.668929932115;11111.055560277306;7854.60726963661;8169.468984515987;8385.958070209732;8477.025142108736;8489.787755306166;5985.191999080069;6037.426444487766;6195.845103872439;6198.81403557894;6209.0996805462055;9639.951800240953;10048.346857454242;10176.982301769764;10199.235057370875;10271.486425678835;10235.641752538626;10426.739331516772;10430;10430.791384172426;10462.68611941643;6990.650467476504;7380.889286660597;7548.962255188689;7556.8488630228185;7735.9613201933635;8443.240108390275;8499.022612399653;8531.786705332417;8557.272631826116;8579.82840343202;6604.339566043357;7541.962290188514;7709.573728860179;7735.922640773745;7806.609669516388;7527.473076884785;7559.28186822251;7579.848403032019;7699.26856948589;7782.571958542335;7119.181294151032;7187;7192.568445893263;7195.712171513081;7199.3520583147765;4232.894177645583;4966.6771659842;5142.452416104842;5158.871028224325;5172.043172013189;4509.143262780061;5047.974760126176;5180.559652429435;5188.377394712658;5270.578353731616 17:53:47 INFO - PROCESS | 1954 | |4;prototype.html;274.27727936886305;297.17830199498377;307.5832247304902;313.5030975903193;317.04569246567826;5541.667499950001;5550.222968784368;5584.86037849054;5592.916106258399;5619.690916999563;4729.668923175379;5441.483059109388;5463.562914966803;5499.1476321170185;5575.386707462176;3785.4889589905288;3927.6857851371965;3968.72218944674;4083.7549747015273;4246;3633.36416127178;4178.206140833247;4178.26880295947;4199.45407097077;4202.97898510507;3695.020819482839;3889.7860617666015;4143.544210136897;4183.226103170923;4189.601987811153;3632.4732913727494;3846.807659617036;4121.505419349667;4156.210320039183;4204.474440694913;3154.511050787132;3582.6775590197008;3588.8923332300074;3593.82030898454;3594.856205751767;4567.018091110427;5021.497850214986;5419.99730049942;5423.674579525241;5501.174823776466;4563.740948230614;4770.212914869043;4779;4795.537361104862;4805.75971201442;4816.831410900637;5813.7383817728305;5834.870272350604;5853.619514731528;5901.645901245896;3974.4038394240806;3979.422983667352;3981.6416522512823;3982.3628219484744;4029.194161167749;4885.581637089628;5151.407588127376;6158.230221222392;6176.567640265183;6199.791040747056;3372.6795954384293;3499.61504234534;4028.617281358266;4043.3934909763184;4044.4944381952255;3459.7578169528397;3591.581325376462;3879.127196380848;4088.2232375848494;4102.179564087194;4022.5977402260123;4277.401163837066;4786.540105267857;4812.614990800727;4819.228923372244;4847.733374664427;5622.746976386073;5867.442592953663;5879.235699359056;5897.67562784051;1130.7908037013178;1362.9182249065088;1387.341013018808;1395.8325000999946;1405.8242719660043;800.5916982339049;834.964643841632;842.8230071685015;845.4124383553494;852.953087580189;4198.929273035356;4985.925211121836;4991.377802214295;5434.456554344613;5439.456054394608;5116.104681680691;5281.098804430402;6003.189569408161;6138.232720909887;6176.382361763787;4535.342375355555;4968.677035992649;5024;5098.425295225961;5457.471907865804;2508;2752.9174124776287;3172.8254945977733;3189.6331921829287;3196.0252123102214;5477.589180811376;5754.798582049608;6221.160143380676;6233.226214334578;6271.749130034747;4935.851924442272;5241.842744717588;5801.3038435388025;5959.332794298158;6085.786997455157;6149.5080393568405;6233;6265.778173256172;6312.684365781784;6325.399087086719;5565.359983601776;6023.488003519575;6055.939440605537;6090;6098.451139397475;5877.4122587740885;5905.5570832186895;5993.790217342372;6037.426444487766;6057.666828324308;6095.542834287357;6283.449850834264;6300.243970723543;6351.237851457855;6364.358791101641;3971.2454633620673;3974.821133048962;3997.7618129315347;3999.500187429714;4015.7271823463784;6048.788292409745;6051.33435322123;6063.575549711478;6068.180795592715;6094.969525152524;4850.569082120854;5899.144624029577;5916.763329466773;5926.799528254866;5947.672877991579;5011.9498805012945;5822.10781496007;5835.94164058371;5871.295444546682;5887.264091988502;5074.391073071255;5756.913646295226;5769.509591684754;5806.332271788629;5832;4556.724117247176;5334.75993580282;5844.035734103995;5917.260342457193;5953.464188223081;5750.5112065474905;5794.304683438015;5813.331466881363;5814.912776308443;5882.323532793613 17:53:47 INFO - PROCESS | 1954 | |5;yui.html;683.9247682754899;717.4475653746618;725.9782206533804;774.6204359863668;801.9077806052302;15108.47120350788;15697.529074127795;15873.126978016204;15874.444394446196;15895.443659471906;14132.293385330744;14258.574142585736;14382;14383;14386.568402947929;845.0873057098349;869.9995005743383;870.2341939093589;875.3522393428849;877.0265005843509;678.8268991407184;841.0748177005306;855.0167307596283;866.2160744526183;877.8288233794412;912.5893347993397;917.2661870503604;917.9265658747318;921.3964853021248;927.7077720518015;866.4627930682954;875.5359659380539;890.9020007799137;908.52756566585;909.1136142261308;647.6502688548178;658.6509150150428;678.7895752316798;682.5051837417832;683.4839696029516;14285.57143285703;14475.493357732534;14831.258437078102;14835;14850.034747741362;12712.936435317857;13777.448902043907;14730.63173420673;14811.037282576597;15132.773008404884;12013.099017573717;12089.697757556043;12207.328596927166;12301.138920275584;12650.873491265062;3821.14024344523;3867.9806600967095;3868.574456809749;3872.2255548890334;3876.0697432616253;80480.59759701222;98501.5224771629;114975;116354.41822790916;118778;10080.697579072494;11255.718607034725;11402.94298528502;11419.257748246337;11559.422028898689;23023;23513.88243058774;23529.7647023531;23558.293251202493;23766.28701138934;8188.222118898695;8242.29940455056;8243.876341854877;8289.212524810133;8311.418200725891;3955.841766329372;4501.549845015537;4530.093981203707;4575.702579332333;4772.308015337757;2899.3331533746937;3555.484454754098;3622.5290712207243;3645.3620616392027;3667.009907325007;651.0657206908102;657.3557913245047;666.7166454256921;670.6948338506065;680.0818894492396;657.2080642825384;663.741140955028;665.2084020016165;685.5269863794066;686.2417029182781;9013.72562681024;9387;9500.19248363883;9574;9914.45492432555;535.4859335038329;676.8815457294953;677.9796406408423;685.4858855858106;690.1717938473752;4300.80646370914;4707.7175369477945;4722.791588706187;4812.181929072066;4885.951140488621;132837.33581332033;137389.3130534341;147214;163878;167965.3203467974;122623.70817021362;125378;127750.36124819503;127916;133265.66734332533;30227;30616.846915765724;30677.386452270828;32250.83874580659;115731;9269.860952085859;9334.486603236886;9384.718458446258;9404.153626173676;9518.714438566854;60485;60773.696131520825;62908.68545657242;64357;64529.35470645234;83623;85221;86249;95573.5221323889;95641.08717825466;13447.731045379232;13675.452981880613;13731.862681373457;13924.58226253214;14195.077319974582;12722.427490762753;14257.786133208218;14797.7235804772;15340.463083792014;15433.382664693736;11024.50389732448;14077.507287245307;14160.150390976574;14352.928235358757;14637.341319640429;13736.175829450265;14075.648108797363;14081.225532595805;14117.082389645051;14151.929240353733;11462.656120316404;13477.249983819287;13643.249621270528;13807.309634518435;14346;11279.436028198394;11306.660800176009;11376.715582110513;11550.133739969368;11795.174337796616;11373.772524549628;11657.358845263592;11792.52829886795;11811.291322520676;11821.763564728486 17:53:47 INFO - PROCESS | 1954 | __end_tp_report 17:53:47 INFO - PROCESS | 1954 | __start_cc_report 17:53:47 INFO - PROCESS | 1954 | _x_x_mozilla_cycle_collect,419 17:53:47 INFO - PROCESS | 1954 | __end_cc_report 17:53:47 INFO - PROCESS | 1954 | __startTimestamp1470704027155__endTimestamp 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | ------- Summary: start ------- 17:53:47 INFO - PROCESS | 1954 | Number of tests: 6 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#0] dojo.html Cycles:165 Average:5794.77 Median:6003.61 stddev:661.86 (11.0%) stddev-sans-first:632.21 17:53:47 INFO - PROCESS | 1954 | Values: 3215.6 3284.6 3388.3 3430.8 3455.1 5704.9 5744.7 5805.7 5904.8 5913.5 4748.3 4859.8 5850.4 5874.8 5933.3 4678.2 5052.8 5317.8 5397.9 5409.5 5435.7 5458.7 5495.8 5506.4 5519.8 5245.6 5247.0 5310.5 5357.7 5404.2 5331.5 5345.7 5375.2 5398.9 5450.2 5218.6 5242.4 5299.2 5339.9 5348.7 5644.9 5680.6 5755.7 5811.4 5858.0 6228.1 6247.6 6262.9 6270.5 6280.8 5170.4 6296.0 6313.1 6345.9 6461.0 5808.2 5915.8 5967.6 6003.6 6020.4 6385.1 6404.6 6420.4 6425.4 6433.4 6186.4 6223.7 6278.5 6280.9 6295.5 6150.8 6183.3 6215.0 6270.2 6282.0 6252.5 6285.5 6288.9 6294.9 6316.2 6222.1 6228.7 6279.3 6302.1 6306.7 4914.4 5023.1 5041.4 5065.8 5151.9 4507.2 4537.5 4546.0 4568.4 4607.5 5776.4 5788.5 5887.5 5910.7 5928.1 5463.3 6111.4 6208.3 6240.2 6256.5 5917.5 5918.7 5949.4 5963.1 5984.3 4790.9 5564.5 5565.4 5571.7 5576.0 6223.9 6374.7 6396.9 6465.9 6489.4 6281.3 6284.4 6284.8 6301.4 6311.5 6391.5 6457.7 6469.4 6480.5 6482.6 6130.4 6187.2 6188.4 6215.4 6219.5 5036.7 5343.7 6293.6 6295.4 6299.3 6261.2 6265.7 6279.6 6281.8 6291.7 5183.9 6144.5 6160.7 6166.3 6233.3 5198.6 5918.3 6114.7 6172.1 6202.2 6217.0 6257.1 6279.7 6283.2 6288.7 5568.0 5956.7 6055.6 6117.4 6140.1 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#1] ext.html Cycles:180 Average:71257.71 Median:40548.90 stddev:161062.73 (397.2%) stddev-sans-first:161426.83 17:53:47 INFO - PROCESS | 1954 | Values: 456.8 488.1 515.7 536.5 546.9 28289.9 34788.1 35169.6 42417.4 42460.0 42934.6 43446.6 43515.6 43534.0 43563.3 9543.6 9612.8 9700.7 9708.2 9779.5 8692.8 9258.4 9699.3 9738.6 9745.9 9720.1 9754.5 9759.0 9764.9 9773.1 9705.8 9716.8 9727.0 9738.0 9762.7 9617.0 9673.2 9700.5 9707.0 9734.1 40918.2 41363.6 41485.6 41596.0 42220.8 3106.3 3322.0 3381.7 3518.1 3563.1 30605.0 31120.8 31349.5 31699.7 31913.7 3067.7 3112.5 3129.4 3129.7 3238.6 449992.0 451986.0 453098.0 454804.0 455671.0 33687.7 33857.8 33876.5 33934.2 33934.7 34871.5 35527.8 35776.6 35979.6 36117.0 16499.4 16540.8 16618.7 16728.8 16735.7 12590.7 12702.7 12707.6 12714.6 12735.6 19801.5 19998.9 20055.9 20063.0 20186.2 6905.7 6939.9 8038.4 8192.8 8235.0 868.1 953.9 1015.8 1060.7 1072.6 31183.5 31322.4 31386.2 31708.4 31889.4 1042.6 1069.8 1072.0 1085.0 1157.8 15993.1 16273.8 16358.8 16406.0 16614.9 703511.0 713971.0 715178.9 715793.0 716169.0 53048.7 60494.0 60916.8 61564.4 61627.7 1197.7 1339.5 1394.0 1395.1 1402.8 30550.4 30835.2 30895.5 31519.4 31677.8 44079.0 54287.5 54379.0 54394.7 54558.7 603872.0 606823.0 607376.0 609996.0 610478.0 26723.6 28136.3 28156.7 28173.9 28227.3 49399.0 49997.5 50460.5 50819.7 51617.5 40849.8 41904.0 42103.7 42418.0 43068.6 34280.7 38729.6 41745.6 41819.0 42027.5 34769.8 39770.8 40525.0 40572.8 40920.6 34413.7 36444.7 36584.7 36891.6 37266.8 26689.0 26795.1 26887.3 26893.9 27098.9 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#2] jquery.html Cycles:180 Average:36027.02 Median:3066.33 stddev:101376.32 (3306.1%) stddev-sans-first:101625.34 17:53:47 INFO - PROCESS | 1954 | Values: 362.4 370.1 375.2 388.8 397.0 19177.4 20694.2 21996.8 22033.8 22183.4 20371.1 20637.7 20883.0 21206.3 21337.5 5336.7 5384.9 5402.2 5409.6 5488.8 3196.5 4685.9 5390.4 5415.8 5426.7 6050.1 6155.9 6156.7 6157.6 6160.6 5661.3 5971.9 6036.6 6146.0 6170.8 5828.0 5843.2 5898.8 5929.7 5945.3 24343.3 24957.0 24967.8 25216.2 25856.6 2290.5 2307.9 2670.6 3024.6 3108.1 9953.6 10094.3 13812.6 15133.4 16101.5 1904.4 1907.3 1917.9 1923.2 1972.3 107008.5 123720.0 125240.4 125669.0 125731.0 11184.6 11587.8 11673.0 11784.5 11952.6 14597.9 14765.0 14775.6 14809.2 14816.4 7159.9 7767.9 8266.6 8547.4 8680.7 8396.8 8403.7 8426.0 8456.0 8666.9 3537.0 4615.0 4616.1 4676.6 4681.6 1605.2 1918.5 1958.3 1980.7 1983.1 811.8 827.0 853.7 875.0 928.6 19141.6 21442.0 21465.0 21554.8 21652.8 793.0 812.9 874.0 964.3 1015.5 11143.6 11528.9 11597.0 11601.1 11629.7 566834.0 585337.0 617873.0 620000.0 630269.0 137959.9 141270.0 142299.0 142650.3 144321.0 828.3 902.0 962.3 964.4 988.8 18718.9 19186.7 19212.9 19272.3 19319.3 54592.2 54669.7 55917.0 56030.7 57829.0 79375.6 79709.0 82981.6 83852.0 93620.0 7126.5 7327.0 7484.0 7766.4 8385.5 10393.6 12304.1 12407.3 12505.2 13461.8 8813.3 11434.8 11532.0 11566.0 11685.0 11352.6 11459.9 11509.2 11644.4 11772.4 11612.9 11667.6 11949.9 12077.0 12168.9 8194.3 8204.1 8262.9 8391.4 8432.8 8466.4 9873.5 10947.8 11313.0 11325.3 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#3] mootools.html Cycles:180 Average:5769.63 Median:463.09 stddev:3284.17 (709.2%) stddev-sans-first:3277.68 17:53:47 INFO - PROCESS | 1954 | Values: 1495.7 1531.5 1733.6 1862.2 1869.1 7993.4 8475.3 8568.8 9018.2 9063.2 8661.7 8811.3 8858.7 8865.0 8871.6 2332.3 2355.4 2364.5 2366.1 2369.8 2324.3 2331.3 2386.8 2388.3 2390.5 2353.9 2365.9 2367.2 2371.1 2388.4 2345.6 2354.5 2360.9 2362.1 2364.6 2264.6 2330.9 2331.0 2347.8 2370.8 7916.2 8903.8 9033.1 9035.7 9046.0 5090.6 5162.0 5428.5 5440.2 5450.8 2826.0 3149.2 3566.9 3638.1 3685.9 812.4 953.4 1077.2 1079.3 1094.2 11502.3 11814.8 11816.8 11838.2 11848.3 4021.9 4259.6 4351.0 4759.6 4881.9 10059.2 10062.8 10066.1 10077.9 10078.2 5333.2 5349.4 5350.0 5357.1 5381.9 4457.6 4794.4 4861.9 4920.9 4953.9 3982.8 4020.1 4089.9 4101.5 4133.8 1086.5 1227.7 1259.7 1269.8 1280.9 810.9 912.3 918.7 929.3 950.3 5371.2 5518.7 5846.4 6128.9 6243.6 436.5 463.0 463.2 953.6 1034.4 3969.1 4007.2 4080.5 4150.5 4162.4 10811.7 11027.1 11056.6 11066.8 11101.9 10901.7 10981.6 11020.3 11035.7 11111.1 7854.6 8169.5 8386.0 8477.0 8489.8 5985.2 6037.4 6195.8 6198.8 6209.1 9640.0 10048.3 10177.0 10199.2 10271.5 10235.6 10426.7 10430.0 10430.8 10462.7 6990.7 7380.9 7549.0 7556.8 7736.0 8443.2 8499.0 8531.8 8557.3 8579.8 6604.3 7542.0 7709.6 7735.9 7806.6 7527.5 7559.3 7579.8 7699.3 7782.6 7119.2 7187.0 7192.6 7195.7 7199.4 4232.9 4966.7 5142.5 5158.9 5172.0 4509.1 5048.0 5180.6 5188.4 5270.6 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#4] prototype.html Cycles:180 Average:4702.62 Median:5261.47 stddev:1496.88 (28.4%) stddev-sans-first:1463.71 17:53:47 INFO - PROCESS | 1954 | Values: 274.3 297.2 307.6 313.5 317.0 5541.7 5550.2 5584.9 5592.9 5619.7 4729.7 5441.5 5463.6 5499.1 5575.4 3785.5 3927.7 3968.7 4083.8 4246.0 3633.4 4178.2 4178.3 4199.5 4203.0 3695.0 3889.8 4143.5 4183.2 4189.6 3632.5 3846.8 4121.5 4156.2 4204.5 3154.5 3582.7 3588.9 3593.8 3594.9 4567.0 5021.5 5420.0 5423.7 5501.2 4563.7 4770.2 4779.0 4795.5 4805.8 4816.8 5813.7 5834.9 5853.6 5901.6 3974.4 3979.4 3981.6 3982.4 4029.2 4885.6 5151.4 6158.2 6176.6 6199.8 3372.7 3499.6 4028.6 4043.4 4044.5 3459.8 3591.6 3879.1 4088.2 4102.2 4022.6 4277.4 4786.5 4812.6 4819.2 4847.7 5622.7 5867.4 5879.2 5897.7 1130.8 1362.9 1387.3 1395.8 1405.8 800.6 835.0 842.8 845.4 853.0 4198.9 4985.9 4991.4 5434.5 5439.5 5116.1 5281.1 6003.2 6138.2 6176.4 4535.3 4968.7 5024.0 5098.4 5457.5 2508.0 2752.9 3172.8 3189.6 3196.0 5477.6 5754.8 6221.2 6233.2 6271.7 4935.9 5241.8 5801.3 5959.3 6085.8 6149.5 6233.0 6265.8 6312.7 6325.4 5565.4 6023.5 6055.9 6090.0 6098.5 5877.4 5905.6 5993.8 6037.4 6057.7 6095.5 6283.4 6300.2 6351.2 6364.4 3971.2 3974.8 3997.8 3999.5 4015.7 6048.8 6051.3 6063.6 6068.2 6095.0 4850.6 5899.1 5916.8 5926.8 5947.7 5011.9 5822.1 5835.9 5871.3 5887.3 5074.4 5756.9 5769.5 5806.3 5832.0 4556.7 5334.8 5844.0 5917.3 5953.5 5750.5 5794.3 5813.3 5814.9 5882.3 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - PROCESS | 1954 | [#5] yui.html Cycles:180 Average:23099.80 Median:3633.95 stddev:37261.30 (1025.4%) stddev-sans-first:37327.81 17:53:47 INFO - PROCESS | 1954 | Values: 683.9 717.4 726.0 774.6 801.9 15108.5 15697.5 15873.1 15874.4 15895.4 14132.3 14258.6 14382.0 14383.0 14386.6 845.1 870.0 870.2 875.4 877.0 678.8 841.1 855.0 866.2 877.8 912.6 917.3 917.9 921.4 927.7 866.5 875.5 890.9 908.5 909.1 647.7 658.7 678.8 682.5 683.5 14285.6 14475.5 14831.3 14835.0 14850.0 12712.9 13777.4 14730.6 14811.0 15132.8 12013.1 12089.7 12207.3 12301.1 12650.9 3821.1 3868.0 3868.6 3872.2 3876.1 80480.6 98501.5 114975.0 116354.4 118778.0 10080.7 11255.7 11402.9 11419.3 11559.4 23023.0 23513.9 23529.8 23558.3 23766.3 8188.2 8242.3 8243.9 8289.2 8311.4 3955.8 4501.5 4530.1 4575.7 4772.3 2899.3 3555.5 3622.5 3645.4 3667.0 651.1 657.4 666.7 670.7 680.1 657.2 663.7 665.2 685.5 686.2 9013.7 9387.0 9500.2 9574.0 9914.5 535.5 676.9 678.0 685.5 690.2 4300.8 4707.7 4722.8 4812.2 4886.0 132837.3 137389.3 147214.0 163878.0 167965.3 122623.7 125378.0 127750.4 127916.0 133265.7 30227.0 30616.8 30677.4 32250.8 115731.0 9269.9 9334.5 9384.7 9404.2 9518.7 60485.0 60773.7 62908.7 64357.0 64529.4 83623.0 85221.0 86249.0 95573.5 95641.1 13447.7 13675.5 13731.9 13924.6 14195.1 12722.4 14257.8 14797.7 15340.5 15433.4 11024.5 14077.5 14160.2 14352.9 14637.3 13736.2 14075.6 14081.2 14117.1 14151.9 11462.7 13477.2 13643.2 13807.3 14346.0 11279.4 11306.7 11376.7 11550.1 11795.2 11373.8 11657.4 11792.5 11811.3 11821.8 17:53:47 INFO - PROCESS | 1954 | -------- Summary: end -------- 17:53:47 INFO - PROCESS | 1954 | 17:53:47 INFO - TEST-INFO | 1954: exit 0 17:53:47 INFO - TEST-OK | dromaeo_css | took 1114759ms 17:53:47 INFO - TEST-START | kraken 17:53:47 INFO - operating with platform_type : mac_ 17:53:47 INFO - Initialising browser for kraken test... 17:53:47 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpgH9BhU/profile http://localhost:53677/getInfo.html) 17:53:50 INFO - PROCESS | 1958 | __metrics Screen width/height:1600/1200 17:53:50 INFO - PROCESS | 1958 | colorDepth:24 17:53:50 INFO - PROCESS | 1958 | Browser inner width/height: 1024/689 17:53:50 INFO - PROCESS | 1958 | __metrics 17:53:50 ERROR - PROCESS | 1958 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2967: TypeError: this.tabs is undefined 17:53:51 INFO - TEST-INFO | 1958: exit 0 17:53:51 INFO - Browser initialized. 17:53:51 INFO - Running cycle 1/1 for kraken test... 17:53:51 INFO - TEST-INFO | started process 1960 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/T/tmpgH9BhU/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 17:54:03 INFO - PROCESS | 1960 | RSS: Main: 254652416 17:54:03 INFO - PROCESS | 1960 | 17:54:06 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:53677/tests/kraken/driver.html?testName=audio-beat-detection) 17:54:07 INFO - PROCESS | 1960 | RSS: Main: 267599872 17:54:07 INFO - PROCESS | 1960 | 17:54:09 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:53677/tests/kraken/driver.html?testName=audio-dft) 17:54:10 INFO - PROCESS | 1960 | RSS: Main: 276406272 17:54:10 INFO - PROCESS | 1960 | 17:54:14 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:53677/tests/kraken/driver.html?testName=audio-fft) 17:54:14 INFO - PROCESS | 1960 | RSS: Main: 279416832 17:54:14 INFO - PROCESS | 1960 | 17:54:17 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:53677/tests/kraken/driver.html?testName=audio-oscillator) 17:54:17 INFO - PROCESS | 1960 | RSS: Main: 281755648 17:54:17 INFO - PROCESS | 1960 | 17:54:19 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:53677/tests/kraken/driver.html?testName=imaging-gaussian-blur) 17:54:20 INFO - PROCESS | 1960 | RSS: Main: 282116096 17:54:20 INFO - PROCESS | 1960 | 17:54:23 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:53677/tests/kraken/driver.html?testName=imaging-darkroom) 17:54:23 INFO - PROCESS | 1960 | RSS: Main: 282931200 17:54:23 INFO - PROCESS | 1960 | 17:54:26 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:53677/tests/kraken/driver.html?testName=imaging-desaturate) 17:54:27 INFO - PROCESS | 1960 | RSS: Main: 282845184 17:54:27 INFO - PROCESS | 1960 | 17:54:30 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:53677/tests/kraken/driver.html?testName=json-parse-financial) 17:54:31 INFO - PROCESS | 1960 | RSS: Main: 283140096 17:54:31 INFO - PROCESS | 1960 | 17:54:32 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:53677/tests/kraken/driver.html?testName=json-stringify-tinderbox) 17:54:33 INFO - PROCESS | 1960 | RSS: Main: 282681344 17:54:33 INFO - PROCESS | 1960 | 17:54:35 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-aes) 17:54:36 INFO - PROCESS | 1960 | RSS: Main: 282755072 17:54:36 INFO - PROCESS | 1960 | 17:54:38 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-ccm) 17:54:39 INFO - PROCESS | 1960 | RSS: Main: 282972160 17:54:39 INFO - PROCESS | 1960 | 17:54:42 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 17:54:42 INFO - PROCESS | 1960 | RSS: Main: 283168768 17:54:42 INFO - PROCESS | 1960 | 17:54:45 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 17:54:46 INFO - PROCESS | 1960 | RSS: Main: 283262976 17:54:46 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | Cycle 1(1): loaded http://localhost:53677/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:53677/tests/kraken/driver.html?testName=ai-astar) 17:54:48 INFO - PROCESS | 1960 | RSS: Main: 284192768 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | __start_tp_report 17:54:48 INFO - PROCESS | 1960 | _x_x_mozilla_page_load 17:54:48 INFO - PROCESS | 1960 | _x_x_mozilla_page_load_details 17:54:48 INFO - PROCESS | 1960 | |i|pagename|runs| 17:54:48 INFO - PROCESS | 1960 | |0;ai-astar;108;117;107;119;117;115;114;117;118;116 17:54:48 INFO - PROCESS | 1960 | |1;audio-beat-detection;147;116;174;182;109;136;141;111;160;110 17:54:48 INFO - PROCESS | 1960 | |2;audio-dft;228;285;213;234;287;243;241;223;200;199 17:54:48 INFO - PROCESS | 1960 | |3;audio-fft;74;105;70;147;76;58;53;70;171;84 17:54:48 INFO - PROCESS | 1960 | |4;audio-oscillator;70;72;119;72;71;77;111;70;73;119 17:54:48 INFO - PROCESS | 1960 | |5;imaging-gaussian-blur;89;88;91;87;90;87;91;90;121;129 17:54:48 INFO - PROCESS | 1960 | |6;imaging-darkroom;151;149;96;97;95;101;102;97;147;152 17:54:48 INFO - PROCESS | 1960 | |7;imaging-desaturate;91;95;84;146;148;88;95;99;91;118 17:54:48 INFO - PROCESS | 1960 | |8;json-parse-financial;58;57;58;56;58;58;58;58;57;55 17:54:48 INFO - PROCESS | 1960 | |9;json-stringify-tinderbox;67;72;70;78;72;78;80;76;74;74 17:54:48 INFO - PROCESS | 1960 | |10;stanford-crypto-aes;84;108;84;84;88;88;89;91;85;78 17:54:48 INFO - PROCESS | 1960 | |11;stanford-crypto-ccm;190;178;144;158;149;152;154;134;154;140 17:54:48 INFO - PROCESS | 1960 | |12;stanford-crypto-pbkdf2;202;233;164;231;224;169;170;218;231;168 17:54:48 INFO - PROCESS | 1960 | |13;stanford-crypto-sha256-iterative;66;70;69;71;83;85;64;65;84;62 17:54:48 INFO - PROCESS | 1960 | __end_tp_report 17:54:48 INFO - PROCESS | 1960 | __start_cc_report 17:54:48 INFO - PROCESS | 1960 | _x_x_mozilla_cycle_collect,1071 17:54:48 INFO - PROCESS | 1960 | __end_cc_report 17:54:48 INFO - PROCESS | 1960 | __startTimestamp1470704088495__endTimestamp 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | ------- Summary: start ------- 17:54:48 INFO - PROCESS | 1960 | Number of tests: 14 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#0] ai-astar Cycles:10 Average:114.80 Median:117.00 stddev:4.10 (3.5%) stddev-sans-first:3.54 17:54:48 INFO - PROCESS | 1960 | Values: 108.0 117.0 107.0 119.0 117.0 115.0 114.0 117.0 118.0 116.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#1] audio-beat-detection Cycles:10 Average:138.60 Median:144.00 stddev:27.17 (18.9%) stddev-sans-first:28.65 17:54:48 INFO - PROCESS | 1960 | Values: 147.0 116.0 174.0 182.0 109.0 136.0 141.0 111.0 160.0 110.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#2] audio-dft Cycles:10 Average:235.30 Median:237.50 stddev:30.74 (12.9%) stddev-sans-first:32.49 17:54:48 INFO - PROCESS | 1960 | Values: 228.0 285.0 213.0 234.0 287.0 243.0 241.0 223.0 200.0 199.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#3] audio-fft Cycles:10 Average:90.80 Median:70.00 stddev:39.03 (55.8%) stddev-sans-first:40.92 17:54:48 INFO - PROCESS | 1960 | Values: 74.0 105.0 70.0 147.0 76.0 58.0 53.0 70.0 171.0 84.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:71.50 stddev:21.55 (30.1%) stddev-sans-first:22.12 17:54:48 INFO - PROCESS | 1960 | Values: 70.0 72.0 119.0 72.0 71.0 77.0 111.0 70.0 73.0 119.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#5] imaging-gaussian-blur Cycles:10 Average:96.30 Median:89.50 stddev:15.31 (17.1%) stddev-sans-first:16.01 17:54:48 INFO - PROCESS | 1960 | Values: 89.0 88.0 91.0 87.0 90.0 87.0 91.0 90.0 121.0 129.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#6] imaging-darkroom Cycles:10 Average:118.70 Median:123.50 stddev:26.84 (21.7%) stddev-sans-first:25.79 17:54:48 INFO - PROCESS | 1960 | Values: 151.0 149.0 96.0 97.0 95.0 101.0 102.0 97.0 147.0 152.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:91.00 stddev:23.70 (26.0%) stddev-sans-first:24.55 17:54:48 INFO - PROCESS | 1960 | Values: 91.0 95.0 84.0 146.0 148.0 88.0 95.0 99.0 91.0 118.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#8] json-parse-financial Cycles:10 Average:57.30 Median:58.00 stddev:1.06 (1.8%) stddev-sans-first:1.09 17:54:48 INFO - PROCESS | 1960 | Values: 58.0 57.0 58.0 56.0 58.0 58.0 58.0 58.0 57.0 55.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#9] json-stringify-tinderbox Cycles:10 Average:74.10 Median:75.00 stddev:4.01 (5.3%) stddev-sans-first:3.33 17:54:48 INFO - PROCESS | 1960 | Values: 67.0 72.0 70.0 78.0 72.0 78.0 80.0 76.0 74.0 74.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#10] stanford-crypto-aes Cycles:10 Average:87.90 Median:86.50 stddev:7.94 (9.2%) stddev-sans-first:8.29 17:54:48 INFO - PROCESS | 1960 | Values: 84.0 108.0 84.0 84.0 88.0 88.0 89.0 91.0 85.0 78.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#11] stanford-crypto-ccm Cycles:10 Average:155.30 Median:154.00 stddev:16.98 (11.0%) stddev-sans-first:12.54 17:54:48 INFO - PROCESS | 1960 | Values: 190.0 178.0 144.0 158.0 149.0 152.0 154.0 134.0 154.0 140.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:201.00 Median:221.00 stddev:29.97 (13.6%) stddev-sans-first:31.79 17:54:48 INFO - PROCESS | 1960 | Values: 202.0 233.0 164.0 231.0 224.0 169.0 170.0 218.0 231.0 168.0 17:54:48 INFO - PROCESS | 1960 | 17:54:48 INFO - PROCESS | 1960 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.90 Median:70.50 stddev:8.80 (12.5%) stddev-sans-first:9.07 17:54:48 INFO - PROCESS | 1960 | Values: 66.0 70.0 69.0 71.0 83.0 85.0 64.0 65.0 84.0 62.0 17:54:48 INFO - PROCESS | 1960 | -------- Summary: end -------- 17:54:48 INFO - PROCESS | 1960 | 17:54:49 INFO - TEST-INFO | 1960: exit 0 17:54:49 INFO - TEST-OK | kraken | took 61649ms 17:54:49 INFO - SUITE-END | took 1176s 17:54:49 INFO - Completed test suite (00:19:36) 17:54:49 INFO - javascript benchmark 17:54:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7402.33751850255, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3215.646278909318, 3284.6386897441275, 3388.2545839915215, 3430.7598468107212, 3455.118944669109, 5704.914426283599, 5744.655320680762, 5805.651660900344, 5904.763809447622, 5913.467787899088, 4748.335233067373, 4859.829905953292, 5850.444207800263, 5874.823755287348, 5933.3473317934995, 4678.204705200107, 5052.848414547551, 5317.813876514323, 5397.946020539784, 5409.486098820606, 5435.728213589325, 5458.727063646822, 5495.8351249462385, 5506.366767821712, 5519.751611177507, 5245.554127899133, 5247.003069416837, 5310.468953104697, 5357.705326207057, 5404.243405923174, 5331.493508116722, 5345.705986170759, 5375.193720941851, 5398.865028374322, 5450.236966824647, 5218.634695571312, 5242.370915490127, 5299.231611416361, 5339.946600533984, 5348.679079255257, 5644.887102257973, 5680.630759000652, 5755.740991655385, 5811.4479124483105, 5857.970710146465, 6228.065790131472, 6247.625142491465, 6262.937370626327, 6270.498360131179, 6280.842978925563, 5170.405403378622, 6296.024116261987, 6313.084602732624, 6345.904811427832, 6461.0, 5808.215890854765, 5915.7633694652595, 5967.612105213234, 6003.6097653652805, 6020.367861374581, 6385.138006369174, 6404.647744374104, 6420.390062944013, 6425.389587989133, 6433.420992110732, 6186.381361863778, 6223.657698826518, 6278.46633036197, 6280.905786413207, 6295.464885484694, 6150.769846030812, 6183.319834818257, 6215.036669316263, 6270.153529273581, 6282.026285925689, 6252.499800016079, 6285.497160227171, 6288.937110628926, 6294.874102518015, 6316.178896743394, 6222.12890195373, 6228.688565571703, 6279.309275979638, 6302.149209856702, 6306.716197771111, 4914.36113305268, 5023.070731914608, 5041.445441001487, 5065.797368105308, 5151.871203219949, 4507.211238033331, 4537.546245375502, 4546.045330480636, 4568.383268258743, 4607.516210797885, 5776.422357764274, 5788.479036886616, 5887.4701276884425, 5910.674912879746, 5928.140419639214, 5463.262459567987, 6111.37903775109, 6208.348123447064, 6240.188775459162, 6256.468200203034, 5917.52659787216, 5918.674472903946, 5949.405059494016, 5963.075723262901, 5984.311804142492, 4790.880227994258, 5564.527015203753, 5565.443455654483, 5571.66570005801, 5575.968445837533, 6223.937760622336, 6374.681265936591, 6396.872062558816, 6465.935340646627, 6489.415952564197, 6281.309056003927, 6284.4344009039405, 6284.842878928063, 6301.4328710416285, 6311.526635502264, 6391.456726178327, 6457.741690332522, 6469.385408386196, 6480.513961452815, 6482.57863238888, 6130.398136741914, 6187.195664563578, 6188.350223226496, 6215.409536094064, 6219.533534984804, 5036.722980236123, 5343.732813359395, 6293.559450838397, 6295.433410993032, 6299.275583307978, 6261.154744109669, 6265.655388953469, 6279.623222606476, 6281.842953926189, 6291.748330066929, 5183.948160518346, 6144.477719393902, 6160.661163635864, 6166.321704612399, 6233.283172435046, 5198.584113271005, 5918.260217472816, 6114.715909658844, 6172.135900973777, 6202.193714817045, 6216.9877948095755, 6257.092721555258, 6279.686015699288, 6283.2460104787715, 6288.748450061947, 5568.0, 5956.672383018976, 6055.6063855849225, 6117.418845209698, 6140.140380346845], "value": 5754.201421585055, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.8058575105579, 488.1092007087067, 515.6673945305279, 536.4849744245524, 546.903458565576, 28289.858550707242, 34788.130296742595, 35169.59321627138, 42417.36373954393, 42460.0, 42934.57065429352, 43446.56553434473, 43515.564844351626, 43534.0, 43563.346549801776, 9543.618255269797, 9612.759681007961, 9700.708978730649, 9708.223342132613, 9779.46212958287, 8692.782680432976, 9258.351915365927, 9699.272554558402, 9738.561764720569, 9745.853812192823, 9720.125188733013, 9754.46350450725, 9758.951205243999, 9764.853527197061, 9773.120419162275, 9705.75735606612, 9716.757081072994, 9727.0, 9738.026197380275, 9762.658306959293, 9616.951915240448, 9673.177779888718, 9700.466474343908, 9706.951465242699, 9734.075262850018, 40918.1816363671, 41363.58636413657, 41485.585144148776, 41596.0, 42220.788896055325, 3106.3476669899123, 3322.0, 3381.746369022333, 3518.0501264658656, 3563.1092226943265, 30605.0, 31120.844395778102, 31349.529757053664, 31699.732010719545, 31913.680863191305, 3067.7239048485744, 3112.5331200319906, 3129.358481511285, 3129.749620030415, 3238.5789847319697, 449992.0, 451985.99999999674, 453098.0, 454804.0, 455671.0, 33687.66312336894, 33857.83071084629, 33876.49185262223, 33934.15164620855, 33934.66065339315, 34871.4769278461, 35527.822360888546, 35776.642233577855, 35979.64020359815, 36117.0, 16499.422520211974, 16540.83459165417, 16618.6676266474, 16728.83271167297, 16735.748963765553, 12590.685232869251, 12702.745945081047, 12707.555235566711, 12714.554990575285, 12735.617931462071, 19801.504962376057, 19998.90000549988, 20055.899720501304, 20062.996850157433, 20186.19255229804, 6905.654717264117, 6939.861202775916, 8038.397120216007, 8192.75421737349, 8235.011798584088, 868.0624925080898, 953.947532885684, 1015.8323876560432, 1060.7242117049468, 1072.6353039966448, 31183.532247016312, 31322.37355252882, 31386.21534461611, 31708.365832683216, 31889.362212755615, 1042.6142327338932, 1069.8181309177457, 1071.9571217151226, 1084.980118688446, 1157.7858096252053, 15993.1203783793, 16273.755893661604, 16358.83641163597, 16406.0, 16614.91692541554, 703511.0, 713971.0, 715178.9374743822, 715793.0, 716169.0, 53048.73475632675, 60494.0, 60916.78166436735, 61564.38435615676, 61627.6918615413, 1197.736497970445, 1339.450825161679, 1393.9684633371232, 1395.1210737235476, 1402.8246469191351, 30550.388992219585, 30835.229119272197, 30895.536566951963, 31519.36961260808, 31677.8416107918, 44079.0, 54287.45712542824, 54379.0, 54394.7280263612, 54558.727206363714, 603872.0, 606822.9658851854, 607376.0, 609996.0, 610478.0, 26723.599146012435, 28136.29659258535, 28156.71843281541, 28173.859130704215, 28227.294317642223, 49399.0, 49997.50002499928, 50460.49539504558, 50819.74590127174, 51617.48382516127, 40849.795751021054, 41903.952401188995, 42103.6970914228, 42418.0, 43068.56931430646, 34280.65719342874, 38729.6127038726, 41745.582544175384, 41818.95452613709, 42027.51527932542, 34769.826150870096, 39770.80687579377, 40525.0, 40572.79713601413, 40920.59079409168, 34413.65586344204, 36444.72443464466, 36584.73195453722, 36891.63108368882, 37266.8136659315, 26689.0, 26795.06217282386, 26887.327816804736, 26893.86553067222, 27098.869972566597], "value": 19789.993183072867, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [362.4237462434728, 370.137579439905, 375.1746158451407, 388.76673995602624, 397.0490674833773, 19177.42467725969, 20694.172233110658, 21996.780032199673, 22033.779662203375, 22183.44541386466, 20371.08330125144, 20637.690434643453, 20883.0, 21206.25778097767, 21337.466563335885, 5336.6531175473665, 5384.946150538493, 5402.216678581623, 5409.648372855771, 5488.835334939959, 3196.5205219217187, 4685.8594242172785, 5390.407055223922, 5415.756290966916, 5426.728663566825, 6050.092486127094, 6155.938440615604, 6156.722947467375, 6157.6305421674615, 6160.599561028519, 5661.2923384576725, 5971.910421343683, 6036.637801731867, 6146.0, 6170.7531698732, 5828.0, 5843.18195452637, 5898.793542226002, 5929.73316200772, 5945.256842894638, 24343.269701908972, 24957.00171993118, 24967.750322496726, 25216.243512694648, 25856.612150817753, 2290.541891621682, 2307.861528308307, 2670.612761149622, 3024.5916801231774, 3108.0675797260956, 9953.601855925754, 10094.343867648578, 13812.618738126208, 15133.394664213418, 16101.516954491384, 1904.381076150243, 1907.2943011085988, 1917.9232830686756, 1923.240320073563, 1972.339266345776, 107008.46495767549, 123720.0, 125240.37379813043, 125669.0, 125731.0, 11184.608538701106, 11587.768244635061, 11673.0, 11784.469698863571, 11952.64142075739, 14597.92701036488, 14765.0, 14775.556733298019, 14809.185494797674, 14816.40734370613, 7159.890217016371, 7767.92232077672, 8266.628001739937, 8547.401681882333, 8680.69617563395, 8396.79008024792, 8403.747887563382, 8426.0, 8456.02755683092, 8666.913330866737, 3536.9646303536633, 4614.976925115353, 4616.122936642095, 4676.602488788423, 4681.6020638245445, 1605.189379363414, 1918.4628304074881, 1958.2558627721376, 1980.7127966445073, 1983.097690550796, 811.7889348769362, 827.0447632983913, 853.6756032707656, 874.9850173798363, 928.5589345061042, 19141.61716765657, 21442.0, 21465.03407346673, 21554.784452155276, 21652.783472165393, 793.0126991895128, 812.858851377846, 873.9606717697719, 964.3394274921701, 1015.5125539740818, 11143.609973651046, 11528.884711152949, 11597.0, 11601.12991525639, 11629.709257268636, 566834.0, 585337.0, 617873.0, 620000.0, 630269.0, 137959.93060104107, 141270.0, 142299.0, 142650.2867485656, 144321.0, 828.3000864269683, 901.9549022548846, 962.2686758063921, 964.4406244378243, 988.7577543501889, 18718.906405467886, 19186.712199317302, 19212.903935480794, 19272.32546860853, 19319.323823666105, 54592.178547501295, 54669.72665136649, 55917.0, 56030.719846400505, 57829.0, 79375.60312198402, 79709.0, 82981.58509207415, 83852.0, 93620.0, 7126.536775109601, 7327.010853534704, 7483.962580187064, 7766.417518686234, 8385.454945428528, 10393.636222732168, 12304.108534494208, 12407.25556466615, 12505.249685018927, 13461.798073029107, 8813.25087367581, 11434.82847757301, 11532.019778318936, 11566.0, 11685.0, 11352.602658906899, 11459.942700286778, 11509.194356394972, 11644.35956022427, 11772.352520611448, 11612.88387116118, 11667.6499705009, 11949.940250298692, 12077.03383729282, 12168.93915530452, 8194.30348420367, 8204.056533498724, 8262.917370826215, 8391.370647201362, 8432.789180270542, 8466.365022623451, 9873.45695986699, 10947.781044379228, 11313.0, 11325.263857849213], "value": 9677.721075564414, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1495.7382458069837, 1531.4639876043382, 1733.566608347913, 1862.1527205121672, 1869.1308541528188, 7993.400494962886, 8475.279601233895, 8568.828623427527, 9018.233450156727, 9063.229625481834, 8661.740147795575, 8811.251043661281, 8858.689945851896, 8865.0, 8871.556422178897, 2332.3352844439382, 2355.3522781235124, 2364.47981444082, 2366.124533922451, 2369.7511761265077, 2324.3027091872455, 2331.347222777625, 2386.78518933296, 2388.34320561846, 2390.5099454611764, 2353.8940747666397, 2365.8698771567556, 2367.2188177901253, 2371.063429945171, 2388.4387169015267, 2345.566070277004, 2354.5290941811622, 2360.8583484990954, 2362.1024010875844, 2364.645303204516, 2264.6489794081945, 2330.93007209784, 2331.0, 2347.812175025994, 2370.8103351731993, 7916.247956444128, 8903.777405564848, 9033.141851524095, 9035.683751068747, 9046.0, 5090.592752579785, 5162.045021670965, 5428.4842939920645, 5440.156775699733, 5450.809221677222, 2825.9858700706573, 3149.2126968257935, 3566.946495802565, 3638.108663377463, 3685.870994515206, 812.4353574265888, 953.423178976716, 1077.1705786544317, 1079.266099052644, 1094.1684319916842, 11502.252353597072, 11814.763704725858, 11816.763664726743, 11838.171328007043, 11848.348340841336, 4021.939670904939, 4259.637930775919, 4350.977520282731, 4759.571638552477, 4881.926771098437, 10059.245556583286, 10062.849057264148, 10066.14437772783, 10077.891431942628, 10078.193744500422, 5333.2266821310695, 5349.384820745586, 5349.983503134392, 5357.089294819812, 5381.919271210935, 4457.598816106566, 4794.400699912511, 4861.927071093936, 4920.852374428773, 4953.876153096201, 3982.761034337949, 4020.0552870075508, 4089.8568550100604, 4101.507819061731, 4133.793310334471, 1086.5002099034375, 1227.6930767308172, 1259.6850787303174, 1269.847618285793, 1280.8783165599252, 810.8986376702912, 912.2519533982204, 918.7381596245039, 929.340168480373, 950.3395140377371, 5371.187036294604, 5518.66887986714, 5846.41535846412, 6128.908066379008, 6243.594166379171, 436.46968932746864, 462.98611041668806, 463.19404236628026, 953.5708930981086, 1034.3535290443472, 3969.106950936074, 4007.178528401729, 4080.510613626005, 4150.543440221573, 4162.41726158338, 10811.729706757395, 11027.117830573536, 11056.557737690402, 11066.833997490045, 11101.944490277498, 10901.727456813485, 10981.6156434526, 11020.338779673246, 11035.668929932115, 11111.055560277306, 7854.60726963661, 8169.468984515987, 8385.958070209732, 8477.025142108736, 8489.787755306166, 5985.191999080069, 6037.426444487766, 6195.845103872439, 6198.81403557894, 6209.0996805462055, 9639.951800240953, 10048.346857454242, 10176.982301769764, 10199.235057370875, 10271.486425678835, 10235.641752538626, 10426.739331516772, 10430.0, 10430.791384172426, 10462.68611941643, 6990.650467476504, 7380.889286660597, 7548.962255188689, 7556.8488630228185, 7735.9613201933635, 8443.240108390275, 8499.022612399653, 8531.786705332417, 8557.272631826116, 8579.82840343202, 6604.339566043357, 7541.962290188514, 7709.573728860179, 7735.922640773745, 7806.609669516388, 7527.473076884785, 7559.28186822251, 7579.848403032019, 7699.26856948589, 7782.571958542335, 7119.181294151032, 7187.0, 7192.568445893263, 7195.712171513081, 7199.3520583147765, 4232.894177645583, 4966.6771659842, 5142.452416104842, 5158.871028224325, 5172.043172013189, 4509.143262780061, 5047.974760126176, 5180.559652429435, 5188.377394712658, 5270.578353731616], "value": 4575.899638235108, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [274.27727936886305, 297.17830199498377, 307.5832247304902, 313.5030975903193, 317.04569246567826, 5541.667499950001, 5550.222968784368, 5584.86037849054, 5592.916106258399, 5619.690916999563, 4729.668923175379, 5441.483059109388, 5463.562914966803, 5499.1476321170185, 5575.386707462176, 3785.4889589905288, 3927.6857851371965, 3968.72218944674, 4083.7549747015273, 4246.0, 3633.36416127178, 4178.206140833247, 4178.26880295947, 4199.45407097077, 4202.97898510507, 3695.020819482839, 3889.7860617666015, 4143.544210136897, 4183.226103170923, 4189.601987811153, 3632.4732913727494, 3846.807659617036, 4121.505419349667, 4156.210320039183, 4204.474440694913, 3154.511050787132, 3582.6775590197008, 3588.8923332300074, 3593.82030898454, 3594.856205751767, 4567.018091110427, 5021.497850214986, 5419.99730049942, 5423.674579525241, 5501.174823776466, 4563.740948230614, 4770.212914869043, 4779.0, 4795.537361104862, 4805.75971201442, 4816.831410900637, 5813.7383817728305, 5834.870272350604, 5853.619514731528, 5901.645901245896, 3974.4038394240806, 3979.422983667352, 3981.6416522512823, 3982.3628219484744, 4029.194161167749, 4885.581637089628, 5151.407588127376, 6158.230221222392, 6176.567640265183, 6199.791040747056, 3372.6795954384293, 3499.61504234534, 4028.617281358266, 4043.3934909763184, 4044.4944381952255, 3459.7578169528397, 3591.581325376462, 3879.127196380848, 4088.2232375848494, 4102.179564087194, 4022.5977402260123, 4277.401163837066, 4786.540105267857, 4812.614990800727, 4819.228923372244, 4847.733374664427, 5622.746976386073, 5867.442592953663, 5879.235699359056, 5897.67562784051, 1130.7908037013178, 1362.9182249065088, 1387.341013018808, 1395.8325000999946, 1405.8242719660043, 800.5916982339049, 834.964643841632, 842.8230071685015, 845.4124383553494, 852.953087580189, 4198.929273035356, 4985.925211121836, 4991.377802214295, 5434.456554344613, 5439.456054394608, 5116.104681680691, 5281.098804430402, 6003.189569408161, 6138.232720909887, 6176.382361763787, 4535.342375355555, 4968.677035992649, 5024.0, 5098.425295225961, 5457.471907865804, 2508.0, 2752.9174124776287, 3172.8254945977733, 3189.6331921829287, 3196.0252123102214, 5477.589180811376, 5754.798582049608, 6221.160143380676, 6233.226214334578, 6271.749130034747, 4935.851924442272, 5241.842744717588, 5801.3038435388025, 5959.332794298158, 6085.786997455157, 6149.5080393568405, 6233.0, 6265.778173256172, 6312.684365781784, 6325.399087086719, 5565.359983601776, 6023.488003519575, 6055.939440605537, 6090.0, 6098.451139397475, 5877.4122587740885, 5905.5570832186895, 5993.790217342372, 6037.426444487766, 6057.666828324308, 6095.542834287357, 6283.449850834264, 6300.243970723543, 6351.237851457855, 6364.358791101641, 3971.2454633620673, 3974.821133048962, 3997.7618129315347, 3999.500187429714, 4015.7271823463784, 6048.788292409745, 6051.33435322123, 6063.575549711478, 6068.180795592715, 6094.969525152524, 4850.569082120854, 5899.144624029577, 5916.763329466773, 5926.799528254866, 5947.672877991579, 5011.9498805012945, 5822.10781496007, 5835.94164058371, 5871.295444546682, 5887.264091988502, 5074.391073071255, 5756.913646295226, 5769.509591684754, 5806.332271788629, 5832.0, 4556.724117247176, 5334.75993580282, 5844.035734103995, 5917.260342457193, 5953.464188223081, 5750.5112065474905, 5794.304683438015, 5813.331466881363, 5814.912776308443, 5882.323532793613], "value": 4215.112024079118, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.9247682754899, 717.4475653746618, 725.9782206533804, 774.6204359863668, 801.9077806052302, 15108.47120350788, 15697.529074127795, 15873.126978016204, 15874.444394446196, 15895.443659471906, 14132.293385330744, 14258.574142585736, 14382.0, 14383.0, 14386.568402947929, 845.0873057098349, 869.9995005743383, 870.2341939093589, 875.3522393428849, 877.0265005843509, 678.8268991407184, 841.0748177005306, 855.0167307596283, 866.2160744526183, 877.8288233794412, 912.5893347993397, 917.2661870503604, 917.9265658747318, 921.3964853021248, 927.7077720518015, 866.4627930682954, 875.5359659380539, 890.9020007799137, 908.52756566585, 909.1136142261308, 647.6502688548178, 658.6509150150428, 678.7895752316798, 682.5051837417832, 683.4839696029516, 14285.57143285703, 14475.493357732534, 14831.258437078102, 14835.0, 14850.034747741362, 12712.936435317857, 13777.448902043907, 14730.63173420673, 14811.037282576597, 15132.773008404884, 12013.099017573717, 12089.697757556043, 12207.328596927166, 12301.138920275584, 12650.873491265062, 3821.14024344523, 3867.9806600967095, 3868.574456809749, 3872.2255548890334, 3876.0697432616253, 80480.59759701222, 98501.5224771629, 114975.0, 116354.41822790916, 118778.0, 10080.697579072494, 11255.718607034725, 11402.94298528502, 11419.257748246337, 11559.422028898689, 23023.0, 23513.88243058774, 23529.7647023531, 23558.293251202493, 23766.28701138934, 8188.222118898695, 8242.29940455056, 8243.876341854877, 8289.212524810133, 8311.418200725891, 3955.841766329372, 4501.549845015537, 4530.093981203707, 4575.702579332333, 4772.308015337757, 2899.3331533746937, 3555.484454754098, 3622.5290712207243, 3645.3620616392027, 3667.009907325007, 651.0657206908102, 657.3557913245047, 666.7166454256921, 670.6948338506065, 680.0818894492396, 657.2080642825384, 663.741140955028, 665.2084020016165, 685.5269863794066, 686.2417029182781, 9013.72562681024, 9387.0, 9500.19248363883, 9574.0, 9914.45492432555, 535.4859335038329, 676.8815457294953, 677.9796406408423, 685.4858855858106, 690.1717938473752, 4300.80646370914, 4707.7175369477945, 4722.791588706187, 4812.181929072066, 4885.951140488621, 132837.33581332033, 137389.3130534341, 147214.0, 163878.0, 167965.3203467974, 122623.70817021362, 125378.0, 127750.36124819503, 127916.0, 133265.66734332533, 30227.0, 30616.846915765724, 30677.386452270828, 32250.83874580659, 115731.0, 9269.860952085859, 9334.486603236886, 9384.718458446258, 9404.153626173676, 9518.714438566854, 60485.0, 60773.696131520825, 62908.68545657242, 64357.0, 64529.35470645234, 83623.0, 85221.0, 86249.0, 95573.5221323889, 95641.08717825466, 13447.731045379232, 13675.452981880613, 13731.862681373457, 13924.58226253214, 14195.077319974582, 12722.427490762753, 14257.786133208218, 14797.7235804772, 15340.463083792014, 15433.382664693736, 11024.50389732448, 14077.507287245307, 14160.150390976574, 14352.928235358757, 14637.341319640429, 13736.175829450265, 14075.648108797363, 14081.225532595805, 14117.082389645051, 14151.929240353733, 11462.656120316404, 13477.249983819287, 13643.249621270528, 13807.309634518435, 14346.0, 11279.436028198394, 11306.660800176009, 11376.715582110513, 11550.133739969368, 11795.174337796616, 11373.772524549628, 11657.358845263592, 11792.52829886795, 11811.291322520676, 11821.763564728486], "value": 7738.905773040372, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1632.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 117.0, 107.0, 119.0, 117.0, 115.0, 114.0, 117.0, 118.0, 116.0], "value": 114.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 116.0, 174.0, 182.0, 109.0, 136.0, 141.0, 111.0, 160.0, 110.0], "value": 138.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [228.0, 285.0, 213.0, 234.0, 287.0, 243.0, 241.0, 223.0, 200.0, 199.0], "value": 235.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 105.0, 70.0, 147.0, 76.0, 58.0, 53.0, 70.0, 171.0, 84.0], "value": 90.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 72.0, 119.0, 72.0, 71.0, 77.0, 111.0, 70.0, 73.0, 119.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 91.0, 87.0, 90.0, 87.0, 91.0, 90.0, 121.0, 129.0], "value": 96.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 149.0, 96.0, 97.0, 95.0, 101.0, 102.0, 97.0, 147.0, 152.0], "value": 118.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 95.0, 84.0, 146.0, 148.0, 88.0, 95.0, 99.0, 91.0, 118.0], "value": 105.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 58.0, 56.0, 58.0, 58.0, 58.0, 58.0, 57.0, 55.0], "value": 57.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 70.0, 78.0, 72.0, 78.0, 80.0, 76.0, 74.0, 74.0], "value": 74.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 108.0, 84.0, 84.0, 88.0, 88.0, 89.0, 91.0, 85.0, 78.0], "value": 87.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [190.0, 178.0, 144.0, 158.0, 149.0, 152.0, 154.0, 134.0, 154.0, 140.0], "value": 155.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [202.0, 233.0, 164.0, 231.0, 224.0, 169.0, 170.0, 218.0, 231.0, 168.0], "value": 201.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 71.0, 83.0, 85.0, 64.0, 65.0, 84.0, 62.0], "value": 71.9, "unit": "score"}]}]} 17:54:49 INFO - Return code: 0 17:54:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 17:54:49 ERROR - # TBPL SUCCESS # 17:54:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 17:54:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 17:54:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 17:54:49 INFO - 2016-08-08 17:54:49.800 screenresolution[1965:68178] starting screenresolution argv=screenresolution get 17:54:49 INFO - 2016-08-08 17:54:49.803 screenresolution[1965:68178] Display 0: 1600x1200x32@60 17:54:49 INFO - 2016-08-08 17:54:49.810 screenresolution[1966:68181] starting screenresolution argv=screenresolution list 17:54:49 INFO - Available Modes on Display 0 17:54:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 17:54:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 17:54:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 17:54:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 17:54:50 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 17:54:50 INFO - Intel Iris: 17:54:50 INFO - Chipset Model: Intel Iris 17:54:50 INFO - Type: GPU 17:54:50 INFO - Bus: Built-In 17:54:50 INFO - VRAM (Dynamic, Max): 1536 MB 17:54:50 INFO - Vendor: Intel (0x8086) 17:54:50 INFO - Device ID: 0x0a2e 17:54:50 INFO - Revision ID: 0x0009 17:54:50 INFO - Displays: 17:54:50 INFO - Display: 17:54:50 INFO - Resolution: 1600 x 1200 @ 60 Hz 17:54:50 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 17:54:50 INFO - Display Serial Number: 1600x1200 60 17:54:50 INFO - Main Display: Yes 17:54:50 INFO - Mirror: Off 17:54:50 INFO - Online: Yes 17:54:50 INFO - Rotation: Supported 17:54:50 INFO - Return code: 0 17:54:50 INFO - Running post-action listener: _resource_record_post_action 17:54:50 INFO - [mozharness: 2016-08-09 00:54:50.048228Z] Finished run-tests step (success) 17:54:50 INFO - Running post-run listener: _resource_record_post_run 17:54:50 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 28672; Write bytes: 709280256; Read time: 2; Write time: 2190 17:54:50 INFO - TinderboxPrint: CPU usage
54.3% 17:54:50 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 2 17:54:50 INFO - TinderboxPrint: I/O write bytes / time
709,280,256 / 2,190 17:54:50 INFO - TinderboxPrint: CPU idle
2,214.3 (45.7%) 17:54:50 INFO - TinderboxPrint: CPU system
985.5 (20.3%) 17:54:50 INFO - TinderboxPrint: CPU user
1,649.9 (34.0%) 17:54:50 INFO - TinderboxPrint: Swap in / out
406,462,464 / 0 17:54:50 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 389917184; Write bytes: 385398784; Read time: 29202; Write time: 729 17:54:50 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 28672; Write bytes: 298560000; Read time: 2; Write time: 1411 17:54:50 INFO - Running post-run listener: _upload_blobber_files 17:54:50 INFO - Blob upload gear active. 17:54:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 17:54:50 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 17:54:50 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 17:54:50 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 17:54:50 INFO - (blobuploader) - INFO - Open directory for files ... 17:54:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 17:54:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 17:54:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:51 INFO - (blobuploader) - INFO - Done attempting. 17:54:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 17:54:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 17:54:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:52 INFO - (blobuploader) - INFO - Done attempting. 17:54:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 17:54:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 17:54:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 17:54:54 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 17:54:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 17:54:54 INFO - (blobuploader) - INFO - Done attempting. 17:54:54 INFO - (blobuploader) - INFO - Iteration through files over. 17:54:54 INFO - Return code: 0 17:54:54 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 17:54:54 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 17:54:54 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a57d275ccdd155bf8095900f369b9adb09c4311ff93c3aa57ec46e2dd91fa342ee8b47c06b9b3205556411d0f2b5ec0a9a518f14a8ec397d52fec874dfd66cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b181c34a845b682e6b6da401dc8c490c4a0e445bff491de8c4ad760a60bc883b469cd2347f899a02428964df962272833cbf3ec748d15860c0a7711d084cd77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20e904458a79f5f998a933a1388b5940103d27a54e985f81bd51a9b8880bf6b2cadc7ed3ab6d4497ed8d7fccd0325fd426a862e0331a8f92039503c93be8c2dc"} 17:54:54 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 17:54:54 INFO - Writing to file /builds/slave/test/properties/blobber_files 17:54:54 INFO - Contents: 17:54:54 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a57d275ccdd155bf8095900f369b9adb09c4311ff93c3aa57ec46e2dd91fa342ee8b47c06b9b3205556411d0f2b5ec0a9a518f14a8ec397d52fec874dfd66cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b181c34a845b682e6b6da401dc8c490c4a0e445bff491de8c4ad760a60bc883b469cd2347f899a02428964df962272833cbf3ec748d15860c0a7711d084cd77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20e904458a79f5f998a933a1388b5940103d27a54e985f81bd51a9b8880bf6b2cadc7ed3ab6d4497ed8d7fccd0325fd426a862e0331a8f92039503c93be8c2dc"} 17:54:54 INFO - Running post-run listener: copy_logs_to_upload_dir 17:54:54 INFO - Copying logs to upload dir... 17:54:54 INFO - mkdir: /builds/slave/test/build/upload/logs 17:54:54 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1267.523903 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 7 secs) (at 2016-08-08 17:54:54.823450) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 17:54:54.827015) ========= 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.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a57d275ccdd155bf8095900f369b9adb09c4311ff93c3aa57ec46e2dd91fa342ee8b47c06b9b3205556411d0f2b5ec0a9a518f14a8ec397d52fec874dfd66cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b181c34a845b682e6b6da401dc8c490c4a0e445bff491de8c4ad760a60bc883b469cd2347f899a02428964df962272833cbf3ec748d15860c0a7711d084cd77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20e904458a79f5f998a933a1388b5940103d27a54e985f81bd51a9b8880bf6b2cadc7ed3ab6d4497ed8d7fccd0325fd426a862e0331a8f92039503c93be8c2dc"} build_url:https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011536 build_url: 'https://queue.taskcluster.net/v1/task/cwncTOVsQoKa2C-MmmcSfA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a57d275ccdd155bf8095900f369b9adb09c4311ff93c3aa57ec46e2dd91fa342ee8b47c06b9b3205556411d0f2b5ec0a9a518f14a8ec397d52fec874dfd66cdb", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0b181c34a845b682e6b6da401dc8c490c4a0e445bff491de8c4ad760a60bc883b469cd2347f899a02428964df962272833cbf3ec748d15860c0a7711d084cd77", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/20e904458a79f5f998a933a1388b5940103d27a54e985f81bd51a9b8880bf6b2cadc7ed3ab6d4497ed8d7fccd0325fd426a862e0331a8f92039503c93be8c2dc"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-08 17:54:54.860183) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 17:54:54.860544) ========= 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.CnkabucJp7/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.4klplnEt9u/Listeners TMPDIR=/var/folders/dm/htz3_8912qn7fxnzm0b7jt8800000w/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.005234 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-08 17:54:54.909652) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 17:54:54.910008) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-08 17:54:54.910344) ========= ========= Total master_lag: 0.76 =========