builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0355 starttime: 1472150419.92 results: success (0) buildid: 20160825082226 builduid: c3610b1543a54974aa1d9c979396d5d4 revision: c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:19.921287) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:19.921722) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:19.921998) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:19.946954) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:19.947222) ========= 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.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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-25 11:40:19-- 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% 147M=0s 2016-08-25 11:40:20 (147 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.097438 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:20.067395) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:20.067716) ========= 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.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.026118 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:20.116000) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 11:40:20.116307) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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-25 11:40:20,196 truncating revision to first 12 chars 2016-08-25 11:40:20,196 Setting DEBUG logging. 2016-08-25 11:40:20,196 attempt 1/10 2016-08-25 11:40:20,196 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/c077d5d5be1f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-25 11:40:21,436 unpacking tar archive at: larch-c077d5d5be1f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.496035 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-25 11:40:21.634532) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:21.634866) ========= script_repo_revision: c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:21.635256) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:21.635523) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-25 11:40:21.653979) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-25 11:40:21.654244) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:40:21 INFO - MultiFileLogger online at 20160825 11:40:21 in /builds/slave/test 11:40:21 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 11:40:21 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 11:40:21 INFO - {'append_to_log': False, 11:40:21 INFO - 'base_work_dir': '/builds/slave/test', 11:40:21 INFO - 'blob_upload_branch': 'Larch', 11:40:21 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 11:40:21 INFO - 'branch': 'Larch', 11:40:21 INFO - 'buildbot_json_path': 'buildprops.json', 11:40:21 INFO - 'config_files': ('talos/mac_config.py',), 11:40:21 INFO - 'default_actions': ('clobber', 11:40:21 INFO - 'read-buildbot-config', 11:40:21 INFO - 'download-and-extract', 11:40:21 INFO - 'populate-webroot', 11:40:21 INFO - 'create-virtualenv', 11:40:21 INFO - 'install', 11:40:21 INFO - 'run-tests'), 11:40:21 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 11:40:21 INFO - 'download_minidump_stackwalk': True, 11:40:21 INFO - 'download_symbols': 'ondemand', 11:40:21 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 11:40:21 INFO - 'tooltool.py': '/tools/tooltool.py', 11:40:21 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 11:40:21 INFO - '/tools/misc-python/virtualenv.py')}, 11:40:21 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 11:40:21 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 11:40:21 INFO - 'installer_path': 'installer.exe', 11:40:21 INFO - 'log_level': 'info', 11:40:21 INFO - 'log_name': 'talos', 11:40:21 INFO - 'log_to_console': True, 11:40:21 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 11:40:21 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 11:40:21 INFO - 'opt_config_files': (), 11:40:21 INFO - 'pip_index': False, 11:40:21 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:40:21 INFO - 'cmd': ('bash', 11:40:21 INFO - '-c', 11:40:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:40:21 INFO - 'enabled': True, 11:40:21 INFO - 'halt_on_failure': False, 11:40:21 INFO - 'name': 'check_screen_resolution'},), 11:40:21 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 11:40:21 INFO - 'cmd': ('bash', 11:40:21 INFO - '-c', 11:40:21 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 11:40:21 INFO - 'enabled': True, 11:40:21 INFO - 'halt_on_failure': False, 11:40:21 INFO - 'name': 'check_screen_resolution'},), 11:40:21 INFO - 'run_cmd_checks_enabled': True, 11:40:21 INFO - 'sps_profile': False, 11:40:21 INFO - 'sps_profile_interval': 0, 11:40:21 INFO - 'suite': 'dromaeojs', 11:40:21 INFO - 'system_bits': '32', 11:40:21 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 11:40:21 INFO - 'title': 't-yosemite-r7-0355', 11:40:21 INFO - 'tooltool_cache': '/builds/tooltool_cache', 11:40:21 INFO - 'use_talos_json': True, 11:40:21 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 11:40:21 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 11:40:21 INFO - 'work_dir': 'build'} 11:40:21 INFO - [mozharness: 2016-08-25 18:40:21.852150Z] Running clobber step. 11:40:21 INFO - Running pre-action listener: _resource_record_pre_action 11:40:21 INFO - Running main action method: clobber 11:40:21 INFO - rmtree: /builds/slave/test/build 11:40:21 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 11:40:23 INFO - Running post-action listener: _resource_record_post_action 11:40:23 INFO - [mozharness: 2016-08-25 18:40:23.921572Z] Finished clobber step (success) 11:40:23 INFO - [mozharness: 2016-08-25 18:40:23.921752Z] Running read-buildbot-config step. 11:40:23 INFO - Running pre-action listener: _resource_record_pre_action 11:40:23 INFO - Running main action method: read_buildbot_config 11:40:23 INFO - Using buildbot properties: 11:40:23 INFO - { 11:40:23 INFO - "project": "", 11:40:23 INFO - "product": "firefox", 11:40:23 INFO - "script_repo_revision": "production", 11:40:23 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 11:40:23 INFO - "repository": "", 11:40:23 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 11:40:23 INFO - "buildid": "20160825082226", 11:40:23 INFO - "pgo_build": "False", 11:40:23 INFO - "basedir": "/builds/slave/test", 11:40:23 INFO - "buildnumber": 1, 11:40:23 INFO - "slavename": "t-yosemite-r7-0355", 11:40:23 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 11:40:23 INFO - "platform": "yosemite_r7", 11:40:23 INFO - "scheduler": "tests-larch-macosx64-talos", 11:40:23 INFO - "branch": "larch", 11:40:23 INFO - "repo_path": "projects/larch", 11:40:23 INFO - "revision": "c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b", 11:40:23 INFO - "stage_platform": "macosx64", 11:40:23 INFO - "builduid": "c3610b1543a54974aa1d9c979396d5d4", 11:40:23 INFO - "slavebuilddir": "test" 11:40:23 INFO - } 11:40:23 INFO - Found installer url https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 11:40:23 INFO - Running post-action listener: _resource_record_post_action 11:40:23 INFO - [mozharness: 2016-08-25 18:40:23.927389Z] Finished read-buildbot-config step (success) 11:40:23 INFO - [mozharness: 2016-08-25 18:40:23.927624Z] Running download-and-extract step. 11:40:23 INFO - Running pre-action listener: _resource_record_pre_action 11:40:23 INFO - Running main action method: download_and_extract 11:40:23 INFO - mkdir: /builds/slave/test/build/tests 11:40:23 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')]} 11:40:23 INFO - trying https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 11:40:23 INFO - Downloading https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:25 INFO - Downloaded 1199 bytes. 11:40:25 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 11:40:25 INFO - Using the following test package requirements: 11:40:25 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:40:25 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 11:40:25 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 11:40:25 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'jsshell-mac.zip'], 11:40:25 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 11:40:25 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 11:40:25 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 11:40:25 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 11:40:25 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 11:40:25 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 11:40:25 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 11:40:25 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 11:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:25 INFO - trying https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:40:25 INFO - Downloading https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:26 INFO - Downloaded 16352371 bytes. 11:40:26 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:40:32 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 11:40:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:32 INFO - trying https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 11:40:32 INFO - Downloading https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:33 INFO - Downloaded 16352371 bytes. 11:40:33 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 11:40:40 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')]} 11:40:40 INFO - trying https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 11:40:40 INFO - Downloading https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:41 INFO - Downloaded 14413981 bytes. 11:40:41 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 11:40:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:41 INFO - trying https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:40:41 INFO - Downloading https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 11:40:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/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 11:40:44 INFO - Downloaded 111541813 bytes. 11:40:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:40:44 INFO - mkdir: /builds/slave/test/properties 11:40:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 11:40:44 INFO - Writing to file /builds/slave/test/properties/build_url 11:40:44 INFO - Contents: 11:40:44 INFO - build_url:https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 11:40:45 INFO - Running post-action listener: _resource_record_post_action 11:40:45 INFO - Running post-action listener: set_extra_try_arguments 11:40:45 INFO - [mozharness: 2016-08-25 18:40:45.468307Z] Finished download-and-extract step (success) 11:40:45 INFO - [mozharness: 2016-08-25 18:40:45.468441Z] Running populate-webroot step. 11:40:45 INFO - Running pre-action listener: _resource_record_pre_action 11:40:45 INFO - Running main action method: populate_webroot 11:40:45 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 11:40:45 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 11:40:45 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 11:40:45 INFO - '--tppagecycles', 11:40:45 INFO - '7'], 11:40:45 INFO - 'tests': ['tsvgm']}}, 11:40:45 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['tresize', 'tcanvasmark']}, 11:40:45 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 11:40:45 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['dromaeo_css', 'kraken']}, 11:40:45 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 11:40:45 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:40:45 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 11:40:45 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['damp', 'tps']}, 11:40:45 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'tests': ['damp', 'tps']}, 11:40:45 INFO - 'g3': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['dromaeo_dom']}, 11:40:45 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 11:40:45 INFO - 'g4': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['basic_compositor_video']}, 11:40:45 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 11:40:45 INFO - 'other': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'other-e10s': {'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'other-e10s_l64': {'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['a11yr', 11:40:45 INFO - 'ts_paint', 11:40:45 INFO - 'tpaint', 11:40:45 INFO - 'sessionrestore', 11:40:45 INFO - 'sessionrestore_no_auto_restore', 11:40:45 INFO - 'tabpaint']}, 11:40:45 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['tsvgx', 11:40:45 INFO - 'tsvgr_opacity', 11:40:45 INFO - 'tart', 11:40:45 INFO - 'tscrollx', 11:40:45 INFO - 'cart']}, 11:40:45 INFO - 'svgr-e10s': {'tests': ['tsvgx', 11:40:45 INFO - 'tsvgr_opacity', 11:40:45 INFO - 'tart', 11:40:45 INFO - 'tscrollx', 11:40:45 INFO - 'cart']}, 11:40:45 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'talos_options': ['--disable-e10s'], 11:40:45 INFO - 'tests': ['tp5o']}, 11:40:45 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'tests': ['tp5o']}, 11:40:45 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'talos_options': ['--disable-e10s', 11:40:45 INFO - '--xperf_path', 11:40:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:40:45 INFO - 'tests': ['tp5n']}, 11:40:45 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 11:40:45 INFO - 'talos_options': ['--xperf_path', 11:40:45 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 11:40:45 INFO - 'tests': ['tp5n']}}, 11:40:45 INFO - 'talos.zip': {'path': '', 11:40:45 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 11:40:45 INFO - Running post-action listener: _resource_record_post_action 11:40:45 INFO - [mozharness: 2016-08-25 18:40:45.487917Z] Finished populate-webroot step (success) 11:40:45 INFO - [mozharness: 2016-08-25 18:40:45.488051Z] Running create-virtualenv step. 11:40:45 INFO - Running pre-action listener: _resource_record_pre_action 11:40:45 INFO - Running main action method: create_virtualenv 11:40:45 INFO - Creating virtualenv /builds/slave/test/build/venv 11:40:45 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 11:40:45 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 11:40:45 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 11:40:45 INFO - Using real prefix '/tools/python27' 11:40:45 INFO - New python executable in /builds/slave/test/build/venv/bin/python 11:40:46 INFO - Installing distribute.............................................................................................................................................................................................done. 11:40:49 INFO - Installing pip.................done. 11:40:49 INFO - Return code: 0 11:40:49 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 11:40: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')]} 11:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40: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')]} 11:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:49 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:40:49 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 11:40:49 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 11:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:49 INFO - 'HOME': '/Users/cltbld', 11:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:49 INFO - 'LOGNAME': 'cltbld', 11:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:49 INFO - 'MOZ_NO_REMOTE': '1', 11:40:49 INFO - 'NO_EM_RESTART': '1', 11:40:49 INFO - 'PAGER': '/bin/cat', 11:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:49 INFO - 'PWD': '/builds/slave/test', 11:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:49 INFO - 'SHELL': '/bin/bash', 11:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:40:49 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:49 INFO - 'USER': 'cltbld', 11:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:49 INFO - 'XPC_FLAGS': '0x0', 11:40:49 INFO - 'XPC_SERVICE_NAME': '0', 11:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:49 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 11:40:49 INFO - Cleaning up... 11:40:49 INFO - Return code: 0 11:40:49 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 11:40: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')]} 11:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40: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')]} 11:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:49 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:40:49 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 11:40:49 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 11:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:49 INFO - 'HOME': '/Users/cltbld', 11:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:49 INFO - 'LOGNAME': 'cltbld', 11:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:49 INFO - 'MOZ_NO_REMOTE': '1', 11:40:49 INFO - 'NO_EM_RESTART': '1', 11:40:49 INFO - 'PAGER': '/bin/cat', 11:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:49 INFO - 'PWD': '/builds/slave/test', 11:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:49 INFO - 'SHELL': '/bin/bash', 11:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:40:49 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:49 INFO - 'USER': 'cltbld', 11:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:49 INFO - 'XPC_FLAGS': '0x0', 11:40:49 INFO - 'XPC_SERVICE_NAME': '0', 11:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:49 INFO - Downloading/unpacking psutil>=3.1.1 11:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 11:40:52 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:40:52 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:40:52 INFO - Installing collected packages: psutil 11:40:52 INFO - Running setup.py install for psutil 11:40:52 INFO - building 'psutil._psutil_osx' extension 11:40:52 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 11:40:53 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 11:40:53 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 11:40:53 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 11:40:53 INFO - building 'psutil._psutil_posix' extension 11:40:53 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 11:40:53 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 11:40:53 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 11:40:53 INFO - ^ 11:40:53 INFO - 1 warning generated. 11:40:53 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 11:40:53 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 11:40:53 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 11:40:53 INFO - Successfully installed psutil 11:40:53 INFO - Cleaning up... 11:40:54 INFO - Return code: 0 11:40:54 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 11:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:40:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:54 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:40:54 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 11:40:54 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 11:40:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:40:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:54 INFO - 'HOME': '/Users/cltbld', 11:40:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:54 INFO - 'LOGNAME': 'cltbld', 11:40:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:54 INFO - 'MOZ_NO_REMOTE': '1', 11:40:54 INFO - 'NO_EM_RESTART': '1', 11:40:54 INFO - 'PAGER': '/bin/cat', 11:40:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:54 INFO - 'PWD': '/builds/slave/test', 11:40:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:54 INFO - 'SHELL': '/bin/bash', 11:40:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:40:54 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:40:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:54 INFO - 'USER': 'cltbld', 11:40:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:54 INFO - 'XPC_FLAGS': '0x0', 11:40:54 INFO - 'XPC_SERVICE_NAME': '0', 11:40:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:54 INFO - Downloading/unpacking mozsystemmonitor==0.3 11:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:40:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:40:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:40:56 INFO - Downloading mozsystemmonitor-0.3.tar.gz 11:40:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 11:40:56 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 11:40:56 INFO - Installing collected packages: mozsystemmonitor 11:40:56 INFO - Running setup.py install for mozsystemmonitor 11:40:56 INFO - Successfully installed mozsystemmonitor 11:40:56 INFO - Cleaning up... 11:40:57 INFO - Return code: 0 11:40:57 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 11:40:57 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')]} 11:40:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:40:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:57 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')]} 11:40:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:40:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:40:57 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:40:57 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 11:40:57 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 11:40:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:40:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:40:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:40:57 INFO - 'HOME': '/Users/cltbld', 11:40:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:40:57 INFO - 'LOGNAME': 'cltbld', 11:40:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:40:57 INFO - 'MOZ_NO_REMOTE': '1', 11:40:57 INFO - 'NO_EM_RESTART': '1', 11:40:57 INFO - 'PAGER': '/bin/cat', 11:40:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:40:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:40:57 INFO - 'PWD': '/builds/slave/test', 11:40:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:40:57 INFO - 'SHELL': '/bin/bash', 11:40:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:40:57 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:40:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:40:57 INFO - 'USER': 'cltbld', 11:40:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:40:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:40:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:40:57 INFO - 'XPC_FLAGS': '0x0', 11:40:57 INFO - 'XPC_SERVICE_NAME': '0', 11:40:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:40:57 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:40:57 INFO - Downloading/unpacking jsonschema==2.5.1 11:40:57 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 11:40:57 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 11:40:57 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 11:40:57 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 11:40:57 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 11:40:57 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 11:41:00 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 11:41:00 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 11:41:00 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 11:41:00 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 11:41:00 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 11:41:00 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 11:41:00 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 11:41:00 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 11:41:01 INFO - Downloading functools32-3.2.3-2.tar.gz 11:41:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 11:41:01 INFO - warning: no files found matching '*.txt' 11:41:01 INFO - no previously-included directories found matching 'build' 11:41:01 INFO - no previously-included directories found matching 'dist' 11:41:01 INFO - no previously-included directories found matching '.git*' 11:41:01 INFO - Installing collected packages: jsonschema, functools32 11:41:01 INFO - Running setup.py install for functools32 11:41:01 INFO - warning: no files found matching '*.txt' 11:41:01 INFO - no previously-included directories found matching 'build' 11:41:01 INFO - no previously-included directories found matching 'dist' 11:41:01 INFO - no previously-included directories found matching '.git*' 11:41:01 INFO - Successfully installed jsonschema functools32 11:41:01 INFO - Cleaning up... 11:41:01 INFO - Return code: 0 11:41:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 11:41:01 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')]} 11:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:01 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')]} 11:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:01 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:01 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 11:41:01 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 11:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:01 INFO - 'HOME': '/Users/cltbld', 11:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:01 INFO - 'LOGNAME': 'cltbld', 11:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:01 INFO - 'MOZ_NO_REMOTE': '1', 11:41:01 INFO - 'NO_EM_RESTART': '1', 11:41:01 INFO - 'PAGER': '/bin/cat', 11:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:01 INFO - 'PWD': '/builds/slave/test', 11:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:01 INFO - 'SHELL': '/bin/bash', 11:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:01 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:01 INFO - 'USER': 'cltbld', 11:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:01 INFO - 'XPC_FLAGS': '0x0', 11:41:01 INFO - 'XPC_SERVICE_NAME': '0', 11:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:01 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 11:41:01 INFO - Cleaning up... 11:41:01 INFO - Return code: 0 11:41:01 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 11:41:01 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')]} 11:41:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:01 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')]} 11:41:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:01 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:01 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 11:41:01 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 11:41:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:01 INFO - 'HOME': '/Users/cltbld', 11:41:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:01 INFO - 'LOGNAME': 'cltbld', 11:41:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:01 INFO - 'MOZ_NO_REMOTE': '1', 11:41:01 INFO - 'NO_EM_RESTART': '1', 11:41:01 INFO - 'PAGER': '/bin/cat', 11:41:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:01 INFO - 'PWD': '/builds/slave/test', 11:41:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:01 INFO - 'SHELL': '/bin/bash', 11:41:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:01 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:01 INFO - 'USER': 'cltbld', 11:41:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:01 INFO - 'XPC_FLAGS': '0x0', 11:41:01 INFO - 'XPC_SERVICE_NAME': '0', 11:41:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:02 INFO - Downloading/unpacking blobuploader==1.2.4 11:41:02 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 11:41:02 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 11:41:02 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 11:41:02 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 11:41:02 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 11:41:02 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 11:41:04 INFO - Downloading blobuploader-1.2.4.tar.gz 11:41:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 11:41:04 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 11:41:04 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 11:41:04 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 11:41:04 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 11:41:04 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 11:41:04 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 11:41:04 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 11:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 11:41:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 11:41:05 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 11:41:05 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 11:41:05 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 11:41:05 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 11:41:05 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 11:41:05 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 11:41:05 INFO - Downloading docopt-0.6.1.tar.gz 11:41:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 11:41:05 INFO - Installing collected packages: blobuploader, requests, docopt 11:41:05 INFO - Running setup.py install for blobuploader 11:41:05 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 11:41:05 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 11:41:05 INFO - Running setup.py install for requests 11:41:06 INFO - Running setup.py install for docopt 11:41:06 INFO - Successfully installed blobuploader requests docopt 11:41:06 INFO - Cleaning up... 11:41:06 INFO - Return code: 0 11:41:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:06 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:06 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 11:41:06 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 11:41:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:06 INFO - 'HOME': '/Users/cltbld', 11:41:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:06 INFO - 'LOGNAME': 'cltbld', 11:41:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:06 INFO - 'MOZ_NO_REMOTE': '1', 11:41:06 INFO - 'NO_EM_RESTART': '1', 11:41:06 INFO - 'PAGER': '/bin/cat', 11:41:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:06 INFO - 'PWD': '/builds/slave/test', 11:41:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:06 INFO - 'SHELL': '/bin/bash', 11:41:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:06 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:06 INFO - 'USER': 'cltbld', 11:41:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:06 INFO - 'XPC_FLAGS': '0x0', 11:41:06 INFO - 'XPC_SERVICE_NAME': '0', 11:41:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:06 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-AyDAs5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-B97r4C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-7FoLM7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-bC7acZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-hQq4_K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-OdLHdm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-yMfApx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-4AqQew-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-LPDp8o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:07 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-S57Xcj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-3RAxu_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-rZPPzb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-GqtcIg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-RWpt7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-EDHtqp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-oLJzc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:08 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-_2anyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41:08 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 11:41:08 INFO - Running setup.py install for manifestparser 11:41:08 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 11:41:08 INFO - Running setup.py install for mozcrash 11:41:09 INFO - Running setup.py install for mozdebug 11:41:09 INFO - Running setup.py install for mozdevice 11:41:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Running setup.py install for mozfile 11:41:09 INFO - Running setup.py install for mozhttpd 11:41:09 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Running setup.py install for mozinfo 11:41:09 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Running setup.py install for mozInstall 11:41:09 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 11:41:09 INFO - Running setup.py install for mozleak 11:41:10 INFO - Running setup.py install for mozlog 11:41:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Running setup.py install for moznetwork 11:41:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Running setup.py install for mozprocess 11:41:10 INFO - Running setup.py install for mozprofile 11:41:10 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Running setup.py install for mozrunner 11:41:10 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 11:41:10 INFO - Running setup.py install for mozscreenshot 11:41:11 INFO - Running setup.py install for moztest 11:41:11 INFO - Running setup.py install for mozversion 11:41:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 11:41:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 11:41:11 INFO - Cleaning up... 11:41:11 INFO - Return code: 0 11:41:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41: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')]} 11:41:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41: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')]} 11:41:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:11 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:11 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 11:41:11 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 11:41:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:11 INFO - 'HOME': '/Users/cltbld', 11:41:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:11 INFO - 'LOGNAME': 'cltbld', 11:41:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:11 INFO - 'MOZ_NO_REMOTE': '1', 11:41:11 INFO - 'NO_EM_RESTART': '1', 11:41:11 INFO - 'PAGER': '/bin/cat', 11:41:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:11 INFO - 'PWD': '/builds/slave/test', 11:41:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:11 INFO - 'SHELL': '/bin/bash', 11:41:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:11 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:11 INFO - 'USER': 'cltbld', 11:41:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:11 INFO - 'XPC_FLAGS': '0x0', 11:41:11 INFO - 'XPC_SERVICE_NAME': '0', 11:41:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 11:41:11 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-0qQpRL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 11:41:11 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)) 11:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 11:41:11 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-MkZSpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 11:41:11 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)) 11:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 11:41:11 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-mat5Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 11:41:11 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)) 11:41:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 11:41:11 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-XNpiwG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-Y3cNBH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-USTcio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-SRy3FP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-_dgFz2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-rZrObp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-P82qMT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-tjC25L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-LdKIVo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 11:41:12 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)) 11:41:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 11:41:12 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-2xTx5s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 11:41:13 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)) 11:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 11:41:13 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-ci1PEG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 11:41:13 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)) 11:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:13 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-RHk6bL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 11:41:13 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)) 11:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 11:41:13 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-oGxoiR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 11:41:13 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)) 11:41:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 11:41:13 INFO - Running setup.py (path:/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/pip-fmWdfh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 11:41:13 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)) 11:41:13 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)) 11:41:13 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)) 11:41:13 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)) 11:41:13 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)) 11:41:13 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)) 11:41:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 11:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 11:41:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 11:41:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 11:41:15 INFO - Downloading blessings-1.6.tar.gz 11:41:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 11:41:15 INFO - Installing collected packages: blessings 11:41:15 INFO - Running setup.py install for blessings 11:41:16 INFO - Successfully installed blessings 11:41:16 INFO - Cleaning up... 11:41:16 INFO - Return code: 0 11:41:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 11:41:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:16 INFO - Reading from file tmpfile_stdout 11:41:16 INFO - Current package versions: 11:41:16 INFO - blessings == 1.6 11:41:16 INFO - blobuploader == 1.2.4 11:41:16 INFO - docopt == 0.6.1 11:41:16 INFO - functools32 == 3.2.3-2 11:41:16 INFO - jsonschema == 2.5.1 11:41:16 INFO - manifestparser == 1.1 11:41:16 INFO - mozInstall == 1.12 11:41:16 INFO - mozcrash == 0.17 11:41:16 INFO - mozdebug == 0.1 11:41:16 INFO - mozdevice == 0.48 11:41:16 INFO - mozfile == 1.2 11:41:16 INFO - mozhttpd == 0.7 11:41:16 INFO - mozinfo == 0.9 11:41:16 INFO - mozleak == 0.1 11:41:16 INFO - mozlog == 3.3 11:41:16 INFO - moznetwork == 0.27 11:41:16 INFO - mozprocess == 0.23 11:41:16 INFO - mozprofile == 0.28 11:41:16 INFO - mozrunner == 6.12 11:41:16 INFO - mozscreenshot == 0.1 11:41:16 INFO - mozsystemmonitor == 0.3 11:41:16 INFO - moztest == 0.7 11:41:16 INFO - mozversion == 1.4 11:41:16 INFO - psutil == 3.1.1 11:41:16 INFO - requests == 1.2.3 11:41:16 INFO - wsgiref == 0.1.2 11:41:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 11:41:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:17 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:17 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 11:41:17 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 11:41:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:17 INFO - 'HOME': '/Users/cltbld', 11:41:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:17 INFO - 'LOGNAME': 'cltbld', 11:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:17 INFO - 'MOZ_NO_REMOTE': '1', 11:41:17 INFO - 'NO_EM_RESTART': '1', 11:41:17 INFO - 'PAGER': '/bin/cat', 11:41:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:17 INFO - 'PWD': '/builds/slave/test', 11:41:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:17 INFO - 'SHELL': '/bin/bash', 11:41:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:17 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:17 INFO - 'USER': 'cltbld', 11:41:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:17 INFO - 'XPC_FLAGS': '0x0', 11:41:17 INFO - 'XPC_SERVICE_NAME': '0', 11:41:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 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)) 11:41:17 INFO - Cleaning up... 11:41:17 INFO - Return code: 0 11:41:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 11:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 11:41:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 11:41:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 11:41:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 11:41:17 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 0x10a6a2a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a737618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fb4025603d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, '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.7Q8Pp2VPQs/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.786SUlC58b/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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 11:41:17 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 11:41:17 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 11:41:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:17 INFO - 'HOME': '/Users/cltbld', 11:41:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:17 INFO - 'LOGNAME': 'cltbld', 11:41:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:17 INFO - 'MOZ_NO_REMOTE': '1', 11:41:17 INFO - 'NO_EM_RESTART': '1', 11:41:17 INFO - 'PAGER': '/bin/cat', 11:41:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:17 INFO - 'PWD': '/builds/slave/test', 11:41:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:17 INFO - 'SHELL': '/bin/bash', 11:41:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:17 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:17 INFO - 'USER': 'cltbld', 11:41:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:17 INFO - 'XPC_FLAGS': '0x0', 11:41:17 INFO - 'XPC_SERVICE_NAME': '0', 11:41:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 11:41:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 11:41:17 INFO - Cleaning up... 11:41:17 INFO - Return code: 0 11:41:17 INFO - Running post-action listener: _resource_record_post_action 11:41:17 INFO - Running post-action listener: _start_resource_monitoring 11:41:17 INFO - Starting resource monitoring. 11:41:17 INFO - [mozharness: 2016-08-25 18:41:17.770517Z] Finished create-virtualenv step (success) 11:41:17 INFO - [mozharness: 2016-08-25 18:41:17.771565Z] Running install step. 11:41:17 INFO - Running pre-action listener: _resource_record_pre_action 11:41:17 INFO - Running main action method: install 11:41:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 11:41:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 11:41:18 INFO - Reading from file tmpfile_stdout 11:41:18 INFO - Detecting whether we're running mozinstall >=1.0... 11:41:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 11:41:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 11:41:18 INFO - Reading from file tmpfile_stdout 11:41:18 INFO - Output received: 11:41:18 INFO - Usage: mozinstall [options] installer 11:41:18 INFO - Options: 11:41:18 INFO - -h, --help show this help message and exit 11:41:18 INFO - -d DEST, --destination=DEST 11:41:18 INFO - Directory to install application into. [default: 11:41:18 INFO - "/builds/slave/test"] 11:41:18 INFO - --app=APP Application being installed. [default: firefox] 11:41:18 INFO - mkdir: /builds/slave/test/build/application 11:41:18 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'] 11:41:18 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 11:41:52 INFO - Reading from file tmpfile_stdout 11:41:52 INFO - Output received: 11:41:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 11:41:52 INFO - Running post-action listener: _resource_record_post_action 11:41:52 INFO - [mozharness: 2016-08-25 18:41:52.677974Z] Finished install step (success) 11:41:52 INFO - [mozharness: 2016-08-25 18:41:52.678144Z] Running run-tests step. 11:41:52 INFO - Running pre-action listener: _resource_record_pre_action 11:41:52 INFO - Running main action method: run_tests 11:41:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 11:41:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 11:41:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 11:41:52 INFO - 2016-08-25 11:41:52.693 screenresolution[1645:7409] starting screenresolution argv=screenresolution get 11:41:52 INFO - 2016-08-25 11:41:52.696 screenresolution[1645:7409] Display 0: 1600x1200x32@60 11:41:52 INFO - 2016-08-25 11:41:52.702 screenresolution[1646:7417] starting screenresolution argv=screenresolution list 11:41:52 INFO - Available Modes on Display 0 11:41:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 11:41:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 11:41:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 11:41:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 11:41:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 11:41:52 INFO - Intel Iris: 11:41:52 INFO - Chipset Model: Intel Iris 11:41:52 INFO - Type: GPU 11:41:52 INFO - Bus: Built-In 11:41:52 INFO - VRAM (Dynamic, Max): 1536 MB 11:41:52 INFO - Vendor: Intel (0x8086) 11:41:52 INFO - Device ID: 0x0a2e 11:41:52 INFO - Revision ID: 0x0009 11:41:52 INFO - Displays: 11:41:52 INFO - Display: 11:41:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 11:41:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 11:41:52 INFO - Display Serial Number: 1600x1200 60 11:41:52 INFO - Main Display: Yes 11:41:52 INFO - Mirror: Off 11:41:52 INFO - Online: Yes 11:41:52 INFO - Rotation: Supported 11:41:52 INFO - Return code: 0 11:41:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 11:41:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 11:41:52 INFO - Python 2.7.3 11:41:52 INFO - Return code: 0 11:41:52 INFO - grabbing minidump binary from tooltool 11:41:52 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')]} 11:41:52 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 0x10a606d30>, '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 0x10a36bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a5feb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 11:41:52 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 11:41:52 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 11:41:52 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 11:41:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 11:41:53 INFO - Return code: 0 11:41:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 11:41:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 11:41:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 11:41:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 11:41:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 11:41:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 11:41:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0355 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 11:41:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.7Q8Pp2VPQs/Render', 11:41:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 11:41:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 11:41:53 INFO - 'HOME': '/Users/cltbld', 11:41:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 11:41:53 INFO - 'LOGNAME': 'cltbld', 11:41:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 11:41:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 11:41:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 11:41:53 INFO - 'MOZ_NO_REMOTE': '1', 11:41:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 11:41:53 INFO - 'NO_EM_RESTART': '1', 11:41:53 INFO - 'PAGER': '/bin/cat', 11:41:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 11:41:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 11:41:53 INFO - 'PWD': '/builds/slave/test', 11:41:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 11:41:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 11:41:53 INFO - 'SHELL': '/bin/bash', 11:41:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.786SUlC58b/Listeners', 11:41:53 INFO - 'TMPDIR': '/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/', 11:41:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 11:41:53 INFO - 'USER': 'cltbld', 11:41:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 11:41:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 11:41:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 11:41:53 INFO - 'XPC_FLAGS': '0x0', 11:41:53 INFO - 'XPC_SERVICE_NAME': '0', 11:41:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 11:41:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0355', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 11:41:53 INFO - mozversion application_buildid: 20160825082226 11:41:53 INFO - mozversion application_changeset: c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b 11:41:53 INFO - mozversion application_display_name: Nightly 11:41:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 11:41:53 INFO - mozversion application_name: Firefox 11:41:53 INFO - mozversion application_remotingname: firefox 11:41:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 11:41:53 INFO - mozversion application_vendor: Mozilla 11:41:53 INFO - mozversion application_version: 51.0a1 11:41:53 INFO - mozversion platform_buildid: 20160825082226 11:41:53 INFO - mozversion platform_changeset: c077d5d5be1fe1c6fc0e50e2d45ee47d02a27d0b 11:41:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 11:41:53 INFO - mozversion platform_version: 51.0a1 11:41:53 INFO - using testdate: 1472150513 11:41:53 INFO - actual date: 1472150513 11:41:53 INFO - starting webserver on 'localhost:49275' 11:41:53 INFO - SUITE-START | Running 2 tests 11:41:53 INFO - TEST-START | dromaeo_css 11:41:53 INFO - operating with platform_type : mac_ 11:41:53 INFO - Initialising browser for dromaeo_css test... 11:41:53 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmp1_T8HX/profile http://localhost:49275/getInfo.html) 11:41:55 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 11:41:55 INFO - PROCESS | 1656 | colorDepth:24 11:41:55 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 11:41:55 INFO - PROCESS | 1656 | __metrics 11:41:55 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 11:41:55 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 11:41:56 INFO - TEST-INFO | 1656: exit 0 11:41:56 INFO - Browser initialized. 11:41:56 INFO - Running cycle 1/1 for dromaeo_css test... 11:41:56 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmp1_T8HX/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 11:42:08 INFO - PROCESS | 1658 | RSS: Main: 231583744 11:42:08 INFO - PROCESS | 1658 | 11:42:59 INFO - PROCESS | 1658 | 1472150579642 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] 11:45:12 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 11:45:13 INFO - PROCESS | 1658 | RSS: Main: 335609856 11:45:13 INFO - PROCESS | 1658 | 11:48:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 11:48:16 INFO - PROCESS | 1658 | RSS: Main: 372363264 11:48:16 INFO - PROCESS | 1658 | 11:51:19 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 11:51:20 INFO - PROCESS | 1658 | RSS: Main: 343392256 11:51:20 INFO - PROCESS | 1658 | 11:54:22 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 11:54:23 INFO - PROCESS | 1658 | RSS: Main: 340971520 11:54:23 INFO - PROCESS | 1658 | 11:57:26 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 11:57:27 INFO - PROCESS | 1658 | RSS: Main: 342122496 11:57:27 INFO - PROCESS | 1658 | 12:00:30 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 12:00:31 INFO - PROCESS | 1658 | RSS: Main: 378556416 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | __start_tp_report 12:00:31 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 12:00:31 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 12:00:31 INFO - PROCESS | 1658 | |i|pagename|runs| 12:00:31 INFO - PROCESS | 1658 | |0;dojo.html;3318.452455344868;3385.5937287525494;3385.7291416686667;3393.1177893747636;3420.230448149165;4373.978130109349;5681.147827825828;5749.712514374285;5809.215755872956;5835.824925252244;5674.233978412913;5749.741261643226;5791.870585235881;5796.24648795656;5796.478316951474;5198.350206224222;5213.661112027725;5283.128283833163;5289.471052894699;5297;5381.058314794915;5382.757775900074;5411.080116380205;5413.729313534327;5427.83716488506;5167.844964651029;5208.583313334924;5237.47625237477;5321.494458026501;5331.5201631853315;5175.637705360627;5200.583953283728;5278.366596008504;5285.49787770165;5300.337457817773;5221.321228240343;5256.9474305256845;5259.000789849916;5329.333833270841;5332.866678333034;5889.676067816268;5902.527797776168;5908.911366329508;5917.940820591782;5928.140419639129;6132.386761323876;6176.722047507828;6195.535334849859;6241.625502469867;6251.468625166866;5245.895082098336;6232.844178895519;6264.561480696353;6280.905786413207;6301.6534090625;5934.495567876736;5947.10793380992;5950.19672344228;5953.047512397996;5953.553483488756;6228.5017198624;6301.275353334425;6339.049142628551;6340.985442329205;6436.034594810722;6185.783497577563;6209.534284928648;6237.937620623736;6238.407351301619;6255.374462553799;6286.622802631857;6310.652914089677;6311;6312.747490100436;6324.620522768649;6046.879062418727;6171;6211.689415529206;6262.248530176408;6270.435660790551;6202.875942481215;6226.875462490725;6232.283287421914;6244.094606282064;6264.874702505925;4274.380214868889;5012.473690262543;5017.89964200714;5023.723695196799;5038.168702164102;4352.042550638855;4388.758618275961;4443.2224360736745;4445.222086134913;4504.189245935698;5893.5874488785375;5909.025010873243;5917.792877249276;5921.259842519685;5921.259842519685;6244.968775156186;6289.7169627366875;6314.71583778731;6327.905081423783;6334;5699.373068962411;5708.115242137475;5756.827295181151;5788.713458183994;5809.564282678816;5486.670799752028;5504.2018907258225;5509.421510741395;5519.034169020405;5528.585356098309;6451.064595633607;6456.644884531396;6476.190476190476;6477.578957367756;6489.805305840832;5038.647294689336;6242.406971337716;6319.146915166394;6334.1765570475545;6339.651319177489;6485.28661847187;6491.610503369624;6492.35076492347;6495.935040649533;6501.934980650133;6221.942584353183;6243.625382477066;6281;6295.338989406229;6324.083007963912;6200;6214.875702485834;6242.375762423721;6315.842103947438;6329.90505142414;5401.648892821954;6047.727852246748;6237.095621135;6239.126522286792;6240.313565507699;6212.658303793335;6220.657863817533;6227.719752611234;6237.563370564017;6253.124562561154;5785.797497087582;5869.295684517885;5895.970520147372;5916.112583112567;5957.970210149095;6176.7838125665385;6214.9689251553455;6225.968870155802;6239.1577137085915;6241.906371404341;6038.305594856648;6093.786717464867;6094.542909281733;6097.725602347817;6101.84745381369 12:00:31 INFO - PROCESS | 1658 | |1;ext.html;467.5277969251055;474.9738764367958;512.4900723779839;530.6046994988732;533.35730444814;30624;39238.60761392385;39640.80179599105;40353.19293614126;40450;42004;42193.36709949353;42337.364939525854;42340.153196936044;42343.153136937246;9788.853167202498;9978.002199780036;9985.700428987142;9990.300678952477;10001.799964000716;8876.201141897229;9118.86321705175;12041.518339266418;12218.572349967753;12336.81494777579;12182.939085304606;12262.754744905096;12271.263724176533;12294.077944154224;12336.656035353255;12222.266664000144;12301.815472767916;12309.93845030778;12332.383380831012;12360.196587221802;11614.76770464586;11686.181967262295;11705.70735731605;11724.003459705935;11743.647690569296;39056.60943390558;39339.40990885139;39373.803130984445;39376.80311598453;39388.803055984536;3440.9655903440894;3569.4824250483534;3607.6572725591027;3648.543932008499;4113.218488487208;30970.38059238825;31283.74865005397;32146;32152.517712234334;32158.839205804055;3186.7291280241207;3393.6436674149354;3408.9659103408894;3459.584849818038;3461.2039230977016;493780;496177;497279.5136024333;499315.0068499341;500806;56618.716906416026;57111;57152;57157.42842571521;57256.42743572511;60486.09270860941;60590.697046515364;60707.785844282866;60731.69634151801;60810.39189608047;12892.226466412045;14962.551123466095;15003.474878379422;15106.622334441727;15154.3180556873;11197.720056998478;11595.536178552931;12664.6833829155;12738;12741.808872866914;15431.382744690087;15506.689866202612;15553;15577.143257120968;17463.825361746472;5871.853203669857;6998.68505917235;7076.504644674823;7090.858182836314;7142.357187853118;754.0160091081082;774.3572834547313;865.35098675993;917.7246825952187;1078.6494389323345;27536.17391478259;28240.43519129606;29759.25601859971;29968.400631987675;30354.696453035187;1010.2776514791959;1041.8385150301715;1075.930064545802;1109.3233127792041;1169.9649010529697;13169.341532923316;15005.724513416264;15274.38902443912;15379.462388705739;15612.531624051297;662887;667152;667280;670325;672015;50407.73980650513;51315.717107072625;56329.155062674094;57597.712011439675;57668.134977975365;1342.7851543752952;1343.5230493174977;1391.4016972701634;1394.7628903086702;1398.76920308148;30313.93901213447;31298.843505782326;31771;31841.23505877418;31944.68055319417;41153.79423102865;49915.75042124766;50639.987200256524;50818.491815081376;51116;557376;562772;563365;563663;563702;26414.603780943686;26825.195244142706;26873;27488.725112748616;27698.86150569234;47513.73482170943;48085.797855053905;48190.27714584214;48452.51547484621;48670.5132948666;37451.81274093721;38028.42957355586;39864.50525017336;40032.99917502086;40446.59553404428;38085;38719.70508381742;39270.607293926696;39699.206015878946;40045.79977100096;37824;37904.62095379011;38226.42660360041;38714.80642596769;38774;33096.50355244625;34242.82878585591;34436;34772.478412824334;35176.82411588028;24973.500529988934;25090.498190036462;25313;25716.228513144637;26187.476250475265 12:00:31 INFO - PROCESS | 1658 | |2;jquery.html;351.49735877694917;362.30075953409914;370.9406494960807;380.8514679275082;399.9900002499937;16009.439669611565;16282;20678.896605516988;20785.792142078575;20866.374008779723;20295.391138265837;20406.285779997703;20709;20725;21112.577748445023;5646.407127251641;5813.128030795393;5969.224000879901;6072.908906366408;6081.54388420868;6029.78895738647;6063.666498342589;6083.087536869483;6090.33006369299;6096.420840020213;3843.500344955152;5478.534324582415;6605.504587155959;6627.1716035495565;6690.63201523916;5932.555058370596;6523.836904077389;6641.501887358443;6646.8005959821285;6672.499562532829;4926.531979461945;4950.43070046942;5775.220345253379;5816.854578635568;5831.825045248649;12379.319137447437;12694;12848.486060557567;12943.223406595635;13017.34913254343;2163.3401812447196;2165.967510487344;2397.113068164773;2426.623873299641;2879.510483217858;9308.95345523268;9898.812142542942;9984.400935943866;10127.949360253226;10276.794464110675;1751.7459968304652;1760.4454596801966;1777.5556110972257;1855.3042609021618;1879.6240751849548;89867.9030356323;112746;127319;128313;132504;10967.287126336763;11054.336739795637;11285.605003824827;11299.740051989635;11519.654410367702;14100.576982690536;14135;14135.78796318056;14142.080764750464;14204.502842400467;8193.713220037389;8444.240018398374;8478.533680647624;8591.656333746705;8627.43921645091;7169.964150179216;8352.12302708219;8449.02836173848;8454.577271136419;8656.39405241627;3581.1047238190454;3737.7757334560015;4211.684123690736;4394.670399720034;4446.755428451402;1839.8160183981495;1883.8587105966833;1888.7544619199416;1890.4423195157462;1892.668782962976;842.4818736477023;852.9275011624081;874.8512752832033;940.9576569054409;983.4001259231862;20244.493887652927;20902.714943436862;20973.161073557192;21066.368008959755;21126.47183820417;799.408437756068;839.4459656626597;910.740438974889;923.9233352934712;1040.6877936618985;9020.729378118667;10162.847557286646;10391.688249352532;10640.414777187329;10952.835707464394;566980;572804;591540;602150.9892450509;603070;126568.36715816362;133844;134363;134668;137512;797.7766225456882;986.5954958466995;1006.6979906028163;1008.2186305613209;1009.0766948242276;16231.67536649284;18575.53561160982;18597.256109755457;18954.0522973848;18960.43118706441;49877;53782;53898.461015390916;55009;55282.44717552934;79099;82452.58773706332;83757;84413.15586844299;84480;6141.539384546266;7174;7247.1303443585;7436.219196984455;7940.166282540481;11009;12677.239365638092;12789.872101279241;13167.539136130572;13194.34028298562;11066.557337706401;11243.831342529706;11377.943110284396;11509.942450287695;11521.711957200801;10899.455027248765;11230.101591872794;11286.717832053935;11351.602693906005;11514.251573647685;10790.56837726482;11262.943685281522;11443.771124577628;11541.249818761753;11598.94200528992;7839.960800195962;8006.719764808437;8091.919080809352;8107.22981316774;8122.796930076795;10341;10880.891191088305;11252.66242012741;11282.774344513227;11330.716732081764 12:00:31 INFO - PROCESS | 1658 | |3;mootools.html;1341.503643651849;1345.643404497808;1476.4610917015289;1636.2555037457957;1853.82388673076;7910.723124690637;8626.870596941044;8886.42238254513;8912.46525208488;8938.776530586721;8714.520701361422;8782.077881822397;8787.209151176392;8796.736097917073;8798.736037918856;2346.9295921122352;2373.2998765364173;2375.964360534593;2376.489054853206;2385.8807059646947;2309.90760369585;2348.2250857217077;2363.243761996162;2370.525894821034;2371.9169829055986;2304.5736538740302;2335.8481698689616;2339.8245131615113;2346.85918844869;2351.0595761695286;2311.39903625057;2329.24299602629;2351.271105957159;2352.752960939094;2356.7171939367386;1923.422973108076;2337.7662233776655;2338.8947497362487;2344.8475849069755;2345.132301048623;8781.165789250012;8782.64869405223;8855.955720221422;8865.06916773736;8871.911280887172;5215.947840521584;5409.972950135263;5410.837674869721;5420.4850539198715;5425.348958125051;3626.057225121461;3647.7264205184715;3706.796126213057;3710.3692372296773;3715.0898029982827;954.3128947158035;1088.9564417423294;1089.096050278267;1116.1963386361806;1136.9829452558217;11573.132015098901;11620.6513804586;11658.18392712519;11665.650030499099;11694.239874408137;4408.228560001987;4457.9331310030375;4629.398178236807;4629.722216667011;4645.651576131736;9146.954265228764;9159.900811902615;9176.403533770284;9178.770530736785;9180.265578753684;5242.771821975645;5251.947480525146;5257.237700533401;5263.499967503081;5269.1832765921345;4213.641840443535;4421.9336709949375;4582.427196600425;4808.975955120272;4929.186684197139;3720.6093360197333;3827.655511003967;3870.41943708446;3876.6317199865966;3947.585503522146;1145.7078444996473;1168.2114572663418;1170.5961443302047;1180.415694231361;1185.324365111878;811.4319976016811;891.2157301574573;906.6146887572887;950.8003319302885;981.4847205217318;5805;5906.173135760997;6018.337982821886;6022.277326720821;6029.155918171459;426.388133029098;430.23418315398015;437.15410680333656;441.5804985264012;454.57724316386077;3885.4948856648457;3945.309570825094;4038.757674539537;4040.5555388907196;4131.710780245354;10448.477576121164;10536.894631053592;10753.193510486744;10847.837282440769;10850.186236032328;10692.572297108029;10870.891291086988;10871.565137394417;10889.564417423215;10890.61882834097;7055.541389809646;8220.671173153007;8232.17678232172;8335.249827515552;8346.374021948379;5869.413058694096;5906.143609176559;5918;5967.582269241111;6052.092186172109;10107.292489525662;10126.493675316353;10157.796844063225;10191.847122293024;10271.537780799732;8673.783155420913;9963.551640176065;10214.846777298495;10342.172626190037;10434.791304174027;7039.612821294878;7227.7831665050135;7277.090363704537;7574.091109066727;7626.351760100453;8047.798805029921;8276.172382761675;8276.958615206886;8287.254147126787;8406.159384061544;7408.925910740823;7507.88738168917;7530.736424225126;7624.275693809301;7677.731279405194;7378.16851569423;7409.777706668809;7415.369693575891;7429.702811887464;7577.848443031219;6934.514583979073;7094.787156385317;7097.396721278749;7098.396636285767;7103.041089452858;5044.8991020178655;5058.443571207091;5087.567556757717;5119.436861945258;5225.817096401608;4037.354023356249;5074.467180946095;5124.410692770379;5180.715060671549;5364.329458817648 12:00:31 INFO - PROCESS | 1658 | |4;prototype.html;293.4746803222232;301.42578388170534;303.42349535881834;305.90669845697056;308.0957390060172;4090.2637525245445;4101.815418306176;4309.655227581794;5083.79664813407;5285.920711189336;4313.741175529471;5258.895631917302;5262.92105618416;5294.761735721892;5299.814506492274;3783.716221283401;3864.9613503865025;3953.723239373245;4004.0990777075217;4038.717289789716;3971.543272523668;3993.6206060424215;4006.879793606182;4015.9397609035886;4044.725911337919;3541.6718730476073;3926.9018274543223;3955.347367684343;3971.682265418774;3977.6618987386105;3295.538624592559;3661.9450708239397;3679.3745063339297;3744.812759362021;3771.396576547739;3475.079104037432;3482.3557641836087;3483.686468217847;3489.3893568625645;3493.877714279988;4100.835966561334;5062.974685126587;5077.695338279678;5079.66982146163;5102.489751024905;3962.8018599069933;4005.499312585927;4319.76241306728;4378.978105109486;4476.798544065525;4572.468223145251;5272.209168624699;5426.902688470219;5440.007995602403;5463.590230732671;3565.3064794222796;3690.3910854708943;3836.3286424875814;3840.5391353037535;3858.9614103858885;4547.977260113678;4937.901241975176;5764.365919748824;5786.681732504752;5793.594448388614;3429.9657003429647;3882.747621404656;3892.922141557153;3905.1994341159993;3911.8630847920185;3332.683395077464;3356.278400143981;3970.900727481778;3973.9403908940812;3991.042149884007;4001.779902105412;4563.908721825611;4574.4739354973935;4609.700369475973;4616.446026476844;4715.976420117878;5201.219817027476;5211.843644690665;5473.698946557977;5600.383957764642;1139.5100106953923;1348.8313960754906;1358.2342954159553;1361.1968938326436;1371.2047012732592;751.3163021650272;800.7068584236532;806.3629732511367;806.9031716193977;813.9540690213048;4754.429468463806;4839.491853355417;5003.699778013331;5016.598672106223;5019.42276638184;5476.424975377608;5515.944840551543;5671.914921276184;5677.37548869624;5680.5171560417;4376.518582955872;4846.909445374762;4955.90088198234;5128.871778205575;5164.612654050961;2492.9031226260386;2683.8325328482174;2923.1961210667237;2990.7158819912074;3006.8195908245575;4364.978175109104;5065.0875746445945;5582.637128586629;5830.329512106077;5869.236999190078;5160.716160611128;5462;5539.030669632798;5812.854678633068;5876.294844618673;5139.89492259166;5950.642961422242;5954.821355359346;5974.581779275409;5990.371011043865;5513.724313784375;5693.8291851242875;5754.424557544379;5762.769489220552;5798.739056742541;4678.766061696833;4740.502247263988;5613.298337707786;5669.433056694297;5680.204771331934;5154.329937108152;5354.3574771027725;5931.673757943182;5941.376155503783;5955.553333499918;3156.352947645773;3616;3806.3719486284454;3821;3828.923421531498;5088.949110508847;5328.307320048369;5673.489385955284;5696.344920334214;5705.914411283751;5412.485813847678;5536.44979405767;5604.740397298001;5604.8038318658655;5622.662640241435;4615.861524154281;5346.144616861284;5540.587150276654;5586.776528938797;5610.194902548726;4503.864884053483;5177.508136727005;5459.716966512787;5472.781088756406;5499.3675727291875;5265.367736001852;5491.697956612425;5500.889982200253;5533.557315414677;5586.709449125839;4309.312430099884;4320.025576469593;4860.92708609364;5403.757135858853;5451.972740136433 12:00:31 INFO - PROCESS | 1658 | |5;yui.html;713.1727196452116;758.6965213914438;770.3798442253986;827.8013276813563;849.8895143631327;15746.055236685806;16584.170791460423;16593.66812663746;16593.83406165938;16609;14548.490802821903;14805.92597037016;14824.29392651837;15051.473198438056;15070.547883563484;844.2697067037;873.829603227371;874.3748220022683;876.1238761238761;876.6011464783509;685.9279775623562;710.4174576846988;841.907390187082;869.447900583128;875.4484674654958;908.5321059654283;909.4043401571981;909.8726178335005;910.0762725833251;910.8542633178693;900.6577500549782;900.8108297257584;901.134910485931;902.4630344944748;902.7652810269311;521.78606771224;542.0243561589164;652.4519403700914;672.0960308385214;678.0101052463409;14721.926390368088;14798.40806367744;14814.407423703038;14824.629384265372;14826.703465930728;11178.664640060812;13763.174209547458;13814.309284535733;13886.305684715724;14293.213873236968;12483.625491235278;12668.746625067448;12677.936610316981;12726.363681815872;13333.333333333294;4564.748938808364;4582.450105987269;4680.555347242007;4794.041191761662;4797.832075877328;122932;124373.37813310875;124816;124959.9999999991;125055.74944250532;10989.725256868642;10992.28550144238;11095.056920161715;11120.61077862271;11293;21549.784502154776;21572.46068848291;21596.46008849791;21624;21634.350969470943;6903.482238832108;7759.534427934342;7995.640196191069;8077.717279895175;8166.469179503313;4331.783410829509;4417.933730994037;4502.909941801211;4615.3999980003055;4617.815287388534;3414.470757032664;3441.380551500704;3529.3294274087843;3536.133647256387;3538.716902647782;634.6763150793129;649.7563413719855;658.9637569933603;660.5838321857199;676.24936320684;583.270911360799;610.6122612141322;624.8344188789974;635.7234602947733;641.5605310362416;8533.701320453754;8741.30069594431;9078.682246121354;9176.220021298264;9194.632214711337;605.6729366142322;620.7548018532655;625.1341891480332;634.2104080419907;637.5951270942985;4307.8276868924895;4459.464864216315;4486.730796152241;4496.505384407713;4500.482444518857;69921.65039174771;104571.43142852864;105382;105446;152097;108939.91060089457;136309;136844;137149;141721;100552;102177.48911255396;122332;123064.38467807783;125026;7520.586367749826;9579.281553883347;9860;9904.851427228454;10168.542415591168;53596;56633.37232670539;56962.86704231307;60156;61220.08169877366;85424.71862921937;88480.55759721418;90019;90061.64907526523;91439.08560914306;13270.071095023255;13333.466661333827;13400.731985360044;13424.664383390493;13624.318784060559;11683.649490515298;15007.174605396807;15132.016418933175;15211.847881521044;15903.045817251003;13074.607761767162;13955.092918960236;14140.646483837574;14177.716445670823;14342.426302947766;13686.794698079337;13927.23400212998;14096.788548171991;14124.78812817788;14545.709085818437;13567.932160339134;13756;13964.650883727989;14396.784048239075;14441.205733684748;11393.843177139086;11412.144089193178;11740;11747.530098795953;11820.822687659864;11290.619328211696;11491.54033838637;11652.708682283012;11687.357195354338;11705.473253703774 12:00:31 INFO - PROCESS | 1658 | __end_tp_report 12:00:31 INFO - PROCESS | 1658 | __start_cc_report 12:00:31 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1172 12:00:31 INFO - PROCESS | 1658 | __end_cc_report 12:00:31 INFO - PROCESS | 1658 | __startTimestamp1472151631048__endTimestamp 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | ------- Summary: start ------- 12:00:31 INFO - PROCESS | 1658 | Number of tests: 6 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5796.01 Median:5957.97 stddev:667.53 (11.2%) stddev-sans-first:640.66 12:00:31 INFO - PROCESS | 1658 | Values: 3318.5 3385.6 3385.7 3393.1 3420.2 4374.0 5681.1 5749.7 5809.2 5835.8 5674.2 5749.7 5791.9 5796.2 5796.5 5198.4 5213.7 5283.1 5289.5 5297.0 5381.1 5382.8 5411.1 5413.7 5427.8 5167.8 5208.6 5237.5 5321.5 5331.5 5175.6 5200.6 5278.4 5285.5 5300.3 5221.3 5256.9 5259.0 5329.3 5332.9 5889.7 5902.5 5908.9 5917.9 5928.1 6132.4 6176.7 6195.5 6241.6 6251.5 5245.9 6232.8 6264.6 6280.9 6301.7 5934.5 5947.1 5950.2 5953.0 5953.6 6228.5 6301.3 6339.0 6341.0 6436.0 6185.8 6209.5 6237.9 6238.4 6255.4 6286.6 6310.7 6311.0 6312.7 6324.6 6046.9 6171.0 6211.7 6262.2 6270.4 6202.9 6226.9 6232.3 6244.1 6264.9 4274.4 5012.5 5017.9 5023.7 5038.2 4352.0 4388.8 4443.2 4445.2 4504.2 5893.6 5909.0 5917.8 5921.3 5921.3 6245.0 6289.7 6314.7 6327.9 6334.0 5699.4 5708.1 5756.8 5788.7 5809.6 5486.7 5504.2 5509.4 5519.0 5528.6 6451.1 6456.6 6476.2 6477.6 6489.8 5038.6 6242.4 6319.1 6334.2 6339.7 6485.3 6491.6 6492.4 6495.9 6501.9 6221.9 6243.6 6281.0 6295.3 6324.1 6200.0 6214.9 6242.4 6315.8 6329.9 5401.6 6047.7 6237.1 6239.1 6240.3 6212.7 6220.7 6227.7 6237.6 6253.1 5785.8 5869.3 5896.0 5916.1 5958.0 6176.8 6215.0 6226.0 6239.2 6241.9 6038.3 6093.8 6094.5 6097.7 6101.8 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:70688.67 Median:37637.91 stddev:155058.39 (412.0%) stddev-sans-first:155403.74 12:00:31 INFO - PROCESS | 1658 | Values: 467.5 475.0 512.5 530.6 533.4 30624.0 39238.6 39640.8 40353.2 40450.0 42004.0 42193.4 42337.4 42340.2 42343.2 9788.9 9978.0 9985.7 9990.3 10001.8 8876.2 9118.9 12041.5 12218.6 12336.8 12182.9 12262.8 12271.3 12294.1 12336.7 12222.3 12301.8 12309.9 12332.4 12360.2 11614.8 11686.2 11705.7 11724.0 11743.6 39056.6 39339.4 39373.8 39376.8 39388.8 3441.0 3569.5 3607.7 3648.5 4113.2 30970.4 31283.7 32146.0 32152.5 32158.8 3186.7 3393.6 3409.0 3459.6 3461.2 493780.0 496177.0 497279.5 499315.0 500806.0 56618.7 57111.0 57152.0 57157.4 57256.4 60486.1 60590.7 60707.8 60731.7 60810.4 12892.2 14962.6 15003.5 15106.6 15154.3 11197.7 11595.5 12664.7 12738.0 12741.8 15431.4 15506.7 15553.0 15577.1 17463.8 5871.9 6998.7 7076.5 7090.9 7142.4 754.0 774.4 865.4 917.7 1078.6 27536.2 28240.4 29759.3 29968.4 30354.7 1010.3 1041.8 1075.9 1109.3 1170.0 13169.3 15005.7 15274.4 15379.5 15612.5 662887.0 667152.0 667280.0 670325.0 672015.0 50407.7 51315.7 56329.2 57597.7 57668.1 1342.8 1343.5 1391.4 1394.8 1398.8 30313.9 31298.8 31771.0 31841.2 31944.7 41153.8 49915.8 50640.0 50818.5 51116.0 557376.0 562772.0 563365.0 563663.0 563702.0 26414.6 26825.2 26873.0 27488.7 27698.9 47513.7 48085.8 48190.3 48452.5 48670.5 37451.8 38028.4 39864.5 40033.0 40446.6 38085.0 38719.7 39270.6 39699.2 40045.8 37824.0 37904.6 38226.4 38714.8 38774.0 33096.5 34242.8 34436.0 34772.5 35176.8 24973.5 25090.5 25313.0 25716.2 26187.5 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:34576.69 Median:2281.54 stddev:98487.01 (4316.7%) stddev-sans-first:98729.77 12:00:31 INFO - PROCESS | 1658 | Values: 351.5 362.3 370.9 380.9 400.0 16009.4 16282.0 20678.9 20785.8 20866.4 20295.4 20406.3 20709.0 20725.0 21112.6 5646.4 5813.1 5969.2 6072.9 6081.5 6029.8 6063.7 6083.1 6090.3 6096.4 3843.5 5478.5 6605.5 6627.2 6690.6 5932.6 6523.8 6641.5 6646.8 6672.5 4926.5 4950.4 5775.2 5816.9 5831.8 12379.3 12694.0 12848.5 12943.2 13017.3 2163.3 2166.0 2397.1 2426.6 2879.5 9309.0 9898.8 9984.4 10127.9 10276.8 1751.7 1760.4 1777.6 1855.3 1879.6 89867.9 112746.0 127319.0 128313.0 132504.0 10967.3 11054.3 11285.6 11299.7 11519.7 14100.6 14135.0 14135.8 14142.1 14204.5 8193.7 8444.2 8478.5 8591.7 8627.4 7170.0 8352.1 8449.0 8454.6 8656.4 3581.1 3737.8 4211.7 4394.7 4446.8 1839.8 1883.9 1888.8 1890.4 1892.7 842.5 852.9 874.9 941.0 983.4 20244.5 20902.7 20973.2 21066.4 21126.5 799.4 839.4 910.7 923.9 1040.7 9020.7 10162.8 10391.7 10640.4 10952.8 566980.0 572804.0 591540.0 602151.0 603070.0 126568.4 133844.0 134363.0 134668.0 137512.0 797.8 986.6 1006.7 1008.2 1009.1 16231.7 18575.5 18597.3 18954.1 18960.4 49877.0 53782.0 53898.5 55009.0 55282.4 79099.0 82452.6 83757.0 84413.2 84480.0 6141.5 7174.0 7247.1 7436.2 7940.2 11009.0 12677.2 12789.9 13167.5 13194.3 11066.6 11243.8 11377.9 11509.9 11521.7 10899.5 11230.1 11286.7 11351.6 11514.3 10790.6 11262.9 11443.8 11541.2 11598.9 7840.0 8006.7 8091.9 8107.2 8122.8 10341.0 10880.9 11252.7 11282.8 11330.7 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5679.60 Median:4727.31 stddev:3213.93 (68.0%) stddev-sans-first:3206.41 12:00:31 INFO - PROCESS | 1658 | Values: 1341.5 1345.6 1476.5 1636.3 1853.8 7910.7 8626.9 8886.4 8912.5 8938.8 8714.5 8782.1 8787.2 8796.7 8798.7 2346.9 2373.3 2376.0 2376.5 2385.9 2309.9 2348.2 2363.2 2370.5 2371.9 2304.6 2335.8 2339.8 2346.9 2351.1 2311.4 2329.2 2351.3 2352.8 2356.7 1923.4 2337.8 2338.9 2344.8 2345.1 8781.2 8782.6 8856.0 8865.1 8871.9 5215.9 5410.0 5410.8 5420.5 5425.3 3626.1 3647.7 3706.8 3710.4 3715.1 954.3 1089.0 1089.1 1116.2 1137.0 11573.1 11620.7 11658.2 11665.7 11694.2 4408.2 4457.9 4629.4 4629.7 4645.7 9147.0 9159.9 9176.4 9178.8 9180.3 5242.8 5251.9 5257.2 5263.5 5269.2 4213.6 4421.9 4582.4 4809.0 4929.2 3720.6 3827.7 3870.4 3876.6 3947.6 1145.7 1168.2 1170.6 1180.4 1185.3 811.4 891.2 906.6 950.8 981.5 5805.0 5906.2 6018.3 6022.3 6029.2 426.4 430.2 437.2 441.6 454.6 3885.5 3945.3 4038.8 4040.6 4131.7 10448.5 10536.9 10753.2 10847.8 10850.2 10692.6 10870.9 10871.6 10889.6 10890.6 7055.5 8220.7 8232.2 8335.2 8346.4 5869.4 5906.1 5918.0 5967.6 6052.1 10107.3 10126.5 10157.8 10191.8 10271.5 8673.8 9963.6 10214.8 10342.2 10434.8 7039.6 7227.8 7277.1 7574.1 7626.4 8047.8 8276.2 8277.0 8287.3 8406.2 7408.9 7507.9 7530.7 7624.3 7677.7 7378.2 7409.8 7415.4 7429.7 7577.8 6934.5 7094.8 7097.4 7098.4 7103.0 5044.9 5058.4 5087.6 5119.4 5225.8 4037.4 5074.5 5124.4 5180.7 5364.3 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4414.80 Median:4946.90 stddev:1373.50 (27.8%) stddev-sans-first:1342.06 12:00:31 INFO - PROCESS | 1658 | Values: 293.5 301.4 303.4 305.9 308.1 4090.3 4101.8 4309.7 5083.8 5285.9 4313.7 5258.9 5262.9 5294.8 5299.8 3783.7 3865.0 3953.7 4004.1 4038.7 3971.5 3993.6 4006.9 4015.9 4044.7 3541.7 3926.9 3955.3 3971.7 3977.7 3295.5 3661.9 3679.4 3744.8 3771.4 3475.1 3482.4 3483.7 3489.4 3493.9 4100.8 5063.0 5077.7 5079.7 5102.5 3962.8 4005.5 4319.8 4379.0 4476.8 4572.5 5272.2 5426.9 5440.0 5463.6 3565.3 3690.4 3836.3 3840.5 3859.0 4548.0 4937.9 5764.4 5786.7 5793.6 3430.0 3882.7 3892.9 3905.2 3911.9 3332.7 3356.3 3970.9 3973.9 3991.0 4001.8 4563.9 4574.5 4609.7 4616.4 4716.0 5201.2 5211.8 5473.7 5600.4 1139.5 1348.8 1358.2 1361.2 1371.2 751.3 800.7 806.4 806.9 814.0 4754.4 4839.5 5003.7 5016.6 5019.4 5476.4 5515.9 5671.9 5677.4 5680.5 4376.5 4846.9 4955.9 5128.9 5164.6 2492.9 2683.8 2923.2 2990.7 3006.8 4365.0 5065.1 5582.6 5830.3 5869.2 5160.7 5462.0 5539.0 5812.9 5876.3 5139.9 5950.6 5954.8 5974.6 5990.4 5513.7 5693.8 5754.4 5762.8 5798.7 4678.8 4740.5 5613.3 5669.4 5680.2 5154.3 5354.4 5931.7 5941.4 5955.6 3156.4 3616.0 3806.4 3821.0 3828.9 5088.9 5328.3 5673.5 5696.3 5705.9 5412.5 5536.4 5604.7 5604.8 5622.7 4615.9 5346.1 5540.6 5586.8 5610.2 4503.9 5177.5 5459.7 5472.8 5499.4 5265.4 5491.7 5500.9 5533.6 5586.7 4309.3 4320.0 4860.9 5403.8 5452.0 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24270.28 Median:3427.93 stddev:38064.37 (1110.4%) stddev-sans-first:38130.06 12:00:31 INFO - PROCESS | 1658 | Values: 713.2 758.7 770.4 827.8 849.9 15746.1 16584.2 16593.7 16593.8 16609.0 14548.5 14805.9 14824.3 15051.5 15070.5 844.3 873.8 874.4 876.1 876.6 685.9 710.4 841.9 869.4 875.4 908.5 909.4 909.9 910.1 910.9 900.7 900.8 901.1 902.5 902.8 521.8 542.0 652.5 672.1 678.0 14721.9 14798.4 14814.4 14824.6 14826.7 11178.7 13763.2 13814.3 13886.3 14293.2 12483.6 12668.7 12677.9 12726.4 13333.3 4564.7 4582.5 4680.6 4794.0 4797.8 122932.0 124373.4 124816.0 124960.0 125055.7 10989.7 10992.3 11095.1 11120.6 11293.0 21549.8 21572.5 21596.5 21624.0 21634.4 6903.5 7759.5 7995.6 8077.7 8166.5 4331.8 4417.9 4502.9 4615.4 4617.8 3414.5 3441.4 3529.3 3536.1 3538.7 634.7 649.8 659.0 660.6 676.2 583.3 610.6 624.8 635.7 641.6 8533.7 8741.3 9078.7 9176.2 9194.6 605.7 620.8 625.1 634.2 637.6 4307.8 4459.5 4486.7 4496.5 4500.5 69921.7 104571.4 105382.0 105446.0 152097.0 108939.9 136309.0 136844.0 137149.0 141721.0 100552.0 102177.5 122332.0 123064.4 125026.0 7520.6 9579.3 9860.0 9904.9 10168.5 53596.0 56633.4 56962.9 60156.0 61220.1 85424.7 88480.6 90019.0 90061.6 91439.1 13270.1 13333.5 13400.7 13424.7 13624.3 11683.6 15007.2 15132.0 15211.8 15903.0 13074.6 13955.1 14140.6 14177.7 14342.4 13686.8 13927.2 14096.8 14124.8 14545.7 13567.9 13756.0 13964.7 14396.8 14441.2 11393.8 11412.1 11740.0 11747.5 11820.8 11290.6 11491.5 11652.7 11687.4 11705.5 12:00:31 INFO - PROCESS | 1658 | -------- Summary: end -------- 12:00:31 INFO - PROCESS | 1658 | 12:00:31 INFO - TEST-INFO | 1658: exit 0 12:00:31 INFO - TEST-OK | dromaeo_css | took 1118395ms 12:00:31 INFO - TEST-START | kraken 12:00:31 INFO - operating with platform_type : mac_ 12:00:31 INFO - Initialising browser for kraken test... 12:00:31 INFO - TEST-INFO | started process 1668 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpK1thkx/profile http://localhost:49275/getInfo.html) 12:00:33 INFO - PROCESS | 1668 | __metrics Screen width/height:1600/1200 12:00:33 INFO - PROCESS | 1668 | colorDepth:24 12:00:33 INFO - PROCESS | 1668 | Browser inner width/height: 1024/689 12:00:33 INFO - PROCESS | 1668 | __metrics 12:00:34 INFO - PROCESS | 1668 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 12:00:34 INFO - PROCESS | 1668 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 12:00:34 INFO - TEST-INFO | 1668: exit 0 12:00:34 INFO - Browser initialized. 12:00:34 INFO - Running cycle 1/1 for kraken test... 12:00:34 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/tmpK1thkx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:00:46 INFO - PROCESS | 1669 | RSS: Main: 231583744 12:00:46 INFO - PROCESS | 1669 | 12:00:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 12:00:50 INFO - PROCESS | 1669 | RSS: Main: 297172992 12:00:50 INFO - PROCESS | 1669 | 12:00:53 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 12:00:53 INFO - PROCESS | 1669 | RSS: Main: 297795584 12:00:53 INFO - PROCESS | 1669 | 12:00:57 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 12:00:58 INFO - PROCESS | 1669 | RSS: Main: 380870656 12:00:58 INFO - PROCESS | 1669 | 12:01:00 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 12:01:00 INFO - PROCESS | 1669 | RSS: Main: 286638080 12:01:00 INFO - PROCESS | 1669 | 12:01:02 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 12:01:03 INFO - PROCESS | 1669 | RSS: Main: 291291136 12:01:03 INFO - PROCESS | 1669 | 12:01:06 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 12:01:06 INFO - PROCESS | 1669 | RSS: Main: 328454144 12:01:06 INFO - PROCESS | 1669 | 12:01:09 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 12:01:10 INFO - PROCESS | 1669 | RSS: Main: 327823360 12:01:10 INFO - PROCESS | 1669 | 12:01:13 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 12:01:14 INFO - PROCESS | 1669 | RSS: Main: 327987200 12:01:14 INFO - PROCESS | 1669 | 12:01:15 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 12:01:16 INFO - PROCESS | 1669 | RSS: Main: 301801472 12:01:16 INFO - PROCESS | 1669 | 12:01:18 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 12:01:18 INFO - PROCESS | 1669 | RSS: Main: 303165440 12:01:18 INFO - PROCESS | 1669 | 12:01:20 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 12:01:21 INFO - PROCESS | 1669 | RSS: Main: 307175424 12:01:21 INFO - PROCESS | 1669 | 12:01:24 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 12:01:25 INFO - PROCESS | 1669 | RSS: Main: 314597376 12:01:25 INFO - PROCESS | 1669 | 12:01:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 12:01:28 INFO - PROCESS | 1669 | RSS: Main: 317259776 12:01:28 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 12:01:30 INFO - PROCESS | 1669 | RSS: Main: 360669184 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | __start_tp_report 12:01:30 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 12:01:30 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 12:01:30 INFO - PROCESS | 1669 | |i|pagename|runs| 12:01:30 INFO - PROCESS | 1669 | |0;ai-astar;134;167;152;118;112;113;112;90;121;168 12:01:30 INFO - PROCESS | 1669 | |1;audio-beat-detection;148;107;131;159;108;128;128;117;180;109 12:01:30 INFO - PROCESS | 1669 | |2;audio-dft;191;221;198;263;184;186;185;186;181;186 12:01:30 INFO - PROCESS | 1669 | |3;audio-fft;77;73;110;94;57;61;62;87;92;56 12:01:30 INFO - PROCESS | 1669 | |4;audio-oscillator;84;85;133;72;95;110;146;69;69;115 12:01:30 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;88;86;90;87;89;87;89;87;89;87 12:01:30 INFO - PROCESS | 1669 | |6;imaging-darkroom;95;94;94;96;94;93;94;95;95;95 12:01:30 INFO - PROCESS | 1669 | |7;imaging-desaturate;84;91;103;84;116;133;137;85;86;84 12:01:30 INFO - PROCESS | 1669 | |8;json-parse-financial;55;53;53;55;54;56;55;55;55;55 12:01:30 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;67;66;65;74;74;76;73;69;70;71 12:01:30 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;86;85;86;86;88;84;83;87;89;84 12:01:30 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;140;138;136;136;137;138;140;139;206;138 12:01:30 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;168;175;234;169;167;162;179;172;167;164 12:01:30 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;67;66;67;67;67;67;66;68;66;66 12:01:30 INFO - PROCESS | 1669 | __end_tp_report 12:01:30 INFO - PROCESS | 1669 | __start_cc_report 12:01:30 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,2107 12:01:30 INFO - PROCESS | 1669 | __end_cc_report 12:01:30 INFO - PROCESS | 1669 | __startTimestamp1472151690764__endTimestamp 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | ------- Summary: start ------- 12:01:30 INFO - PROCESS | 1669 | Number of tests: 14 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:128.70 Median:143.00 stddev:25.94 (18.1%) stddev-sans-first:27.45 12:01:30 INFO - PROCESS | 1669 | Values: 134.0 167.0 152.0 118.0 112.0 113.0 112.0 90.0 121.0 168.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:131.50 Median:129.50 stddev:24.21 (18.7%) stddev-sans-first:24.93 12:01:30 INFO - PROCESS | 1669 | Values: 148.0 107.0 131.0 159.0 108.0 128.0 128.0 117.0 180.0 109.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:198.10 Median:188.50 stddev:25.58 (13.6%) stddev-sans-first:27.00 12:01:30 INFO - PROCESS | 1669 | Values: 191.0 221.0 198.0 263.0 184.0 186.0 185.0 186.0 181.0 186.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:76.90 Median:75.00 stddev:18.38 (24.5%) stddev-sans-first:19.50 12:01:30 INFO - PROCESS | 1669 | Values: 77.0 73.0 110.0 94.0 57.0 61.0 62.0 87.0 92.0 56.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:97.80 Median:70.50 stddev:27.27 (38.7%) stddev-sans-first:28.46 12:01:30 INFO - PROCESS | 1669 | Values: 84.0 85.0 133.0 72.0 95.0 110.0 146.0 69.0 69.0 115.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:87.90 Median:88.50 stddev:1.29 (1.5%) stddev-sans-first:1.36 12:01:30 INFO - PROCESS | 1669 | Values: 88.0 86.0 90.0 87.0 89.0 87.0 89.0 87.0 89.0 87.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:94.50 Median:95.00 stddev:0.85 (0.9%) stddev-sans-first:0.88 12:01:30 INFO - PROCESS | 1669 | Values: 95.0 94.0 94.0 96.0 94.0 93.0 94.0 95.0 95.0 95.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:100.30 Median:84.00 stddev:21.06 (25.1%) stddev-sans-first:21.50 12:01:30 INFO - PROCESS | 1669 | Values: 84.0 91.0 103.0 84.0 116.0 133.0 137.0 85.0 86.0 84.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:54.60 Median:55.00 stddev:0.97 (1.8%) stddev-sans-first:1.01 12:01:30 INFO - PROCESS | 1669 | Values: 55.0 53.0 53.0 55.0 54.0 56.0 55.0 55.0 55.0 55.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:70.50 Median:72.00 stddev:3.75 (5.2%) stddev-sans-first:3.76 12:01:30 INFO - PROCESS | 1669 | Values: 67.0 66.0 65.0 74.0 74.0 76.0 73.0 69.0 70.0 71.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:85.80 Median:86.00 stddev:1.87 (2.2%) stddev-sans-first:1.99 12:01:30 INFO - PROCESS | 1669 | Values: 86.0 85.0 86.0 86.0 88.0 84.0 83.0 87.0 89.0 84.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:144.80 Median:138.50 stddev:21.55 (15.6%) stddev-sans-first:22.79 12:01:30 INFO - PROCESS | 1669 | Values: 140.0 138.0 136.0 136.0 137.0 138.0 140.0 139.0 206.0 138.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:175.70 Median:170.50 stddev:21.09 (12.4%) stddev-sans-first:22.19 12:01:30 INFO - PROCESS | 1669 | Values: 168.0 175.0 234.0 169.0 167.0 162.0 179.0 172.0 167.0 164.0 12:01:30 INFO - PROCESS | 1669 | 12:01:30 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.70 Median:67.00 stddev:0.67 (1.0%) stddev-sans-first:0.71 12:01:30 INFO - PROCESS | 1669 | Values: 67.0 66.0 67.0 67.0 67.0 67.0 66.0 68.0 66.0 66.0 12:01:30 INFO - PROCESS | 1669 | -------- Summary: end -------- 12:01:30 INFO - PROCESS | 1669 | 12:01:31 INFO - TEST-INFO | 1669: exit 0 12:01:31 INFO - TEST-OK | kraken | took 59930ms 12:01:31 INFO - SUITE-END | took 1178s 12:01:31 INFO - Completed test suite (00:19:38) 12:01:31 INFO - javascript benchmark 12:01:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3318.452455344868, 3385.5937287525494, 3385.7291416686667, 3393.1177893747636, 3420.230448149165, 4373.978130109349, 5681.147827825828, 5749.712514374285, 5809.215755872956, 5835.824925252244, 5674.233978412913, 5749.741261643226, 5791.870585235881, 5796.24648795656, 5796.478316951474, 5198.350206224222, 5213.661112027725, 5283.128283833163, 5289.471052894699, 5297.0, 5381.058314794915, 5382.757775900074, 5411.080116380205, 5413.729313534327, 5427.83716488506, 5167.844964651029, 5208.583313334924, 5237.47625237477, 5321.494458026501, 5331.5201631853315, 5175.637705360627, 5200.583953283728, 5278.366596008504, 5285.49787770165, 5300.337457817773, 5221.321228240343, 5256.9474305256845, 5259.000789849916, 5329.333833270841, 5332.866678333034, 5889.676067816268, 5902.527797776168, 5908.911366329508, 5917.940820591782, 5928.140419639129, 6132.386761323876, 6176.722047507828, 6195.535334849859, 6241.625502469867, 6251.468625166866, 5245.895082098336, 6232.844178895519, 6264.561480696353, 6280.905786413207, 6301.6534090625, 5934.495567876736, 5947.10793380992, 5950.19672344228, 5953.047512397996, 5953.553483488756, 6228.5017198624, 6301.275353334425, 6339.049142628551, 6340.985442329205, 6436.034594810722, 6185.783497577563, 6209.534284928648, 6237.937620623736, 6238.407351301619, 6255.374462553799, 6286.622802631857, 6310.652914089677, 6311.0, 6312.747490100436, 6324.620522768649, 6046.879062418727, 6171.0, 6211.689415529206, 6262.248530176408, 6270.435660790551, 6202.875942481215, 6226.875462490725, 6232.283287421914, 6244.094606282064, 6264.874702505925, 4274.380214868889, 5012.473690262543, 5017.89964200714, 5023.723695196799, 5038.168702164102, 4352.042550638855, 4388.758618275961, 4443.2224360736745, 4445.222086134913, 4504.189245935698, 5893.5874488785375, 5909.025010873243, 5917.792877249276, 5921.259842519685, 5921.259842519685, 6244.968775156186, 6289.7169627366875, 6314.71583778731, 6327.905081423783, 6334.0, 5699.373068962411, 5708.115242137475, 5756.827295181151, 5788.713458183994, 5809.564282678816, 5486.670799752028, 5504.2018907258225, 5509.421510741395, 5519.034169020405, 5528.585356098309, 6451.064595633607, 6456.644884531396, 6476.190476190476, 6477.578957367756, 6489.805305840832, 5038.647294689336, 6242.406971337716, 6319.146915166394, 6334.1765570475545, 6339.651319177489, 6485.28661847187, 6491.610503369624, 6492.35076492347, 6495.935040649533, 6501.934980650133, 6221.942584353183, 6243.625382477066, 6281.0, 6295.338989406229, 6324.083007963912, 6200.0, 6214.875702485834, 6242.375762423721, 6315.842103947438, 6329.90505142414, 5401.648892821954, 6047.727852246748, 6237.095621135, 6239.126522286792, 6240.313565507699, 6212.658303793335, 6220.657863817533, 6227.719752611234, 6237.563370564017, 6253.124562561154, 5785.797497087582, 5869.295684517885, 5895.970520147372, 5916.112583112567, 5957.970210149095, 6176.7838125665385, 6214.9689251553455, 6225.968870155802, 6239.1577137085915, 6241.906371404341, 6038.305594856648, 6093.786717464867, 6094.542909281733, 6097.725602347817, 6101.84745381369], "value": 5753.349746479843, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.5277969251055, 474.9738764367958, 512.4900723779839, 530.6046994988732, 533.35730444814, 30624.0, 39238.60761392385, 39640.80179599105, 40353.19293614126, 40450.0, 42004.0, 42193.36709949353, 42337.364939525854, 42340.153196936044, 42343.153136937246, 9788.853167202498, 9978.002199780036, 9985.700428987142, 9990.300678952477, 10001.799964000716, 8876.201141897229, 9118.86321705175, 12041.518339266418, 12218.572349967753, 12336.81494777579, 12182.939085304606, 12262.754744905096, 12271.263724176533, 12294.077944154224, 12336.656035353255, 12222.266664000144, 12301.815472767916, 12309.93845030778, 12332.383380831012, 12360.196587221802, 11614.76770464586, 11686.181967262295, 11705.70735731605, 11724.003459705935, 11743.647690569296, 39056.60943390558, 39339.40990885139, 39373.803130984445, 39376.80311598453, 39388.803055984536, 3440.9655903440894, 3569.4824250483534, 3607.6572725591027, 3648.543932008499, 4113.218488487208, 30970.38059238825, 31283.74865005397, 32146.0, 32152.517712234334, 32158.839205804055, 3186.7291280241207, 3393.6436674149354, 3408.9659103408894, 3459.584849818038, 3461.2039230977016, 493780.0, 496177.0, 497279.5136024333, 499315.0068499341, 500806.0, 56618.716906416026, 57111.0, 57152.0, 57157.42842571521, 57256.42743572511, 60486.09270860941, 60590.697046515364, 60707.785844282866, 60731.69634151801, 60810.39189608047, 12892.226466412045, 14962.551123466095, 15003.474878379422, 15106.622334441727, 15154.3180556873, 11197.720056998478, 11595.536178552931, 12664.6833829155, 12738.0, 12741.808872866914, 15431.382744690087, 15506.689866202612, 15553.0, 15577.143257120968, 17463.825361746472, 5871.853203669857, 6998.68505917235, 7076.504644674823, 7090.858182836314, 7142.357187853118, 754.0160091081082, 774.3572834547313, 865.35098675993, 917.7246825952187, 1078.6494389323345, 27536.17391478259, 28240.43519129606, 29759.25601859971, 29968.400631987675, 30354.696453035187, 1010.2776514791959, 1041.8385150301715, 1075.930064545802, 1109.3233127792041, 1169.9649010529697, 13169.341532923316, 15005.724513416264, 15274.38902443912, 15379.462388705739, 15612.531624051297, 662887.0, 667152.0, 667280.0, 670325.0, 672015.0, 50407.73980650513, 51315.717107072625, 56329.155062674094, 57597.712011439675, 57668.134977975365, 1342.7851543752952, 1343.5230493174977, 1391.4016972701634, 1394.7628903086702, 1398.76920308148, 30313.93901213447, 31298.843505782326, 31771.0, 31841.23505877418, 31944.68055319417, 41153.79423102865, 49915.75042124766, 50639.987200256524, 50818.491815081376, 51116.0, 557376.0, 562772.0, 563365.0, 563663.0, 563702.0, 26414.603780943686, 26825.195244142706, 26873.0, 27488.725112748616, 27698.86150569234, 47513.73482170943, 48085.797855053905, 48190.27714584214, 48452.51547484621, 48670.5132948666, 37451.81274093721, 38028.42957355586, 39864.50525017336, 40032.99917502086, 40446.59553404428, 38085.0, 38719.70508381742, 39270.607293926696, 39699.206015878946, 40045.79977100096, 37824.0, 37904.62095379011, 38226.42660360041, 38714.80642596769, 38774.0, 33096.50355244625, 34242.82878585591, 34436.0, 34772.478412824334, 35176.82411588028, 24973.500529988934, 25090.498190036462, 25313.0, 25716.228513144637, 26187.476250475265], "value": 20188.98455465785, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [351.49735877694917, 362.30075953409914, 370.9406494960807, 380.8514679275082, 399.9900002499937, 16009.439669611565, 16282.0, 20678.896605516988, 20785.792142078575, 20866.374008779723, 20295.391138265837, 20406.285779997703, 20709.0, 20725.0, 21112.577748445023, 5646.407127251641, 5813.128030795393, 5969.224000879901, 6072.908906366408, 6081.54388420868, 6029.78895738647, 6063.666498342589, 6083.087536869483, 6090.33006369299, 6096.420840020213, 3843.500344955152, 5478.534324582415, 6605.504587155959, 6627.1716035495565, 6690.63201523916, 5932.555058370596, 6523.836904077389, 6641.501887358443, 6646.8005959821285, 6672.499562532829, 4926.531979461945, 4950.43070046942, 5775.220345253379, 5816.854578635568, 5831.825045248649, 12379.319137447437, 12694.0, 12848.486060557567, 12943.223406595635, 13017.34913254343, 2163.3401812447196, 2165.967510487344, 2397.113068164773, 2426.623873299641, 2879.510483217858, 9308.95345523268, 9898.812142542942, 9984.400935943866, 10127.949360253226, 10276.794464110675, 1751.7459968304652, 1760.4454596801966, 1777.5556110972257, 1855.3042609021618, 1879.6240751849548, 89867.9030356323, 112746.0, 127319.0, 128313.0, 132504.0, 10967.287126336763, 11054.336739795637, 11285.605003824827, 11299.740051989635, 11519.654410367702, 14100.576982690536, 14135.0, 14135.78796318056, 14142.080764750464, 14204.502842400467, 8193.713220037389, 8444.240018398374, 8478.533680647624, 8591.656333746705, 8627.43921645091, 7169.964150179216, 8352.12302708219, 8449.02836173848, 8454.577271136419, 8656.39405241627, 3581.1047238190454, 3737.7757334560015, 4211.684123690736, 4394.670399720034, 4446.755428451402, 1839.8160183981495, 1883.8587105966833, 1888.7544619199416, 1890.4423195157462, 1892.668782962976, 842.4818736477023, 852.9275011624081, 874.8512752832033, 940.9576569054409, 983.4001259231862, 20244.493887652927, 20902.714943436862, 20973.161073557192, 21066.368008959755, 21126.47183820417, 799.408437756068, 839.4459656626597, 910.740438974889, 923.9233352934712, 1040.6877936618985, 9020.729378118667, 10162.847557286646, 10391.688249352532, 10640.414777187329, 10952.835707464394, 566980.0, 572804.0, 591540.0, 602150.9892450509, 603070.0, 126568.36715816362, 133844.0, 134363.0, 134668.0, 137512.0, 797.7766225456882, 986.5954958466995, 1006.6979906028163, 1008.2186305613209, 1009.0766948242276, 16231.67536649284, 18575.53561160982, 18597.256109755457, 18954.0522973848, 18960.43118706441, 49877.0, 53782.0, 53898.461015390916, 55009.0, 55282.44717552934, 79099.0, 82452.58773706332, 83757.0, 84413.15586844299, 84480.0, 6141.539384546266, 7174.0, 7247.1303443585, 7436.219196984455, 7940.166282540481, 11009.0, 12677.239365638092, 12789.872101279241, 13167.539136130572, 13194.34028298562, 11066.557337706401, 11243.831342529706, 11377.943110284396, 11509.942450287695, 11521.711957200801, 10899.455027248765, 11230.101591872794, 11286.717832053935, 11351.602693906005, 11514.251573647685, 10790.56837726482, 11262.943685281522, 11443.771124577628, 11541.249818761753, 11598.94200528992, 7839.960800195962, 8006.719764808437, 8091.919080809352, 8107.22981316774, 8122.796930076795, 10341.0, 10880.891191088305, 11252.66242012741, 11282.774344513227, 11330.716732081764], "value": 9300.42207130592, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1341.503643651849, 1345.643404497808, 1476.4610917015289, 1636.2555037457957, 1853.82388673076, 7910.723124690637, 8626.870596941044, 8886.42238254513, 8912.46525208488, 8938.776530586721, 8714.520701361422, 8782.077881822397, 8787.209151176392, 8796.736097917073, 8798.736037918856, 2346.9295921122352, 2373.2998765364173, 2375.964360534593, 2376.489054853206, 2385.8807059646947, 2309.90760369585, 2348.2250857217077, 2363.243761996162, 2370.525894821034, 2371.9169829055986, 2304.5736538740302, 2335.8481698689616, 2339.8245131615113, 2346.85918844869, 2351.0595761695286, 2311.39903625057, 2329.24299602629, 2351.271105957159, 2352.752960939094, 2356.7171939367386, 1923.422973108076, 2337.7662233776655, 2338.8947497362487, 2344.8475849069755, 2345.132301048623, 8781.165789250012, 8782.64869405223, 8855.955720221422, 8865.06916773736, 8871.911280887172, 5215.947840521584, 5409.972950135263, 5410.837674869721, 5420.4850539198715, 5425.348958125051, 3626.057225121461, 3647.7264205184715, 3706.796126213057, 3710.3692372296773, 3715.0898029982827, 954.3128947158035, 1088.9564417423294, 1089.096050278267, 1116.1963386361806, 1136.9829452558217, 11573.132015098901, 11620.6513804586, 11658.18392712519, 11665.650030499099, 11694.239874408137, 4408.228560001987, 4457.9331310030375, 4629.398178236807, 4629.722216667011, 4645.651576131736, 9146.954265228764, 9159.900811902615, 9176.403533770284, 9178.770530736785, 9180.265578753684, 5242.771821975645, 5251.947480525146, 5257.237700533401, 5263.499967503081, 5269.1832765921345, 4213.641840443535, 4421.9336709949375, 4582.427196600425, 4808.975955120272, 4929.186684197139, 3720.6093360197333, 3827.655511003967, 3870.41943708446, 3876.6317199865966, 3947.585503522146, 1145.7078444996473, 1168.2114572663418, 1170.5961443302047, 1180.415694231361, 1185.324365111878, 811.4319976016811, 891.2157301574573, 906.6146887572887, 950.8003319302885, 981.4847205217318, 5805.0, 5906.173135760997, 6018.337982821886, 6022.277326720821, 6029.155918171459, 426.388133029098, 430.23418315398015, 437.15410680333656, 441.5804985264012, 454.57724316386077, 3885.4948856648457, 3945.309570825094, 4038.757674539537, 4040.5555388907196, 4131.710780245354, 10448.477576121164, 10536.894631053592, 10753.193510486744, 10847.837282440769, 10850.186236032328, 10692.572297108029, 10870.891291086988, 10871.565137394417, 10889.564417423215, 10890.61882834097, 7055.541389809646, 8220.671173153007, 8232.17678232172, 8335.249827515552, 8346.374021948379, 5869.413058694096, 5906.143609176559, 5918.0, 5967.582269241111, 6052.092186172109, 10107.292489525662, 10126.493675316353, 10157.796844063225, 10191.847122293024, 10271.537780799732, 8673.783155420913, 9963.551640176065, 10214.846777298495, 10342.172626190037, 10434.791304174027, 7039.612821294878, 7227.7831665050135, 7277.090363704537, 7574.091109066727, 7626.351760100453, 8047.798805029921, 8276.172382761675, 8276.958615206886, 8287.254147126787, 8406.159384061544, 7408.925910740823, 7507.88738168917, 7530.736424225126, 7624.275693809301, 7677.731279405194, 7378.16851569423, 7409.777706668809, 7415.369693575891, 7429.702811887464, 7577.848443031219, 6934.514583979073, 7094.787156385317, 7097.396721278749, 7098.396636285767, 7103.041089452858, 5044.8991020178655, 5058.443571207091, 5087.567556757717, 5119.436861945258, 5225.817096401608, 4037.354023356249, 5074.467180946095, 5124.410692770379, 5180.715060671549, 5364.329458817648], "value": 4470.578968312862, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.4746803222232, 301.42578388170534, 303.42349535881834, 305.90669845697056, 308.0957390060172, 4090.2637525245445, 4101.815418306176, 4309.655227581794, 5083.79664813407, 5285.920711189336, 4313.741175529471, 5258.895631917302, 5262.92105618416, 5294.761735721892, 5299.814506492274, 3783.716221283401, 3864.9613503865025, 3953.723239373245, 4004.0990777075217, 4038.717289789716, 3971.543272523668, 3993.6206060424215, 4006.879793606182, 4015.9397609035886, 4044.725911337919, 3541.6718730476073, 3926.9018274543223, 3955.347367684343, 3971.682265418774, 3977.6618987386105, 3295.538624592559, 3661.9450708239397, 3679.3745063339297, 3744.812759362021, 3771.396576547739, 3475.079104037432, 3482.3557641836087, 3483.686468217847, 3489.3893568625645, 3493.877714279988, 4100.835966561334, 5062.974685126587, 5077.695338279678, 5079.66982146163, 5102.489751024905, 3962.8018599069933, 4005.499312585927, 4319.76241306728, 4378.978105109486, 4476.798544065525, 4572.468223145251, 5272.209168624699, 5426.902688470219, 5440.007995602403, 5463.590230732671, 3565.3064794222796, 3690.3910854708943, 3836.3286424875814, 3840.5391353037535, 3858.9614103858885, 4547.977260113678, 4937.901241975176, 5764.365919748824, 5786.681732504752, 5793.594448388614, 3429.9657003429647, 3882.747621404656, 3892.922141557153, 3905.1994341159993, 3911.8630847920185, 3332.683395077464, 3356.278400143981, 3970.900727481778, 3973.9403908940812, 3991.042149884007, 4001.779902105412, 4563.908721825611, 4574.4739354973935, 4609.700369475973, 4616.446026476844, 4715.976420117878, 5201.219817027476, 5211.843644690665, 5473.698946557977, 5600.383957764642, 1139.5100106953923, 1348.8313960754906, 1358.2342954159553, 1361.1968938326436, 1371.2047012732592, 751.3163021650272, 800.7068584236532, 806.3629732511367, 806.9031716193977, 813.9540690213048, 4754.429468463806, 4839.491853355417, 5003.699778013331, 5016.598672106223, 5019.42276638184, 5476.424975377608, 5515.944840551543, 5671.914921276184, 5677.37548869624, 5680.5171560417, 4376.518582955872, 4846.909445374762, 4955.90088198234, 5128.871778205575, 5164.612654050961, 2492.9031226260386, 2683.8325328482174, 2923.1961210667237, 2990.7158819912074, 3006.8195908245575, 4364.978175109104, 5065.0875746445945, 5582.637128586629, 5830.329512106077, 5869.236999190078, 5160.716160611128, 5462.0, 5539.030669632798, 5812.854678633068, 5876.294844618673, 5139.89492259166, 5950.642961422242, 5954.821355359346, 5974.581779275409, 5990.371011043865, 5513.724313784375, 5693.8291851242875, 5754.424557544379, 5762.769489220552, 5798.739056742541, 4678.766061696833, 4740.502247263988, 5613.298337707786, 5669.433056694297, 5680.204771331934, 5154.329937108152, 5354.3574771027725, 5931.673757943182, 5941.376155503783, 5955.553333499918, 3156.352947645773, 3616.0, 3806.3719486284454, 3821.0, 3828.923421531498, 5088.949110508847, 5328.307320048369, 5673.489385955284, 5696.344920334214, 5705.914411283751, 5412.485813847678, 5536.44979405767, 5604.740397298001, 5604.8038318658655, 5622.662640241435, 4615.861524154281, 5346.144616861284, 5540.587150276654, 5586.776528938797, 5610.194902548726, 4503.864884053483, 5177.508136727005, 5459.716966512787, 5472.781088756406, 5499.3675727291875, 5265.367736001852, 5491.697956612425, 5500.889982200253, 5533.557315414677, 5586.709449125839, 4309.312430099884, 4320.025576469593, 4860.92708609364, 5403.757135858853, 5451.972740136433], "value": 3977.172049027558, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [713.1727196452116, 758.6965213914438, 770.3798442253986, 827.8013276813563, 849.8895143631327, 15746.055236685806, 16584.170791460423, 16593.66812663746, 16593.83406165938, 16609.0, 14548.490802821903, 14805.92597037016, 14824.29392651837, 15051.473198438056, 15070.547883563484, 844.2697067037, 873.829603227371, 874.3748220022683, 876.1238761238761, 876.6011464783509, 685.9279775623562, 710.4174576846988, 841.907390187082, 869.447900583128, 875.4484674654958, 908.5321059654283, 909.4043401571981, 909.8726178335005, 910.0762725833251, 910.8542633178693, 900.6577500549782, 900.8108297257584, 901.134910485931, 902.4630344944748, 902.7652810269311, 521.78606771224, 542.0243561589164, 652.4519403700914, 672.0960308385214, 678.0101052463409, 14721.926390368088, 14798.40806367744, 14814.407423703038, 14824.629384265372, 14826.703465930728, 11178.664640060812, 13763.174209547458, 13814.309284535733, 13886.305684715724, 14293.213873236968, 12483.625491235278, 12668.746625067448, 12677.936610316981, 12726.363681815872, 13333.333333333294, 4564.748938808364, 4582.450105987269, 4680.555347242007, 4794.041191761662, 4797.832075877328, 122932.0, 124373.37813310875, 124816.0, 124959.9999999991, 125055.74944250532, 10989.725256868642, 10992.28550144238, 11095.056920161715, 11120.61077862271, 11293.0, 21549.784502154776, 21572.46068848291, 21596.46008849791, 21624.0, 21634.350969470943, 6903.482238832108, 7759.534427934342, 7995.640196191069, 8077.717279895175, 8166.469179503313, 4331.783410829509, 4417.933730994037, 4502.909941801211, 4615.3999980003055, 4617.815287388534, 3414.470757032664, 3441.380551500704, 3529.3294274087843, 3536.133647256387, 3538.716902647782, 634.6763150793129, 649.7563413719855, 658.9637569933603, 660.5838321857199, 676.24936320684, 583.270911360799, 610.6122612141322, 624.8344188789974, 635.7234602947733, 641.5605310362416, 8533.701320453754, 8741.30069594431, 9078.682246121354, 9176.220021298264, 9194.632214711337, 605.6729366142322, 620.7548018532655, 625.1341891480332, 634.2104080419907, 637.5951270942985, 4307.8276868924895, 4459.464864216315, 4486.730796152241, 4496.505384407713, 4500.482444518857, 69921.65039174771, 104571.43142852864, 105382.0, 105446.0, 152097.0, 108939.91060089457, 136309.0, 136844.0, 137149.0, 141721.0, 100552.0, 102177.48911255396, 122332.0, 123064.38467807783, 125026.0, 7520.586367749826, 9579.281553883347, 9860.0, 9904.851427228454, 10168.542415591168, 53596.0, 56633.37232670539, 56962.86704231307, 60156.0, 61220.08169877366, 85424.71862921937, 88480.55759721418, 90019.0, 90061.64907526523, 91439.08560914306, 13270.071095023255, 13333.466661333827, 13400.731985360044, 13424.664383390493, 13624.318784060559, 11683.649490515298, 15007.174605396807, 15132.016418933175, 15211.847881521044, 15903.045817251003, 13074.607761767162, 13955.092918960236, 14140.646483837574, 14177.716445670823, 14342.426302947766, 13686.794698079337, 13927.23400212998, 14096.788548171991, 14124.78812817788, 14545.709085818437, 13567.932160339134, 13756.0, 13964.650883727989, 14396.784048239075, 14441.205733684748, 11393.843177139086, 11412.144089193178, 11740.0, 11747.530098795953, 11820.822687659864, 11290.619328211696, 11491.54033838637, 11652.708682283012, 11687.357195354338, 11705.473253703774], "value": 7854.278302950394, "unit": "score"}], "name": "dromaeo_css", "value": 7296.3404108227005, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 167.0, 152.0, 118.0, 112.0, 113.0, 112.0, 90.0, 121.0, 168.0], "value": 128.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 107.0, 131.0, 159.0, 108.0, 128.0, 128.0, 117.0, 180.0, 109.0], "value": 131.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [191.0, 221.0, 198.0, 263.0, 184.0, 186.0, 185.0, 186.0, 181.0, 186.0], "value": 198.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 110.0, 94.0, 57.0, 61.0, 62.0, 87.0, 92.0, 56.0], "value": 76.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 133.0, 72.0, 95.0, 110.0, 146.0, 69.0, 69.0, 115.0], "value": 97.8, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 90.0, 87.0, 89.0, 87.0, 89.0, 87.0, 89.0, 87.0], "value": 87.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 94.0, 94.0, 96.0, 94.0, 93.0, 94.0, 95.0, 95.0, 95.0], "value": 94.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 91.0, 103.0, 84.0, 116.0, 133.0, 137.0, 85.0, 86.0, 84.0], "value": 100.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 53.0, 53.0, 55.0, 54.0, 56.0, 55.0, 55.0, 55.0, 55.0], "value": 54.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 65.0, 74.0, 74.0, 76.0, 73.0, 69.0, 70.0, 71.0], "value": 70.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 85.0, 86.0, 86.0, 88.0, 84.0, 83.0, 87.0, 89.0, 84.0], "value": 85.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 138.0, 136.0, 136.0, 137.0, 138.0, 140.0, 139.0, 206.0, 138.0], "value": 144.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 175.0, 234.0, 169.0, 167.0, 162.0, 179.0, 172.0, 167.0, 164.0], "value": 175.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 66.0, 67.0, 67.0, 67.0, 67.0, 66.0, 68.0, 66.0, 66.0], "value": 66.7, "unit": "score"}], "name": "kraken", "value": 1513.7999999999997, "alertThreshold": 2.0}]} 12:01:32 INFO - Return code: 0 12:01:32 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 12:01:32 INFO - # TBPL SUCCESS # 12:01:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:01:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:01:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:01:32 INFO - 2016-08-25 12:01:32.104 screenresolution[1673:9781] starting screenresolution argv=screenresolution get 12:01:32 INFO - 2016-08-25 12:01:32.107 screenresolution[1673:9781] Display 0: 1600x1200x32@60 12:01:32 INFO - 2016-08-25 12:01:32.113 screenresolution[1674:9784] starting screenresolution argv=screenresolution list 12:01:32 INFO - Available Modes on Display 0 12:01:32 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:01:32 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:01:32 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:01:32 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:01:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:01:32 INFO - Intel Iris: 12:01:32 INFO - Chipset Model: Intel Iris 12:01:32 INFO - Type: GPU 12:01:32 INFO - Bus: Built-In 12:01:32 INFO - VRAM (Dynamic, Max): 1536 MB 12:01:32 INFO - Vendor: Intel (0x8086) 12:01:32 INFO - Device ID: 0x0a2e 12:01:32 INFO - Revision ID: 0x0009 12:01:32 INFO - Displays: 12:01:32 INFO - Display: 12:01:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:01:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:01:32 INFO - Display Serial Number: 1600x1200 60 12:01:32 INFO - Main Display: Yes 12:01:32 INFO - Mirror: Off 12:01:32 INFO - Online: Yes 12:01:32 INFO - Rotation: Supported 12:01:32 INFO - Return code: 0 12:01:32 INFO - Running post-action listener: _resource_record_post_action 12:01:32 INFO - [mozharness: 2016-08-25 19:01:32.350010Z] Finished run-tests step (success) 12:01:32 INFO - Running post-run listener: _resource_record_post_run 12:01:32 INFO - Total resource usage - Wall time: 1214s; CPU: 53.0%; Read bytes: 37213184; Write bytes: 704071168; Read time: 241; Write time: 1982 12:01:32 INFO - TinderboxPrint: CPU usage
53.5% 12:01:32 INFO - TinderboxPrint: I/O read bytes / time
37,213,184 / 241 12:01:32 INFO - TinderboxPrint: I/O write bytes / time
704,071,168 / 1,982 12:01:32 INFO - TinderboxPrint: CPU idle
2,259.0 (46.5%) 12:01:32 INFO - TinderboxPrint: CPU system
975.9 (20.1%) 12:01:32 INFO - TinderboxPrint: CPU user
1,622.9 (33.4%) 12:01:32 INFO - TinderboxPrint: Swap in / out
443,146,240 / 0 12:01:32 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 387246592; Write bytes: 381147136; Read time: 29429; Write time: 809 12:01:32 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 36258816; Write bytes: 295202304; Read time: 230; Write time: 1109 12:01:32 INFO - Running post-run listener: _upload_blobber_files 12:01:32 INFO - Blob upload gear active. 12:01:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 12:01:32 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 12:01:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 12:01:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 12:01:33 INFO - (blobuploader) - INFO - Open directory for files ... 12:01:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 12:01:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:01:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 12:01:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:01:34 INFO - (blobuploader) - INFO - Done attempting. 12:01:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 12:01:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:01:35 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 12:01:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:01:35 INFO - (blobuploader) - INFO - Done attempting. 12:01:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 12:01:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 12:01:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 12:01:37 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 12:01:37 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 12:01:37 INFO - (blobuploader) - INFO - Done attempting. 12:01:37 INFO - (blobuploader) - INFO - Iteration through files over. 12:01:37 INFO - Return code: 0 12:01:37 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 12:01:37 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 12:01:37 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/37e27a3e493a9ed8b44ca3fb5d5a0450060e487eb6b98da6978098567254dec47511ae4912151cbf77f01ed71c9d8fbc7cfec7ff77866bb7f6dd9142b5b05e00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c4a47bae00c3a53c865589f3b54632bf6ae95ce5009aefb241c0234236f1b492ba9dd1f65649775b89c32a9eb262f92033d3b5e5bf3de02e577b3af85f97250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2c0b787601048fecf1772ece6ab2bb4cadb59a38596ff8ab98bb54b1fc5688c6ac2aeddc7e3ea99eb385d61359028accff433f69fe131094b731b6ec29e3cfac"} 12:01:37 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 12:01:37 INFO - Writing to file /builds/slave/test/properties/blobber_files 12:01:37 INFO - Contents: 12:01:37 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/37e27a3e493a9ed8b44ca3fb5d5a0450060e487eb6b98da6978098567254dec47511ae4912151cbf77f01ed71c9d8fbc7cfec7ff77866bb7f6dd9142b5b05e00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c4a47bae00c3a53c865589f3b54632bf6ae95ce5009aefb241c0234236f1b492ba9dd1f65649775b89c32a9eb262f92033d3b5e5bf3de02e577b3af85f97250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2c0b787601048fecf1772ece6ab2bb4cadb59a38596ff8ab98bb54b1fc5688c6ac2aeddc7e3ea99eb385d61359028accff433f69fe131094b731b6ec29e3cfac"} 12:01:37 INFO - Running post-run listener: copy_logs_to_upload_dir 12:01:37 INFO - Copying logs to upload dir... 12:01:37 INFO - mkdir: /builds/slave/test/build/upload/logs 12:01:37 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1276.286320 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 16 secs) (at 2016-08-25 12:01:37.997959) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 12:01:38.001170) ========= 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.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/37e27a3e493a9ed8b44ca3fb5d5a0450060e487eb6b98da6978098567254dec47511ae4912151cbf77f01ed71c9d8fbc7cfec7ff77866bb7f6dd9142b5b05e00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c4a47bae00c3a53c865589f3b54632bf6ae95ce5009aefb241c0234236f1b492ba9dd1f65649775b89c32a9eb262f92033d3b5e5bf3de02e577b3af85f97250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2c0b787601048fecf1772ece6ab2bb4cadb59a38596ff8ab98bb54b1fc5688c6ac2aeddc7e3ea99eb385d61359028accff433f69fe131094b731b6ec29e3cfac"} build_url:https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011051 build_url: 'https://queue.taskcluster.net/v1/task/eul7sYKpR2uritKPTJTx-Q/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/37e27a3e493a9ed8b44ca3fb5d5a0450060e487eb6b98da6978098567254dec47511ae4912151cbf77f01ed71c9d8fbc7cfec7ff77866bb7f6dd9142b5b05e00", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/c4a47bae00c3a53c865589f3b54632bf6ae95ce5009aefb241c0234236f1b492ba9dd1f65649775b89c32a9eb262f92033d3b5e5bf3de02e577b3af85f97250f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2c0b787601048fecf1772ece6ab2bb4cadb59a38596ff8ab98bb54b1fc5688c6ac2aeddc7e3ea99eb385d61359028accff433f69fe131094b731b6ec29e3cfac"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-25 12:01:38.034189) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 12:01:38.034621) ========= 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.7Q8Pp2VPQs/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.786SUlC58b/Listeners TMPDIR=/var/folders/3y/bnrkybh942z2nnybc81cy8_r00000w/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.005295 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-25 12:01:38.083870) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 12:01:38.084225) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-25 12:01:38.084608) ========= ========= Total master_lag: 0.19 =========