builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0134 starttime: 1474521780.4 results: success (0) buildid: 20160921173859 builduid: ee6c993604504537a011b94dcfbaba06 revision: 3b8cf1e729ef97ed49808f980bed542b81a3631e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.397434) ========= master: http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.397827) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.398108) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.423021) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.423281) ========= 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.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-21 22:23:00-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.8M=0.001s 2016-09-21 22:23:00 (14.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.126583 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.572894) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.573217) ========= 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.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.029552 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:00.622902) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 22:23:00.623411) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 3b8cf1e729ef97ed49808f980bed542b81a3631e --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 3b8cf1e729ef97ed49808f980bed542b81a3631e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-21 22:23:00,869 truncating revision to first 12 chars 2016-09-21 22:23:00,869 Setting DEBUG logging. 2016-09-21 22:23:00,870 attempt 1/10 2016-09-21 22:23:00,870 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/3b8cf1e729ef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-21 22:23:01,538 unpacking tar archive at: larch-3b8cf1e729ef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942917 ========= master_lag: 0.18 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-21 22:23:01.746340) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:01.746692) ========= script_repo_revision: 3b8cf1e729ef97ed49808f980bed542b81a3631e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:01.747132) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:01.747453) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-21 22:23:01.766733) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-21 22:23:01.767021) ========= /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.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:01 INFO - MultiFileLogger online at 20160921 22:23:01 in /builds/slave/test 22:23:01 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 22:23:01 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 22:23:01 INFO - {'append_to_log': False, 22:23:01 INFO - 'base_work_dir': '/builds/slave/test', 22:23:01 INFO - 'blob_upload_branch': 'Larch', 22:23:01 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 22:23:01 INFO - 'branch': 'Larch', 22:23:01 INFO - 'buildbot_json_path': 'buildprops.json', 22:23:01 INFO - 'config_files': ('talos/mac_config.py',), 22:23:01 INFO - 'default_actions': ('clobber', 22:23:01 INFO - 'read-buildbot-config', 22:23:01 INFO - 'download-and-extract', 22:23:01 INFO - 'populate-webroot', 22:23:01 INFO - 'create-virtualenv', 22:23:01 INFO - 'install', 22:23:01 INFO - 'run-tests'), 22:23:01 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 22:23:01 INFO - 'download_minidump_stackwalk': True, 22:23:01 INFO - 'download_symbols': 'ondemand', 22:23:01 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 22:23:01 INFO - 'tooltool.py': '/tools/tooltool.py', 22:23:01 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 22:23:01 INFO - '/tools/misc-python/virtualenv.py')}, 22:23:01 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 22:23:01 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 22:23:01 INFO - 'installer_path': 'installer.exe', 22:23:01 INFO - 'log_level': 'info', 22:23:01 INFO - 'log_name': 'talos', 22:23:01 INFO - 'log_to_console': True, 22:23:01 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 22:23:01 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 22:23:01 INFO - 'opt_config_files': (), 22:23:01 INFO - 'pip_index': False, 22:23:01 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:01 INFO - 'cmd': ('bash', 22:23:01 INFO - '-c', 22:23:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:01 INFO - 'enabled': True, 22:23:01 INFO - 'halt_on_failure': False, 22:23:01 INFO - 'name': 'check_screen_resolution'},), 22:23:01 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 22:23:01 INFO - 'cmd': ('bash', 22:23:01 INFO - '-c', 22:23:01 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 22:23:01 INFO - 'enabled': True, 22:23:01 INFO - 'halt_on_failure': False, 22:23:01 INFO - 'name': 'check_screen_resolution'},), 22:23:01 INFO - 'run_cmd_checks_enabled': True, 22:23:01 INFO - 'sps_profile': False, 22:23:01 INFO - 'sps_profile_interval': 0, 22:23:01 INFO - 'suite': 'dromaeojs', 22:23:01 INFO - 'system_bits': '32', 22:23:01 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 22:23:01 INFO - 'title': 't-yosemite-r7-0134', 22:23:01 INFO - 'tooltool_cache': '/builds/tooltool_cache', 22:23:01 INFO - 'use_talos_json': True, 22:23:01 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 22:23:01 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 22:23:01 INFO - 'work_dir': 'build'} 22:23:02 INFO - [mozharness: 2016-09-22 05:23:01.999939Z] Running clobber step. 22:23:02 INFO - Running pre-action listener: _resource_record_pre_action 22:23:02 INFO - Running main action method: clobber 22:23:02 INFO - rmtree: /builds/slave/test/build 22:23:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 22:23:04 INFO - Running post-action listener: _resource_record_post_action 22:23:04 INFO - [mozharness: 2016-09-22 05:23:04.249995Z] Finished clobber step (success) 22:23:04 INFO - [mozharness: 2016-09-22 05:23:04.250124Z] Running read-buildbot-config step. 22:23:04 INFO - Running pre-action listener: _resource_record_pre_action 22:23:04 INFO - Running main action method: read_buildbot_config 22:23:04 INFO - Using buildbot properties: 22:23:04 INFO - { 22:23:04 INFO - "project": "", 22:23:04 INFO - "product": "firefox", 22:23:04 INFO - "script_repo_revision": "production", 22:23:04 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 22:23:04 INFO - "repository": "", 22:23:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 22:23:04 INFO - "buildid": "20160921173859", 22:23:04 INFO - "pgo_build": "False", 22:23:04 INFO - "basedir": "/builds/slave/test", 22:23:04 INFO - "buildnumber": 4, 22:23:04 INFO - "slavename": "t-yosemite-r7-0134", 22:23:04 INFO - "master": "http://buildbot-master107.bb.releng.scl3.mozilla.com:8201/", 22:23:04 INFO - "platform": "yosemite_r7", 22:23:04 INFO - "scheduler": "tests-larch-macosx64-talos", 22:23:04 INFO - "branch": "larch", 22:23:04 INFO - "repo_path": "projects/larch", 22:23:04 INFO - "revision": "3b8cf1e729ef97ed49808f980bed542b81a3631e", 22:23:04 INFO - "stage_platform": "macosx64", 22:23:04 INFO - "builduid": "ee6c993604504537a011b94dcfbaba06", 22:23:04 INFO - "slavebuilddir": "test" 22:23:04 INFO - } 22:23:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 22:23:04 INFO - Running post-action listener: _resource_record_post_action 22:23:04 INFO - [mozharness: 2016-09-22 05:23:04.253698Z] Finished read-buildbot-config step (success) 22:23:04 INFO - [mozharness: 2016-09-22 05:23:04.253840Z] Running download-and-extract step. 22:23:04 INFO - Running pre-action listener: _resource_record_pre_action 22:23:04 INFO - Running main action method: download_and_extract 22:23:04 INFO - mkdir: /builds/slave/test/build/tests 22:23:04 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')]} 22:23:04 INFO - trying https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 22:23:04 INFO - Downloading https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:23:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 22:23:05 INFO - Downloaded 1199 bytes. 22:23:05 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 22:23:05 INFO - Using the following test package requirements: 22:23:05 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:23:05 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 22:23:05 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 22:23:05 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'jsshell-mac.zip'], 22:23:05 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 22:23:05 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 22:23:05 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 22:23:05 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 22:23:05 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 22:23:05 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 22:23:05 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 22:23:05 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 22:23:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:23:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:05 INFO - Fetch https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:23:06 INFO - Expected file size: 16432476 22:23:13 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 22:23:13 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 22:23:13 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 22:23:13 INFO - Fetch https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 22:23:14 INFO - Expected file size: 16432476 22:23:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 22:23:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 22:23:21 INFO - Fetch https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 22:23:21 INFO - Expected file size: 14414059 22:23: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')]} 22:23:23 INFO - trying https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:23:23 INFO - Downloading https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 22:23:23 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 22:23:26 INFO - Downloaded 111340073 bytes. 22:23:26 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:23:26 INFO - mkdir: /builds/slave/test/properties 22:23:26 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 22:23:26 INFO - Writing to file /builds/slave/test/properties/build_url 22:23:26 INFO - Contents: 22:23:26 INFO - build_url:https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 22:23:27 INFO - Running post-action listener: _resource_record_post_action 22:23:27 INFO - Running post-action listener: set_extra_try_arguments 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.566477Z] Finished download-and-extract step (success) 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.566601Z] Running populate-webroot step. 22:23:27 INFO - Running pre-action listener: _resource_record_pre_action 22:23:27 INFO - Running main action method: populate_webroot 22:23:27 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 22:23:27 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 22:23:27 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 22:23:27 INFO - '--tppagecycles', 22:23:27 INFO - '7'], 22:23:27 INFO - 'tests': ['tsvgm']}}, 22:23:27 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['tresize', 'tcanvasmark']}, 22:23:27 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 22:23:27 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['dromaeo_css', 'kraken']}, 22:23:27 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 22:23:27 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:27 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 22:23:27 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['damp', 'tps']}, 22:23:27 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'tests': ['damp', 'tps']}, 22:23:27 INFO - 'g3': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['dromaeo_dom']}, 22:23:27 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 22:23:27 INFO - 'g4': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['basic_compositor_video']}, 22:23:27 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 22:23:27 INFO - 'other': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'other-e10s': {'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'other-e10s_l64': {'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['a11yr', 22:23:27 INFO - 'ts_paint', 22:23:27 INFO - 'tpaint', 22:23:27 INFO - 'sessionrestore', 22:23:27 INFO - 'sessionrestore_no_auto_restore', 22:23:27 INFO - 'tabpaint']}, 22:23:27 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['tsvgx', 22:23:27 INFO - 'tsvgr_opacity', 22:23:27 INFO - 'tart', 22:23:27 INFO - 'tscrollx', 22:23:27 INFO - 'cart']}, 22:23:27 INFO - 'svgr-e10s': {'tests': ['tsvgx', 22:23:27 INFO - 'tsvgr_opacity', 22:23:27 INFO - 'tart', 22:23:27 INFO - 'tscrollx', 22:23:27 INFO - 'cart']}, 22:23:27 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'talos_options': ['--disable-e10s'], 22:23:27 INFO - 'tests': ['tp5o']}, 22:23:27 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'tests': ['tp5o']}, 22:23:27 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'talos_options': ['--disable-e10s', 22:23:27 INFO - '--xperf_path', 22:23:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:27 INFO - 'tests': ['tp5n']}, 22:23:27 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 22:23:27 INFO - 'talos_options': ['--xperf_path', 22:23:27 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 22:23:27 INFO - 'tests': ['tp5n']}}, 22:23:27 INFO - 'talos.zip': {'path': '', 22:23:27 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 22:23:27 INFO - Running post-action listener: _resource_record_post_action 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.585648Z] Finished populate-webroot step (success) 22:23:27 INFO - [mozharness: 2016-09-22 05:23:27.585751Z] Running create-virtualenv step. 22:23:27 INFO - Running pre-action listener: _resource_record_pre_action 22:23:27 INFO - Running main action method: create_virtualenv 22:23:27 INFO - Creating virtualenv /builds/slave/test/build/venv 22:23:27 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 22:23:27 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 22:23:27 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 22:23:27 INFO - Using real prefix '/tools/python27' 22:23:27 INFO - New python executable in /builds/slave/test/build/venv/bin/python 22:23:28 INFO - Installing distribute.............................................................................................................................................................................................done. 22:23:31 INFO - Installing pip.................done. 22:23:31 INFO - Return code: 0 22:23:31 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:31 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 22:23:31 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 22:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:31 INFO - 'HOME': '/Users/cltbld', 22:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:31 INFO - 'LOGNAME': 'cltbld', 22:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:31 INFO - 'MOZ_NO_REMOTE': '1', 22:23:31 INFO - 'NO_EM_RESTART': '1', 22:23:31 INFO - 'PAGER': '/bin/cat', 22:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:31 INFO - 'PWD': '/builds/slave/test', 22:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:31 INFO - 'SHELL': '/bin/bash', 22:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:31 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:31 INFO - 'USER': 'cltbld', 22:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:31 INFO - 'XPC_FLAGS': '0x0', 22:23:31 INFO - 'XPC_SERVICE_NAME': '0', 22:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:31 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 22:23:31 INFO - Cleaning up... 22:23:31 INFO - Return code: 0 22:23:31 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:31 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:31 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 22:23:31 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 22:23:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:31 INFO - 'HOME': '/Users/cltbld', 22:23:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:31 INFO - 'LOGNAME': 'cltbld', 22:23:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:31 INFO - 'MOZ_NO_REMOTE': '1', 22:23:31 INFO - 'NO_EM_RESTART': '1', 22:23:31 INFO - 'PAGER': '/bin/cat', 22:23:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:31 INFO - 'PWD': '/builds/slave/test', 22:23:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:31 INFO - 'SHELL': '/bin/bash', 22:23:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:31 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:31 INFO - 'USER': 'cltbld', 22:23:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:31 INFO - 'XPC_FLAGS': '0x0', 22:23:31 INFO - 'XPC_SERVICE_NAME': '0', 22:23:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:31 INFO - Downloading/unpacking psutil>=3.1.1 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:31 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 22:23:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 22:23:35 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:35 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:35 INFO - Installing collected packages: psutil 22:23:35 INFO - Running setup.py install for psutil 22:23:35 INFO - building 'psutil._psutil_osx' extension 22:23:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 22:23:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 22:23:35 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 22:23:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 22:23:36 INFO - building 'psutil._psutil_posix' extension 22:23:36 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 22:23:36 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 22:23:36 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 22:23:36 INFO - ^ 22:23:36 INFO - 1 warning generated. 22:23:36 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 22:23:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 22:23:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 22:23:36 INFO - Successfully installed psutil 22:23:36 INFO - Cleaning up... 22:23:36 INFO - Return code: 0 22:23:36 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 22:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:36 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 22:23:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 22:23:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:36 INFO - 'HOME': '/Users/cltbld', 22:23:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:36 INFO - 'LOGNAME': 'cltbld', 22:23:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:36 INFO - 'MOZ_NO_REMOTE': '1', 22:23:36 INFO - 'NO_EM_RESTART': '1', 22:23:36 INFO - 'PAGER': '/bin/cat', 22:23:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:36 INFO - 'PWD': '/builds/slave/test', 22:23:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:36 INFO - 'SHELL': '/bin/bash', 22:23:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:36 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:36 INFO - 'USER': 'cltbld', 22:23:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:36 INFO - 'XPC_FLAGS': '0x0', 22:23:36 INFO - 'XPC_SERVICE_NAME': '0', 22:23:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:36 INFO - Downloading/unpacking mozsystemmonitor==0.3 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:36 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 22:23:39 INFO - Downloading mozsystemmonitor-0.3.tar.gz 22:23:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 22:23:39 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 22:23:39 INFO - Installing collected packages: mozsystemmonitor 22:23:39 INFO - Running setup.py install for mozsystemmonitor 22:23:39 INFO - Successfully installed mozsystemmonitor 22:23:39 INFO - Cleaning up... 22:23:39 INFO - Return code: 0 22:23:39 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 22:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 22:23:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 22:23:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:39 INFO - 'HOME': '/Users/cltbld', 22:23:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:39 INFO - 'LOGNAME': 'cltbld', 22:23:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:39 INFO - 'MOZ_NO_REMOTE': '1', 22:23:39 INFO - 'NO_EM_RESTART': '1', 22:23:39 INFO - 'PAGER': '/bin/cat', 22:23:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:39 INFO - 'PWD': '/builds/slave/test', 22:23:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:39 INFO - 'SHELL': '/bin/bash', 22:23:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:39 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:39 INFO - 'USER': 'cltbld', 22:23:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:39 INFO - 'XPC_FLAGS': '0x0', 22:23:39 INFO - 'XPC_SERVICE_NAME': '0', 22:23:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:39 INFO - Downloading/unpacking jsonschema==2.5.1 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:39 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 22:23:42 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 22:23:42 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 22:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:42 INFO - Downloading functools32-3.2.3-2.tar.gz 22:23:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 22:23:42 INFO - warning: no files found matching '*.txt' 22:23:42 INFO - no previously-included directories found matching 'build' 22:23:42 INFO - no previously-included directories found matching 'dist' 22:23:42 INFO - no previously-included directories found matching '.git*' 22:23:42 INFO - Installing collected packages: jsonschema, functools32 22:23:42 INFO - Running setup.py install for functools32 22:23:42 INFO - warning: no files found matching '*.txt' 22:23:42 INFO - no previously-included directories found matching 'build' 22:23:42 INFO - no previously-included directories found matching 'dist' 22:23:42 INFO - no previously-included directories found matching '.git*' 22:23:42 INFO - Successfully installed jsonschema functools32 22:23:42 INFO - Cleaning up... 22:23:42 INFO - Return code: 0 22:23:42 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 22:23:42 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')]} 22:23:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:42 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')]} 22:23:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:42 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:42 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 22:23:42 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 22:23:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:42 INFO - 'HOME': '/Users/cltbld', 22:23:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:42 INFO - 'LOGNAME': 'cltbld', 22:23:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:42 INFO - 'MOZ_NO_REMOTE': '1', 22:23:42 INFO - 'NO_EM_RESTART': '1', 22:23:42 INFO - 'PAGER': '/bin/cat', 22:23:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:42 INFO - 'PWD': '/builds/slave/test', 22:23:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:42 INFO - 'SHELL': '/bin/bash', 22:23:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:42 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:42 INFO - 'USER': 'cltbld', 22:23:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:42 INFO - 'XPC_FLAGS': '0x0', 22:23:42 INFO - 'XPC_SERVICE_NAME': '0', 22:23:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:43 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 22:23:43 INFO - Cleaning up... 22:23:43 INFO - Return code: 0 22:23:43 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 22:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 22:23:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 22:23:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:43 INFO - 'HOME': '/Users/cltbld', 22:23:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:43 INFO - 'LOGNAME': 'cltbld', 22:23:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:43 INFO - 'MOZ_NO_REMOTE': '1', 22:23:43 INFO - 'NO_EM_RESTART': '1', 22:23:43 INFO - 'PAGER': '/bin/cat', 22:23:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:43 INFO - 'PWD': '/builds/slave/test', 22:23:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:43 INFO - 'SHELL': '/bin/bash', 22:23:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:43 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:43 INFO - 'USER': 'cltbld', 22:23:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:43 INFO - 'XPC_FLAGS': '0x0', 22:23:43 INFO - 'XPC_SERVICE_NAME': '0', 22:23:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:43 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:43 INFO - Downloading/unpacking blobuploader==1.2.4 22:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:45 INFO - Downloading blobuploader-1.2.4.tar.gz 22:23:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 22:23:46 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 22:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 22:23:46 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 22:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 22:23:46 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 22:23:46 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 22:23:47 INFO - Downloading docopt-0.6.1.tar.gz 22:23:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 22:23:47 INFO - Installing collected packages: blobuploader, requests, docopt 22:23:47 INFO - Running setup.py install for blobuploader 22:23:47 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 22:23:47 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 22:23:47 INFO - Running setup.py install for requests 22:23:47 INFO - Running setup.py install for docopt 22:23:47 INFO - Successfully installed blobuploader requests docopt 22:23:47 INFO - Cleaning up... 22:23:48 INFO - Return code: 0 22:23:48 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:48 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:48 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:48 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 22:23:48 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 22:23:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:48 INFO - 'HOME': '/Users/cltbld', 22:23:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:48 INFO - 'LOGNAME': 'cltbld', 22:23:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:48 INFO - 'MOZ_NO_REMOTE': '1', 22:23:48 INFO - 'NO_EM_RESTART': '1', 22:23:48 INFO - 'PAGER': '/bin/cat', 22:23:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:48 INFO - 'PWD': '/builds/slave/test', 22:23:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:48 INFO - 'SHELL': '/bin/bash', 22:23:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:48 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:48 INFO - 'USER': 'cltbld', 22:23:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:48 INFO - 'XPC_FLAGS': '0x0', 22:23:48 INFO - 'XPC_SERVICE_NAME': '0', 22:23:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:48 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-axvlbt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-ZtFQDS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-fZaoKN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-nthowx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-YWvxJN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-xSFfzE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:48 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NcMOBn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-MPkHLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-izk_Mc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-2U9c_9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-xRlqaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-FOHIYs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-2Pau60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-gyoD88-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-NArj3B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-O6IRZq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:49 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-cWErya-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:50 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 22:23:50 INFO - Running setup.py install for manifestparser 22:23:50 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozcrash 22:23:50 INFO - Running setup.py install for mozdebug 22:23:50 INFO - Running setup.py install for mozdevice 22:23:50 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Installing dm script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozfile 22:23:50 INFO - Running setup.py install for mozhttpd 22:23:50 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 22:23:50 INFO - Running setup.py install for mozinfo 22:23:51 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Running setup.py install for mozInstall 22:23:51 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Running setup.py install for mozleak 22:23:51 INFO - Running setup.py install for mozlog 22:23:51 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Running setup.py install for moznetwork 22:23:51 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 22:23:51 INFO - Running setup.py install for mozprocess 22:23:51 INFO - Running setup.py install for mozprofile 22:23:52 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 22:23:52 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 22:23:52 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 22:23:52 INFO - Running setup.py install for mozrunner 22:23:52 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 22:23:52 INFO - Running setup.py install for mozscreenshot 22:23:52 INFO - Running setup.py install for moztest 22:23:52 INFO - Running setup.py install for mozversion 22:23:52 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 22:23:52 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 22:23:52 INFO - Cleaning up... 22:23:52 INFO - Return code: 0 22:23:52 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:52 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:52 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 22:23:52 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 22:23:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:52 INFO - 'HOME': '/Users/cltbld', 22:23:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:52 INFO - 'LOGNAME': 'cltbld', 22:23:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:52 INFO - 'MOZ_NO_REMOTE': '1', 22:23:52 INFO - 'NO_EM_RESTART': '1', 22:23:52 INFO - 'PAGER': '/bin/cat', 22:23:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:52 INFO - 'PWD': '/builds/slave/test', 22:23:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:52 INFO - 'SHELL': '/bin/bash', 22:23:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:52 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:52 INFO - 'USER': 'cltbld', 22:23:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:52 INFO - 'XPC_FLAGS': '0x0', 22:23:52 INFO - 'XPC_SERVICE_NAME': '0', 22:23:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 22:23:52 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-UiB58_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-rOPdnZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-sXWiYo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-IYZLI3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-PsjvTY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-vFd107-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-jLouIv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-wkGYog-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-0i6p2W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 22:23:53 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 22:23:53 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-N81_tK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 22:23:53 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 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)) 22:23:53 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-i1835A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 22:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-T7YVFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 22:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-7Jpbo9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 22:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-y7SnVO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 22:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-TSfDVs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 22:23:54 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-qII2jO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 22:23:54 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)) 22:23:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 22:23:54 INFO - Running setup.py (path:/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/pip-1e8uOl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 22:23:54 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)) 22:23:54 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)) 22:23:54 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)) 22:23:54 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)) 22:23:54 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)) 22:23:54 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)) 22:23:54 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23: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 22:23:57 INFO - Downloading blessings-1.6.tar.gz 22:23:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 22:23:57 INFO - Installing collected packages: blessings 22:23:57 INFO - Running setup.py install for blessings 22:23:57 INFO - Successfully installed blessings 22:23:57 INFO - Cleaning up... 22:23:57 INFO - Return code: 0 22:23:57 INFO - Done creating virtualenv /builds/slave/test/build/venv. 22:23:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:57 INFO - Reading from file tmpfile_stdout 22:23:57 INFO - Current package versions: 22:23:57 INFO - blessings == 1.6 22:23:57 INFO - blobuploader == 1.2.4 22:23:57 INFO - docopt == 0.6.1 22:23:57 INFO - functools32 == 3.2.3-2 22:23:57 INFO - jsonschema == 2.5.1 22:23:57 INFO - manifestparser == 1.1 22:23:57 INFO - mozInstall == 1.12 22:23:57 INFO - mozcrash == 0.17 22:23:57 INFO - mozdebug == 0.1 22:23:57 INFO - mozdevice == 0.48 22:23:57 INFO - mozfile == 1.2 22:23:57 INFO - mozhttpd == 0.7 22:23:57 INFO - mozinfo == 0.9 22:23:57 INFO - mozleak == 0.1 22:23:57 INFO - mozlog == 3.4 22:23:57 INFO - moznetwork == 0.27 22:23:57 INFO - mozprocess == 0.23 22:23:57 INFO - mozprofile == 0.28 22:23:57 INFO - mozrunner == 6.12 22:23:57 INFO - mozscreenshot == 0.1 22:23:57 INFO - mozsystemmonitor == 0.3 22:23:57 INFO - moztest == 0.7 22:23:57 INFO - mozversion == 1.4 22:23:57 INFO - psutil == 3.1.1 22:23:57 INFO - requests == 1.2.3 22:23:57 INFO - wsgiref == 0.1.2 22:23:57 INFO - Installing None into virtualenv /builds/slave/test/build/venv 22:23: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')]} 22:23:57 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:57 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23: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')]} 22:23:57 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:57 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:57 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:57 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 22:23:57 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 22:23:57 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:57 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:57 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:57 INFO - 'HOME': '/Users/cltbld', 22:23:57 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:57 INFO - 'LOGNAME': 'cltbld', 22:23:57 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:57 INFO - 'MOZ_NO_REMOTE': '1', 22:23:57 INFO - 'NO_EM_RESTART': '1', 22:23:57 INFO - 'PAGER': '/bin/cat', 22:23:57 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:57 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:57 INFO - 'PWD': '/builds/slave/test', 22:23:57 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:57 INFO - 'SHELL': '/bin/bash', 22:23:57 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:57 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:57 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:57 INFO - 'USER': 'cltbld', 22:23:57 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:57 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:57 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:57 INFO - 'XPC_FLAGS': '0x0', 22:23:57 INFO - 'XPC_SERVICE_NAME': '0', 22:23:57 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 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)) 22:23:58 INFO - Cleaning up... 22:23:58 INFO - Return code: 0 22:23:58 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 22:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 22:23:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 22:23:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 22:23:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 22:23:58 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 0x10a5cb490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a541570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe4e9d95570>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, '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.fRBowlGHEu/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.UQcD2WuTp9/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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 22:23:58 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 22:23:58 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 22:23:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:23:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:23:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:23:58 INFO - 'HOME': '/Users/cltbld', 22:23:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:23:58 INFO - 'LOGNAME': 'cltbld', 22:23:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:23:58 INFO - 'MOZ_NO_REMOTE': '1', 22:23:58 INFO - 'NO_EM_RESTART': '1', 22:23:58 INFO - 'PAGER': '/bin/cat', 22:23:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:23:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:23:58 INFO - 'PWD': '/builds/slave/test', 22:23:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:23:58 INFO - 'SHELL': '/bin/bash', 22:23:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:23:58 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:23:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:23:58 INFO - 'USER': 'cltbld', 22:23:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:23:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:23:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:23:58 INFO - 'XPC_FLAGS': '0x0', 22:23:58 INFO - 'XPC_SERVICE_NAME': '0', 22:23:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:23:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 22:23:58 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 22:23:58 INFO - Cleaning up... 22:23:58 INFO - Return code: 0 22:23:58 INFO - Running post-action listener: _resource_record_post_action 22:23:58 INFO - Running post-action listener: _start_resource_monitoring 22:23:58 INFO - Starting resource monitoring. 22:23:58 INFO - [mozharness: 2016-09-22 05:23:58.332457Z] Finished create-virtualenv step (success) 22:23:58 INFO - [mozharness: 2016-09-22 05:23:58.333484Z] Running install step. 22:23:58 INFO - Running pre-action listener: _resource_record_pre_action 22:23:58 INFO - Running main action method: install 22:23:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 22:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 22:23:58 INFO - Reading from file tmpfile_stdout 22:23:58 INFO - Detecting whether we're running mozinstall >=1.0... 22:23:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 22:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 22:23:58 INFO - Reading from file tmpfile_stdout 22:23:58 INFO - Output received: 22:23:58 INFO - Usage: mozinstall [options] installer 22:23:58 INFO - Options: 22:23:58 INFO - -h, --help show this help message and exit 22:23:58 INFO - -d DEST, --destination=DEST 22:23:58 INFO - Directory to install application into. [default: 22:23:58 INFO - "/builds/slave/test"] 22:23:58 INFO - --app=APP Application being installed. [default: firefox] 22:23:58 INFO - mkdir: /builds/slave/test/build/application 22:23:58 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 22:23:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 22:24:32 INFO - Reading from file tmpfile_stdout 22:24:32 INFO - Output received: 22:24:32 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 22:24:32 INFO - Running post-action listener: _resource_record_post_action 22:24:32 INFO - [mozharness: 2016-09-22 05:24:32.985421Z] Finished install step (success) 22:24:32 INFO - [mozharness: 2016-09-22 05:24:32.985563Z] Running run-tests step. 22:24:32 INFO - Running pre-action listener: _resource_record_pre_action 22:24:32 INFO - Running main action method: run_tests 22:24:32 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:24:32 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:24:32 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:24:33 INFO - 2016-09-21 22:24:32.999 screenresolution[1646:7938] starting screenresolution argv=screenresolution get 22:24:33 INFO - 2016-09-21 22:24:33.002 screenresolution[1646:7938] Display 0: 1600x1200x32@60 22:24:33 INFO - 2016-09-21 22:24:33.008 screenresolution[1647:7940] starting screenresolution argv=screenresolution list 22:24:33 INFO - Available Modes on Display 0 22:24:33 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:24:33 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:24:33 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:24:33 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:24:33 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:24:33 INFO - Intel Iris: 22:24:33 INFO - Chipset Model: Intel Iris 22:24:33 INFO - Type: GPU 22:24:33 INFO - Bus: Built-In 22:24:33 INFO - VRAM (Dynamic, Max): 1536 MB 22:24:33 INFO - Vendor: Intel (0x8086) 22:24:33 INFO - Device ID: 0x0a2e 22:24:33 INFO - Revision ID: 0x0009 22:24:33 INFO - Displays: 22:24:33 INFO - Display: 22:24:33 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:24:33 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:24:33 INFO - Display Serial Number: 1600x1200 60 22:24:33 INFO - Main Display: Yes 22:24:33 INFO - Mirror: Off 22:24:33 INFO - Online: Yes 22:24:33 INFO - Rotation: Supported 22:24:33 INFO - Return code: 0 22:24:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 22:24:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 22:24:33 INFO - Python 2.7.3 22:24:33 INFO - Return code: 0 22:24:33 INFO - grabbing minidump binary from tooltool 22:24:33 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')]} 22:24:33 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 0x10a3dfd30>, '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 0x10a205db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10a3d7b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 22:24:33 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 22:24:33 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 22:24:33 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 22:24:33 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 22:24:33 INFO - Return code: 0 22:24:33 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 22:24:33 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 22:24:33 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 22:24:33 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 22:24:33 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 22:24:33 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/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 22:24:33 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/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0134 --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 22:24:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.fRBowlGHEu/Render', 22:24:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 22:24:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 22:24:33 INFO - 'HOME': '/Users/cltbld', 22:24:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 22:24:33 INFO - 'LOGNAME': 'cltbld', 22:24:33 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 22:24:33 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 22:24:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 22:24:33 INFO - 'MOZ_NO_REMOTE': '1', 22:24:33 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 22:24:33 INFO - 'NO_EM_RESTART': '1', 22:24:33 INFO - 'PAGER': '/bin/cat', 22:24:33 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 22:24:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 22:24:33 INFO - 'PWD': '/builds/slave/test', 22:24:33 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 22:24:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 22:24:33 INFO - 'SHELL': '/bin/bash', 22:24:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.UQcD2WuTp9/Listeners', 22:24:33 INFO - 'TMPDIR': '/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/', 22:24:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 22:24:33 INFO - 'USER': 'cltbld', 22:24:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 22:24:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 22:24:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 22:24:33 INFO - 'XPC_FLAGS': '0x0', 22:24:33 INFO - 'XPC_SERVICE_NAME': '0', 22:24:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 22:24:33 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/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0134', '--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 22:24:33 INFO - mozversion application_buildid: 20160921173859 22:24:33 INFO - mozversion application_changeset: 3b8cf1e729ef97ed49808f980bed542b81a3631e 22:24:33 INFO - mozversion application_display_name: Nightly 22:24:33 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 22:24:33 INFO - mozversion application_name: Firefox 22:24:33 INFO - mozversion application_remotingname: firefox 22:24:33 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 22:24:33 INFO - mozversion application_vendor: Mozilla 22:24:33 INFO - mozversion application_version: 52.0a1 22:24:33 INFO - mozversion platform_buildid: 20160921173859 22:24:33 INFO - mozversion platform_changeset: 3b8cf1e729ef97ed49808f980bed542b81a3631e 22:24:33 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 22:24:33 INFO - mozversion platform_version: 52.0a1 22:24:33 INFO - using testdate: 1474521873 22:24:33 INFO - actual date: 1474521873 22:24:33 INFO - starting webserver on 'localhost:49272' 22:24:33 INFO - SUITE-START | Running 2 tests 22:24:33 INFO - TEST-START | dromaeo_css 22:24:33 INFO - operating with platform_type : mac_ 22:24:33 INFO - Initialising browser for dromaeo_css test... 22:24:33 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpSQV1ly/profile http://localhost:49272/getInfo.html) 22:24:36 INFO - PROCESS | 1657 | __metrics Screen width/height:1600/1200 22:24:36 INFO - PROCESS | 1657 | colorDepth:24 22:24:36 INFO - PROCESS | 1657 | Browser inner width/height: 1024/689 22:24:36 INFO - PROCESS | 1657 | __metrics 22:24:36 INFO - PROCESS | 1657 | 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" 22:24:36 INFO - PROCESS | 1657 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:24:36 INFO - TEST-INFO | 1657: exit 0 22:24:36 INFO - Browser initialized. 22:24:36 INFO - Running cycle 1/1 for dromaeo_css test... 22:24:36 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmpSQV1ly/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:24:49 INFO - PROCESS | 1660 | RSS: Main: 236994560 22:24:49 INFO - PROCESS | 1660 | 22:25:40 INFO - PROCESS | 1660 | 1474521940794 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] 22:27:51 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49272/tests/dromaeo/cssquery-ext.html) 22:27:52 INFO - PROCESS | 1660 | RSS: Main: 394018816 22:27:52 INFO - PROCESS | 1660 | 22:30:56 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-ext.html (next: http://localhost:49272/tests/dromaeo/cssquery-jquery.html) 22:30:56 INFO - PROCESS | 1660 | RSS: Main: 385347584 22:30:56 INFO - PROCESS | 1660 | 22:33:59 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49272/tests/dromaeo/cssquery-mootools.html) 22:33:59 INFO - PROCESS | 1660 | RSS: Main: 342396928 22:33:59 INFO - PROCESS | 1660 | 22:37:02 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49272/tests/dromaeo/cssquery-prototype.html) 22:37:02 INFO - PROCESS | 1660 | RSS: Main: 339488768 22:37:02 INFO - PROCESS | 1660 | 22:40:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49272/tests/dromaeo/cssquery-yui.html) 22:40:06 INFO - PROCESS | 1660 | RSS: Main: 327118848 22:40:06 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49272/tests/dromaeo/cssquery-yui.html (next: http://localhost:49272/tests/dromaeo/cssquery-dojo.html) 22:43:10 INFO - PROCESS | 1660 | RSS: Main: 410091520 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | __start_tp_report 22:43:10 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 22:43:10 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 22:43:10 INFO - PROCESS | 1660 | |i|pagename|runs| 22:43:10 INFO - PROCESS | 1660 | |0;dojo.html;3197.584314039175;3236.190952261934;3262.9999999999986;3293.407186706395;3299.8020118792874;5479;5528.889422211559;5570.944290557093;5579.386267510576;5645.491905728483;5591.664500129995;5609.074502707059;5616.691081990489;5617.775288988446;5631.99999999999;5050.949490505084;5084.694918304895;5098;5104.055749686301;5160.845174644748;5044.848654540351;5093.79624815007;5126.333576635032;5143.0485360208295;5204.219367094947;5144.6655967362185;5180.197069454241;5198.194279886605;5208.374995000586;5208.921866172011;414.47628308365506;5154.329937108189;5155.896882062357;5189.896202075937;5210.583153347723;5070.72111033893;5088.1350170471105;5112.795488180505;5128.897422051576;5193.922091168598;5449.782008719647;5585.050541407971;5586.106223004341;5591.776328946838;5595.440455954372;5093.974530127326;5993.011153159723;6177.629342239435;6202.34875338092;6211.565190436671;6098.099428985301;6118.373546475427;6126.846828829271;6148.508119350441;6157.322694503601;5706.25818643582;5715.628484148517;5716.228309178249;5723.742431590588;5785.218995435646;6295.055741638791;6299.0866324382705;6299.842503937438;6307.180066591313;6334.366563343721;5875.970620146957;5927.614705044159;5940.1683764272175;6049.969750151221;6066.636001839904;6110.938890611037;6116.028315130255;6193.597416167935;6202.193714817045;6202.689865506707;5326.866828329245;5860.531157507475;6108.402780883668;6121.360988904203;6145.600535965237;6195.907061394083;6203.906941395882;6211.3167551569295;6212.9068063979075;6215.533834962396;4773.880652983703;4842.346283251786;4845.41854977405;4848;4908.04293162837;4363.105563359503;4444.888877778081;4460.955390446119;4489.393931819162;4490.842820501267;5650.3502097258515;5672.659640421588;5679.148127780783;5684.8010319638615;5706.657600543981;6212.968935155386;6278.995360742259;6289.150964619809;6299.4330510253385;6305.96847015762;5488.753006114654;5581.05122129239;5584.972075139598;5594.608377413542;5629.549636029108;5319.547838433777;5362.061639213122;5367.731613419313;5367.892642147213;5371.221172929982;6277.7175027123885;6278.183836101369;6295.905561416583;6297.937020629826;6299.622022678654;6119.632822030692;6132.141500190066;6169.598976066542;6233.625982440886;6237.594556354004;6246.312905580292;6247.0629405589525;6296.024116261987;6320.873582528416;6321.715522801393;6171.6297022178815;6178.567500274938;6191.442770150708;6195.318514963441;6197.96901015492;6041.2750469943885;6150.231221097363;6246.281677607133;6264.467520260829;6276.843078923063;5876.44173803488;5936.228290322403;5958.821235362946;5974.761009559569;6060.636361818305;5005.949940500694;6058.606190597597;6086.056661217518;6107.175531303395;6127.172831667668;5882.088276317178;5895.7052147393315;5909.881802364014;5920.911186332122;5921.851953701192;5774.133879918046;5929.881402372014;6003.609765365237;6020.4280593343565;6073.392660733891;5889.5582831286965;5936.732847021808;5937.050071988461;5960.76156953717;5970.552208584287 22:43:10 INFO - PROCESS | 1660 | |1;ext.html;483.5285596543371;484.9887983554289;486.60341821415545;519.3248776590432;530.9973450132749;30415.23961900951;40353.59646403535;41548.792256038716;41753;43764.34353484692;42125;42705.57294427055;42765.572344276545;42791.93020174497;42853.57146428535;8277.544735039586;9833.31166818323;9840.803183936318;9851.950740246324;9860.506974651275;8820.338474614398;9039.050899655507;11935.343556104412;12112.15214934955;12124.515019399258;10247.897521024768;11995.16033877629;12040.337781422018;12129.332886691229;12144.089193310538;12032.699182520464;12080.818787718192;12110.515579376814;12114.515419383259;12122.878771212263;10016.64941727036;11869.46587403569;11983.10126740498;11999.700007499796;12008.939955300168;39861.40207896884;40205.79897100496;40232;40246.19507609861;40338.59661403378;3332.933341333184;3386.356592247465;3386.831418900442;3388.2376465295356;3491.9650803491895;29598.85200574005;30949.845250773604;31020.22449438782;31056.534151987737;31276.374472510422;3146.2920842810413;3163.3198862244726;3315.1712071982006;3316.336732653479;3374.8143852088124;511928;514749;515369;516090;518350;55182.72408637931;55471.16793248105;55659.443405566235;55871.44128558744;56031;59790.10314845281;59887.10169347463;60028.39971600316;60032.39967600355;60133;15629.765553516707;15872;15874.52376428709;15877.841221587867;15884.76172857408;11319.943400283111;12403;12454.377281135907;12468.314242716557;12484.313362765135;13708;14104.71790564183;15247.847521524865;15560.299786509635;15621.21893905323;5887.4701276884425;5898.852528686732;6272.6236425814595;6358.300586935434;6466.353364663496;895.8029233568604;985.2069084387138;997.9201663867018;1073.4632683658172;1116.972075698114;29049.56425653617;29477.705222947498;29511.967081152055;29512.262193444905;30261;1077.5635867473686;1086.538221255963;1122.6239209864787;1125.8930401611833;1129.745807193375;14813.777793333109;15363.846361536465;15368.769468457982;15397.538073857802;15788.21058947048;679864.6006769935;681194;681326;682151;687791;56608.43391566114;57141.714291428274;57192.142117868265;57200.42799572034;57219;1074.7962282243936;1260.3761138236632;1340.7184491256946;1370.1299674706634;1394.874461298488;30126.304228445384;30906.845465772527;31005.534916975812;31500.212494687818;31616.841915790275;47683.76158119187;48292;48327.75836120797;48446.75776621094;48561.5143848557;568712;569678;569795.1143419921;570031.1498442481;570873;25070;25714.742852571235;25839.870800645876;26611;38816.6118338813;46257;46660.53339466562;47222;47560.810979725786;47918.5208147928;38610.613893861824;38808.61191388163;38965;39838.60161398349;40293.59706402898;37787.43318850274;38080.80959595184;38359.42460863033;39192;39607.80196099001;37897.81051094727;37919;38163.42754858735;38639.42040869333;38726;27585.724142758314;31296.530552041284;32468.837655812516;34321;34355.82822085873;16015.199240038184;18553.35063272833;18561.721574176667;19069.618607627493;24969.625455617817 22:43:10 INFO - PROCESS | 1660 | |2;jquery.html;352.22861932368096;371.08157310656145;377.4338492261607;384.4233649525712;400.4933758795123;14793.556193314203;15187.46843860465;15495.99276047056;19637.705434418458;21078.367648970518;19887.502812429695;20307.18771249152;21004.684929726027;21082.89458552705;21286.467838304052;4804.543568361018;5714.342850572186;6075.513958883279;6101.084837274422;6108.69456527174;5276.551493123089;6028.517718582525;6064.302605200414;6076.756929722828;6085.178500902388;5754.510866576346;6006.309274433453;6027.487663548603;6038.275406951172;6050.96974515129;5994.280686317648;6049.697515124249;6090.208272924536;6092.847678808065;6099.48154406876;4985.52637499437;5191.169412893956;5236.7119808410525;5249.47505249472;5260.8684782880355;25262.621060684105;26152.476950460885;26306.342341441425;26362.077327293646;26651.866740666173;2397.764340838579;2415.3480854799855;2506.9373265668323;3021.839251225668;3150.810951342904;15789.763153552705;15830.920845395814;15873.206339682969;16191.389842977238;16420.425285114965;1844.1885570349139;1880.8119188081218;1893.2616279650947;1905.7713074431158;1934.3809980806147;59678;88623;91851.54074229654;115042;121908.56182876382;10741.624043158452;10825.458727063615;10972.670819875417;11140.944295278472;11146.721331966604;13463.865361346458;13691.931540342433;13733.862661373458;13797.862021379859;13832.723345533033;8539.274161696201;8630.332108853712;8650.307975361957;8818.911810881937;8819.338549608805;8724.78188045304;8743.14755310796;8800.735977920673;8933.687320943736;8964.013958464564;5542.75057622408;5876.559258055663;6298.212723409574;6522.706478208492;6660.600363978177;1791.1581556668345;1824.5073830066074;1848.7596612440566;1856.2667746240159;1863.3478282601252;896.3904544909394;897.2194191053825;902.9142231487998;928.7399528132133;942.1097063275139;21751.238706645192;22357.441063973532;22360.888195558917;22420.43948901258;22461.663075053886;909.490685216281;960.159860122393;969.96120155193;989.9059589339001;997.8004399120205;8912.777180570538;10793.40636265013;11403.828942565868;11531.019863311712;11880.108991825648;521722;605335;617051;621787;624019;101633;105393;114496;121283;151231.2438437823;934.6121359635811;969.1374676537746;997.9401235925868;998.0767789794411;998.7203582996773;18294.35969741106;18938.810611893703;19079.427617171506;19397.709034364212;19478.513037174183;37973;46534.76732616315;54069.45930540802;54807.90384192214;55335;83198;83608.36853550727;84781.1521884798;85002;85872.57063714642;7949.960250198712;7982.521048737094;8132.2680958714;8191.385646076449;8234.300084492885;10676.359418434919;11055.391953442639;12975;13010.934945325213;13178.166694160802;11111.944440277746;11121.388323642277;11272.774544509228;11289.322640641587;11348.432578371214;10943.233973621771;10967.012968832843;10968.616098436516;11044.392558409365;11161.218714689894;9247.630094796134;11434.828477572677;11555.2489088209;11597.594084207012;11672.241304315192;7932.563708996061;8174.754757357289;8177.713780017909;8291.917080829115;8336.333093352396;10929.781404372026;10950.616728414467;11158;11177.640720119323;11230.04544613717 22:43:10 INFO - PROCESS | 1660 | |3;mootools.html;1475.6605980624454;1505.5633866178807;1525.4965861265784;1680.344665580423;1878.0891267735137;8025.759227223178;8268.545230012347;8450.746477605666;9010.594523246462;9027.684031058914;8142.470739401935;8553;8730.214280714734;8753.124687531244;8920.018797932238;2365.2312998275543;2393.413613664655;2397.5564520563667;2402.987985060081;2409.132712223598;2364.609839376501;2384.6542251373626;2407.4944261704977;2411.841401945258;2419.4556224849357;2109.599176156533;2182.5743979923923;2184.6067707812585;2321.872297023663;2404.3147702904675;2373.0033385977845;2388.7491813359693;2391.4499665076955;2396.652485389609;2400.231925783741;2352.870592117433;2381.083282936082;2381.57131716291;2390.306811024801;2401.1955994741784;8922.241609463203;9002.324825638103;9005.054469280762;9010.14403631654;9016.278697704234;4582.793774280165;5366.114591092465;5370.489803468664;5377.381601115882;5380.354357477127;3494.8427320770625;3585.372559802024;3716.163863130801;3725.6460636239512;3733.253349330145;1070.4594179939158;1073.0932362153967;1084.322298563398;1095.2114477576213;1104.9944750276197;11643.825342619783;11770.941145294219;11779.175457717964;11784.705382365424;11792.705182370424;4530.88672783183;4616.446026476777;4698.788554514987;4707.317438971399;4819.325294458778;8787.34094942869;9864.753381165528;9997.300188986701;10022.649207277856;10028.949855250678;5293.100172970528;5320.068987927098;5375.919361209585;5398.08232600451;5411.404745477994;4710.552497512731;4890.486498917562;4934.481879402611;4939.87650308738;4958.975205124024;3778.3954567269107;3809.6190380961684;3823.2926908522013;3842.7502212356108;3877.9030524236555;1019.2457581389866;1180.8405865208088;1185.4843143232722;1197.4790965929674;1197.7724232395817;645.8901986662185;794.6543253684665;896.820635872828;914.0630853375211;950.50098698184;5577.553795696334;5914.172015917775;5924.851878703067;5967.701614919237;6092.939070609325;461.44856896009617;462.7269910752672;467.53947361848554;468.770302551754;609.7530500147448;3838.5201849768778;3987.0222426003174;4055.6755459563165;4073.368627862686;4126.195391898611;10417.843732344021;10658.78682426347;10661.84007239892;10925.907409259169;10928.726781830517;10592.523336449878;10624.309419887682;10818.891811081945;10868.72828179286;10891.074258687942;8021.358291336679;8136.75589732309;8142.145074767182;8210.384221183434;8300.252977232078;5092.363454568179;5842.2405087338375;5890.293164820249;5900.203472531153;5989.1615173874825;9744.902550974399;9937.950310248403;10020.198384129106;10087.495625218857;10219.489025548544;9975.950120249643;10106.39361638304;10313.381197128196;10368.170546356425;10474.057334839901;6971.721131154697;7016.36852683261;7024.824379390556;7029.3673569379;7104.254053324326;8224.177582241968;8303.4602750821;8348.37387195975;8388.664453421794;8393.496390216607;7424.443166762407;7493.70025198986;7497.850042999;7563.848723025619;7668.849672548939;6484.935150648433;7178.24628414009;7343.70625174987;7345.767862140641;7518.699252030076;6960.060391847036;6969.0940177776565;7058.223595404604;7062.964685176747;7080.256573059961;5024.799008039638;5052.924206136984;5101.46434624374;5109.054824857413;5197.24639927216;4984.975075124601;5036.722980236123;5150.356205474316;5185.351831021122;5261.605379596622 22:43:10 INFO - PROCESS | 1660 | |4;prototype.html;303.4552977405557;304.88871561879915;310.15792124382295;318.6638096807868;320.88287774962146;4640.234361330381;5221.530062294398;5257.36911570612;5292.470752924705;5307.2569840222395;4887.951120488794;4946.332245146904;5050.171903733903;5165.483451654833;5188.37739471263;3540.858365665383;3884.2231553689235;3953.861614843481;3968.353133449615;4020.8235493959737;3370.595528536579;3700.85196592136;3951;3967.7817720025387;4002.2195671844;3198.824064676442;3755.061234691327;3813.2564149990762;3991.5409727881374;4012.1574469361467;3915.5105611798526;3961.1087505311075;3966.643002129822;3973.562908080109;3982.9331414810435;2935.6330458692664;3123.2660324823646;3315.751318651111;3471.2536804587135;3477.7565570410084;4785.401824771903;5027.818462661272;5040.899182016376;5054.19132938728;5100.438951715309;3975.363941769332;4518.864434066983;4537.818487260505;4551.590356867865;4553.840615578439;4334.631556317717;4757.35775670287;4980.825671101494;5204.34945631796;5484.588655850827;3395.2514455055666;3567.2865426914723;3821.8280177392085;3846.3461211593817;3855.20968201518;5511.476409741068;5597.384287728347;5687.7156142192725;5759.2512973313205;5780.53755699543;3880.8447662093763;3883.96116038836;3892.2993861104706;3896.279188350164;3896.8441262349756;3349.614794298687;3948.3287841066513;3988.780617066031;3990.022444501116;4005.759654420744;3855.1711382052995;4052.9189416212103;4332.545082766327;4583.770811459413;4629;4938.629457860247;5004.899902001939;5515.641483303572;5521.861953451196;5549.889002219933;1194.814803705427;1253.6991122130623;1305.6083175047447;1321.3723481346283;1337.164272329794;805.2028491793083;836.6154015105011;895.0467751844265;938.2822141062093;950.3015283766426;4298.376735373354;4688.710604583766;4990;5149.382074151126;5154.329937108152;5632.52123569493;5652.519535839499;5656.274836174924;5656.43435656431;5662.234613981656;4301.870943871689;4816.662833601685;5090.592752579785;5106.285120083191;5139.743012849342;2921.561765735114;2956.822590644568;2963.97742778741;2964.303388703653;2971.1281892407646;4727.7163370197895;5536.52939500147;5568.8862227554655;5746.827595172152;5916.497097746654;5024.723640199751;5038.546530812245;5108.821191258363;5424.864378390493;5503.862403439946;5467.644603100785;5682.857928551736;5715.971420142872;5780.566457515703;5797.101449275369;4685.718856868462;5171.405288391883;5490.670559766427;5556.694381809039;5592.748326325243;4366.279563871925;4574.725516469022;5212.921806172986;5509.118541033416;5534.723263836872;5168.689878607295;5220.233276363381;5514;5699.316082070178;5797.797077102281;3534.091590541079;3865.6714179295077;3882.398228274622;3894.1822217334675;3901.8049097545577;4832.888435659747;5103.744812759422;5105.953279577752;5122.615803814654;5554.944450555443;5465.344158700981;5469.206964990134;5476.507114359727;5477.726113694379;5501.477359650827;4956.454789973172;5079.974600126976;5423.213634022965;5438.374586922555;5520.089185284383;5147.639665223398;5152.793888244578;5357.973210133924;5416.918746218731;5421.512063914267;4299.505556860876;4681.99337142516;5226.268322434787;5342.504098852328;5455.345358556999;4316.158349121891;4744.3357929890535;4810.783514741775;5384.949934762683;5522.41043321844 22:43:10 INFO - PROCESS | 1660 | |5;yui.html;711.8967749676297;764.8890910817931;787.8660627693292;815.3314282288526;824.8185399212175;14882.702345953074;16060.839391606096;16129.354825806982;16145.515634530953;16156.75764863528;14329;14438.927805360985;14457.63855903603;14460.987730858844;14473.131612103281;699.3565919354181;716.2228981555019;720.1825927572218;859.2481578618708;861.750092473182;871.0723079919896;883.5228976352762;894.342658146262;897.8383890899636;898.420518765396;950.2160717408166;953.6233187890779;953.6805170267969;953.704351650987;958.1616085924816;731.7804658602452;943.4103685196752;947.6920000999668;948.6063283737275;949.4018768176074;566.9659820410789;671.2616122265468;690.1752405874981;690.6098054599136;692.003514938487;13480.595582132551;13492.257925814076;13644.386002629906;13648.658783530393;13728.65678358039;13216.867831321755;13749.645075642467;13775.311234438239;13791.172529648253;14215.289235538181;12381.566645167466;12536.373181340987;12611.684707882285;12641.178323409042;12993.415296311689;4510.120526497334;4572.839950601746;4619.353290539327;4650.976745116287;4782.856514304576;123163;125174;129202.3539882304;129265;129424;10889.945550272198;10928.453577321261;10934.343939363664;10949.069329106957;11124.165687573302;17584.64830703379;20033.899830501046;21000.264990725253;21012.26457073995;21021.78978210229;7192.7122915083855;7759.301662850257;7827.295543401121;7832.647530861125;7911;3718.7396882217986;4149.813258403379;4153.273177193979;4275.764832934156;4389.890252743643;3394.9830250848586;3473.756837021435;3516.226430185355;3520.313538859949;3522.0490467574123;626.8432891777056;639.7249182851418;640.2509064394669;650.1385663995226;650.7787352300146;583.8452810005352;603.7132362127954;604.694629212245;611.804222648757;614.9569530132848;8374.539400332918;8628.568571571397;9004;9022.097790220925;9025.6891917547;541.7535021565258;595.6604735300837;620.460199626319;633.395107672172;642.389729756733;4223.429836972031;4372.256716358227;4378;4396.2569464719645;4512;96782.5160874191;99843.50078249708;101692.98307016835;102368.97631023594;151660;106420;110337.89662103436;113331;135350;141024;76277;103084.48457757663;104768.47615761873;106341.46829265804;106466;9470.51398014048;9884.901150988399;10018.448985305587;10103.54534045955;10106.8483972739;42533.78733106315;60748;63296.367036329044;64484;64600.0309995341;79173.60413197897;82086;87990.56004719935;89557;90689.09310906807;13078.019148563706;13302.467901284228;13538.052336336363;13676;13893.513727019506;11793.823092653789;12297.508099675913;12333.691657708629;14443.638909027359;15157.393704252148;13421;13932.651683707989;14059.297035147996;14172.503962361268;14643;13350.866491334962;13781.448742050206;14031.36858841375;14166.504172353918;14503.057301275383;13227.206367617973;13627.79558306646;13883.514077007256;14040.929795350958;14162.929185354007;11133.498992545194;11556.94221528887;11560.710982225512;11612.361320127475;11687;11162.776744465227;11252.805702887857;11462.770744585228;11582.88417115818;11586.47860846247 22:43:10 INFO - PROCESS | 1660 | __end_tp_report 22:43:10 INFO - PROCESS | 1660 | __start_cc_report 22:43:10 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,1411 22:43:10 INFO - PROCESS | 1660 | __end_cc_report 22:43:10 INFO - PROCESS | 1660 | __startTimestamp1474522990438__endTimestamp 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | ------- Summary: start ------- 22:43:10 INFO - PROCESS | 1660 | Number of tests: 6 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#0] dojo.html Cycles:165 Average:5638.54 Median:5882.09 stddev:764.08 (13.0%) stddev-sans-first:742.04 22:43:10 INFO - PROCESS | 1660 | Values: 3197.6 3236.2 3263.0 3293.4 3299.8 5479.0 5528.9 5570.9 5579.4 5645.5 5591.7 5609.1 5616.7 5617.8 5632.0 5050.9 5084.7 5098.0 5104.1 5160.8 5044.8 5093.8 5126.3 5143.0 5204.2 5144.7 5180.2 5198.2 5208.4 5208.9 414.5 5154.3 5155.9 5189.9 5210.6 5070.7 5088.1 5112.8 5128.9 5193.9 5449.8 5585.1 5586.1 5591.8 5595.4 5094.0 5993.0 6177.6 6202.3 6211.6 6098.1 6118.4 6126.8 6148.5 6157.3 5706.3 5715.6 5716.2 5723.7 5785.2 6295.1 6299.1 6299.8 6307.2 6334.4 5876.0 5927.6 5940.2 6050.0 6066.6 6110.9 6116.0 6193.6 6202.2 6202.7 5326.9 5860.5 6108.4 6121.4 6145.6 6195.9 6203.9 6211.3 6212.9 6215.5 4773.9 4842.3 4845.4 4848.0 4908.0 4363.1 4444.9 4461.0 4489.4 4490.8 5650.4 5672.7 5679.1 5684.8 5706.7 6213.0 6279.0 6289.2 6299.4 6306.0 5488.8 5581.1 5585.0 5594.6 5629.5 5319.5 5362.1 5367.7 5367.9 5371.2 6277.7 6278.2 6295.9 6297.9 6299.6 6119.6 6132.1 6169.6 6233.6 6237.6 6246.3 6247.1 6296.0 6320.9 6321.7 6171.6 6178.6 6191.4 6195.3 6198.0 6041.3 6150.2 6246.3 6264.5 6276.8 5876.4 5936.2 5958.8 5974.8 6060.6 5005.9 6058.6 6086.1 6107.2 6127.2 5882.1 5895.7 5909.9 5920.9 5921.9 5774.1 5929.9 6003.6 6020.4 6073.4 5889.6 5936.7 5937.1 5960.8 5970.6 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#1] ext.html Cycles:180 Average:71560.16 Median:37842.62 stddev:158753.30 (419.5%) stddev-sans-first:159108.95 22:43:10 INFO - PROCESS | 1660 | Values: 483.5 485.0 486.6 519.3 531.0 30415.2 40353.6 41548.8 41753.0 43764.3 42125.0 42705.6 42765.6 42791.9 42853.6 8277.5 9833.3 9840.8 9852.0 9860.5 8820.3 9039.1 11935.3 12112.2 12124.5 10247.9 11995.2 12040.3 12129.3 12144.1 12032.7 12080.8 12110.5 12114.5 12122.9 10016.6 11869.5 11983.1 11999.7 12008.9 39861.4 40205.8 40232.0 40246.2 40338.6 3332.9 3386.4 3386.8 3388.2 3492.0 29598.9 30949.8 31020.2 31056.5 31276.4 3146.3 3163.3 3315.2 3316.3 3374.8 511928.0 514749.0 515369.0 516090.0 518350.0 55182.7 55471.2 55659.4 55871.4 56031.0 59790.1 59887.1 60028.4 60032.4 60133.0 15629.8 15872.0 15874.5 15877.8 15884.8 11319.9 12403.0 12454.4 12468.3 12484.3 13708.0 14104.7 15247.8 15560.3 15621.2 5887.5 5898.9 6272.6 6358.3 6466.4 895.8 985.2 997.9 1073.5 1117.0 29049.6 29477.7 29512.0 29512.3 30261.0 1077.6 1086.5 1122.6 1125.9 1129.7 14813.8 15363.8 15368.8 15397.5 15788.2 679864.6 681194.0 681326.0 682151.0 687791.0 56608.4 57141.7 57192.1 57200.4 57219.0 1074.8 1260.4 1340.7 1370.1 1394.9 30126.3 30906.8 31005.5 31500.2 31616.8 47683.8 48292.0 48327.8 48446.8 48561.5 568712.0 569678.0 569795.1 570031.1 570873.0 25070.0 25714.7 25839.9 26611.0 38816.6 46257.0 46660.5 47222.0 47560.8 47918.5 38610.6 38808.6 38965.0 39838.6 40293.6 37787.4 38080.8 38359.4 39192.0 39607.8 37897.8 37919.0 38163.4 38639.4 38726.0 27585.7 31296.5 32468.8 34321.0 34355.8 16015.2 18553.4 18561.7 19069.6 24969.6 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#2] jquery.html Cycles:180 Average:34405.73 Median:361.66 stddev:99475.22 (27505.5%) stddev-sans-first:99721.41 22:43:10 INFO - PROCESS | 1660 | Values: 352.2 371.1 377.4 384.4 400.5 14793.6 15187.5 15496.0 19637.7 21078.4 19887.5 20307.2 21004.7 21082.9 21286.5 4804.5 5714.3 6075.5 6101.1 6108.7 5276.6 6028.5 6064.3 6076.8 6085.2 5754.5 6006.3 6027.5 6038.3 6051.0 5994.3 6049.7 6090.2 6092.8 6099.5 4985.5 5191.2 5236.7 5249.5 5260.9 25262.6 26152.5 26306.3 26362.1 26651.9 2397.8 2415.3 2506.9 3021.8 3150.8 15789.8 15830.9 15873.2 16191.4 16420.4 1844.2 1880.8 1893.3 1905.8 1934.4 59678.0 88623.0 91851.5 115042.0 121908.6 10741.6 10825.5 10972.7 11140.9 11146.7 13463.9 13691.9 13733.9 13797.9 13832.7 8539.3 8630.3 8650.3 8818.9 8819.3 8724.8 8743.1 8800.7 8933.7 8964.0 5542.8 5876.6 6298.2 6522.7 6660.6 1791.2 1824.5 1848.8 1856.3 1863.3 896.4 897.2 902.9 928.7 942.1 21751.2 22357.4 22360.9 22420.4 22461.7 909.5 960.2 970.0 989.9 997.8 8912.8 10793.4 11403.8 11531.0 11880.1 521722.0 605335.0 617051.0 621787.0 624019.0 101633.0 105393.0 114496.0 121283.0 151231.2 934.6 969.1 997.9 998.1 998.7 18294.4 18938.8 19079.4 19397.7 19478.5 37973.0 46534.8 54069.5 54807.9 55335.0 83198.0 83608.4 84781.2 85002.0 85872.6 7950.0 7982.5 8132.3 8191.4 8234.3 10676.4 11055.4 12975.0 13010.9 13178.2 11111.9 11121.4 11272.8 11289.3 11348.4 10943.2 10967.0 10968.6 11044.4 11161.2 9247.6 11434.8 11555.2 11597.6 11672.2 7932.6 8174.8 8177.7 8291.9 8336.3 10929.8 10950.6 11158.0 11177.6 11230.0 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#3] mootools.html Cycles:180 Average:5697.91 Median:4912.48 stddev:3223.74 (65.6%) stddev-sans-first:3217.17 22:43:10 INFO - PROCESS | 1660 | Values: 1475.7 1505.6 1525.5 1680.3 1878.1 8025.8 8268.5 8450.7 9010.6 9027.7 8142.5 8553.0 8730.2 8753.1 8920.0 2365.2 2393.4 2397.6 2403.0 2409.1 2364.6 2384.7 2407.5 2411.8 2419.5 2109.6 2182.6 2184.6 2321.9 2404.3 2373.0 2388.7 2391.4 2396.7 2400.2 2352.9 2381.1 2381.6 2390.3 2401.2 8922.2 9002.3 9005.1 9010.1 9016.3 4582.8 5366.1 5370.5 5377.4 5380.4 3494.8 3585.4 3716.2 3725.6 3733.3 1070.5 1073.1 1084.3 1095.2 1105.0 11643.8 11770.9 11779.2 11784.7 11792.7 4530.9 4616.4 4698.8 4707.3 4819.3 8787.3 9864.8 9997.3 10022.6 10028.9 5293.1 5320.1 5375.9 5398.1 5411.4 4710.6 4890.5 4934.5 4939.9 4959.0 3778.4 3809.6 3823.3 3842.8 3877.9 1019.2 1180.8 1185.5 1197.5 1197.8 645.9 794.7 896.8 914.1 950.5 5577.6 5914.2 5924.9 5967.7 6092.9 461.4 462.7 467.5 468.8 609.8 3838.5 3987.0 4055.7 4073.4 4126.2 10417.8 10658.8 10661.8 10925.9 10928.7 10592.5 10624.3 10818.9 10868.7 10891.1 8021.4 8136.8 8142.1 8210.4 8300.3 5092.4 5842.2 5890.3 5900.2 5989.2 9744.9 9938.0 10020.2 10087.5 10219.5 9976.0 10106.4 10313.4 10368.2 10474.1 6971.7 7016.4 7024.8 7029.4 7104.3 8224.2 8303.5 8348.4 8388.7 8393.5 7424.4 7493.7 7497.9 7563.8 7668.8 6484.9 7178.2 7343.7 7345.8 7518.7 6960.1 6969.1 7058.2 7063.0 7080.3 5024.8 5052.9 5101.5 5109.1 5197.2 4985.0 5036.7 5150.4 5185.4 5261.6 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#4] prototype.html Cycles:180 Average:4388.07 Median:4951.39 stddev:1326.15 (26.8%) stddev-sans-first:1293.95 22:43:10 INFO - PROCESS | 1660 | Values: 303.5 304.9 310.2 318.7 320.9 4640.2 5221.5 5257.4 5292.5 5307.3 4888.0 4946.3 5050.2 5165.5 5188.4 3540.9 3884.2 3953.9 3968.4 4020.8 3370.6 3700.9 3951.0 3967.8 4002.2 3198.8 3755.1 3813.3 3991.5 4012.2 3915.5 3961.1 3966.6 3973.6 3982.9 2935.6 3123.3 3315.8 3471.3 3477.8 4785.4 5027.8 5040.9 5054.2 5100.4 3975.4 4518.9 4537.8 4551.6 4553.8 4334.6 4757.4 4980.8 5204.3 5484.6 3395.3 3567.3 3821.8 3846.3 3855.2 5511.5 5597.4 5687.7 5759.3 5780.5 3880.8 3884.0 3892.3 3896.3 3896.8 3349.6 3948.3 3988.8 3990.0 4005.8 3855.2 4052.9 4332.5 4583.8 4629.0 4938.6 5004.9 5515.6 5521.9 5549.9 1194.8 1253.7 1305.6 1321.4 1337.2 805.2 836.6 895.0 938.3 950.3 4298.4 4688.7 4990.0 5149.4 5154.3 5632.5 5652.5 5656.3 5656.4 5662.2 4301.9 4816.7 5090.6 5106.3 5139.7 2921.6 2956.8 2964.0 2964.3 2971.1 4727.7 5536.5 5568.9 5746.8 5916.5 5024.7 5038.5 5108.8 5424.9 5503.9 5467.6 5682.9 5716.0 5780.6 5797.1 4685.7 5171.4 5490.7 5556.7 5592.7 4366.3 4574.7 5212.9 5509.1 5534.7 5168.7 5220.2 5514.0 5699.3 5797.8 3534.1 3865.7 3882.4 3894.2 3901.8 4832.9 5103.7 5106.0 5122.6 5554.9 5465.3 5469.2 5476.5 5477.7 5501.5 4956.5 5080.0 5423.2 5438.4 5520.1 5147.6 5152.8 5358.0 5416.9 5421.5 4299.5 4682.0 5226.3 5342.5 5455.3 4316.2 4744.3 4810.8 5384.9 5522.4 22:43:10 INFO - PROCESS | 1660 | 22:43:10 INFO - PROCESS | 1660 | [#5] yui.html Cycles:180 Average:23502.98 Median:3434.37 stddev:36566.78 (1064.7%) stddev-sans-first:36629.32 22:43:10 INFO - PROCESS | 1660 | Values: 711.9 764.9 787.9 815.3 824.8 14882.7 16060.8 16129.4 16145.5 16156.8 14329.0 14438.9 14457.6 14461.0 14473.1 699.4 716.2 720.2 859.2 861.8 871.1 883.5 894.3 897.8 898.4 950.2 953.6 953.7 953.7 958.2 731.8 943.4 947.7 948.6 949.4 567.0 671.3 690.2 690.6 692.0 13480.6 13492.3 13644.4 13648.7 13728.7 13216.9 13749.6 13775.3 13791.2 14215.3 12381.6 12536.4 12611.7 12641.2 12993.4 4510.1 4572.8 4619.4 4651.0 4782.9 123163.0 125174.0 129202.4 129265.0 129424.0 10889.9 10928.5 10934.3 10949.1 11124.2 17584.6 20033.9 21000.3 21012.3 21021.8 7192.7 7759.3 7827.3 7832.6 7911.0 3718.7 4149.8 4153.3 4275.8 4389.9 3395.0 3473.8 3516.2 3520.3 3522.0 626.8 639.7 640.3 650.1 650.8 583.8 603.7 604.7 611.8 615.0 8374.5 8628.6 9004.0 9022.1 9025.7 541.8 595.7 620.5 633.4 642.4 4223.4 4372.3 4378.0 4396.3 4512.0 96782.5 99843.5 101693.0 102369.0 151660.0 106420.0 110337.9 113331.0 135350.0 141024.0 76277.0 103084.5 104768.5 106341.5 106466.0 9470.5 9884.9 10018.4 10103.5 10106.8 42533.8 60748.0 63296.4 64484.0 64600.0 79173.6 82086.0 87990.6 89557.0 90689.1 13078.0 13302.5 13538.1 13676.0 13893.5 11793.8 12297.5 12333.7 14443.6 15157.4 13421.0 13932.7 14059.3 14172.5 14643.0 13350.9 13781.4 14031.4 14166.5 14503.1 13227.2 13627.8 13883.5 14040.9 14162.9 11133.5 11556.9 11560.7 11612.4 11687.0 11162.8 11252.8 11462.8 11582.9 11586.5 22:43:10 INFO - PROCESS | 1660 | -------- Summary: end -------- 22:43:10 INFO - PROCESS | 1660 | 22:43:11 INFO - TEST-INFO | 1660: exit 0 22:43:11 INFO - TEST-OK | dromaeo_css | took 1117507ms 22:43:11 INFO - TEST-START | kraken 22:43:11 INFO - operating with platform_type : mac_ 22:43:11 INFO - Initialising browser for kraken test... 22:43:11 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmphidHQ3/profile http://localhost:49272/getInfo.html) 22:43:13 INFO - PROCESS | 1666 | __metrics Screen width/height:1600/1200 22:43:13 INFO - PROCESS | 1666 | colorDepth:24 22:43:13 INFO - PROCESS | 1666 | Browser inner width/height: 1024/689 22:43:13 INFO - PROCESS | 1666 | __metrics 22:43:13 INFO - PROCESS | 1666 | 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" 22:43:13 INFO - PROCESS | 1666 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 22:43:14 INFO - TEST-INFO | 1666: exit 0 22:43:14 INFO - Browser initialized. 22:43:14 INFO - Running cycle 1/1 for kraken test... 22:43:14 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/T/tmphidHQ3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 22:43:26 INFO - PROCESS | 1667 | RSS: Main: 236732416 22:43:26 INFO - PROCESS | 1667 | 22:43:28 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection) 22:43:29 INFO - PROCESS | 1667 | RSS: Main: 295034880 22:43:29 INFO - PROCESS | 1667 | 22:43:32 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-dft) 22:43:32 INFO - PROCESS | 1667 | RSS: Main: 295567360 22:43:32 INFO - PROCESS | 1667 | 22:43:36 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-fft) 22:43:37 INFO - PROCESS | 1667 | RSS: Main: 384307200 22:43:37 INFO - PROCESS | 1667 | 22:43:39 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator) 22:43:39 INFO - PROCESS | 1667 | RSS: Main: 288546816 22:43:39 INFO - PROCESS | 1667 | 22:43:41 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur) 22:43:42 INFO - PROCESS | 1667 | RSS: Main: 291127296 22:43:42 INFO - PROCESS | 1667 | 22:43:45 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom) 22:43:45 INFO - PROCESS | 1667 | RSS: Main: 330518528 22:43:45 INFO - PROCESS | 1667 | 22:43:48 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate) 22:43:49 INFO - PROCESS | 1667 | RSS: Main: 331390976 22:43:49 INFO - PROCESS | 1667 | 22:43:52 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial) 22:43:52 INFO - PROCESS | 1667 | RSS: Main: 329138176 22:43:52 INFO - PROCESS | 1667 | 22:43:54 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox) 22:43:54 INFO - PROCESS | 1667 | RSS: Main: 302735360 22:43:54 INFO - PROCESS | 1667 | 22:43:56 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes) 22:43:57 INFO - PROCESS | 1667 | RSS: Main: 308387840 22:43:57 INFO - PROCESS | 1667 | 22:43:59 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm) 22:44:00 INFO - PROCESS | 1667 | RSS: Main: 309219328 22:44:00 INFO - PROCESS | 1667 | 22:44:03 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 22:44:04 INFO - PROCESS | 1667 | RSS: Main: 324931584 22:44:04 INFO - PROCESS | 1667 | 22:44:06 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 22:44:07 INFO - PROCESS | 1667 | RSS: Main: 321118208 22:44:07 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | Cycle 1(1): loaded http://localhost:49272/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49272/tests/kraken/driver.html?testName=ai-astar) 22:44:09 INFO - PROCESS | 1667 | RSS: Main: 365428736 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | __start_tp_report 22:44:09 INFO - PROCESS | 1667 | _x_x_mozilla_page_load 22:44:09 INFO - PROCESS | 1667 | _x_x_mozilla_page_load_details 22:44:09 INFO - PROCESS | 1667 | |i|pagename|runs| 22:44:09 INFO - PROCESS | 1667 | |0;ai-astar;115;114;114;113;111;115;112;113;113;110 22:44:09 INFO - PROCESS | 1667 | |1;audio-beat-detection;147;109;107;140;134;139;109;108;136;106 22:44:09 INFO - PROCESS | 1667 | |2;audio-dft;184;183;186;183;185;185;181;182;182;184 22:44:09 INFO - PROCESS | 1667 | |3;audio-fft;80;58;74;113;86;79;58;58;82;57 22:44:09 INFO - PROCESS | 1667 | |4;audio-oscillator;88;70;93;70;68;69;93;69;69;91 22:44:09 INFO - PROCESS | 1667 | |5;imaging-gaussian-blur;87;83;88;85;87;84;89;83;87;83 22:44:09 INFO - PROCESS | 1667 | |6;imaging-darkroom;96;95;96;95;95;103;94;96;95;94 22:44:09 INFO - PROCESS | 1667 | |7;imaging-desaturate;126;127;85;83;83;84;85;85;84;92 22:44:09 INFO - PROCESS | 1667 | |8;json-parse-financial;59;58;57;57;54;54;55;54;55;74 22:44:09 INFO - PROCESS | 1667 | |9;json-stringify-tinderbox;67;68;75;75;75;69;70;75;105;106 22:44:09 INFO - PROCESS | 1667 | |10;stanford-crypto-aes;84;87;86;89;85;87;85;82;90;84 22:44:09 INFO - PROCESS | 1667 | |11;stanford-crypto-ccm;138;137;139;139;138;139;141;140;183;144 22:44:09 INFO - PROCESS | 1667 | |12;stanford-crypto-pbkdf2;171;169;174;171;171;171;173;171;167;173 22:44:09 INFO - PROCESS | 1667 | |13;stanford-crypto-sha256-iterative;68;68;68;69;68;68;68;67;67;66 22:44:09 INFO - PROCESS | 1667 | __end_tp_report 22:44:09 INFO - PROCESS | 1667 | __start_cc_report 22:44:09 INFO - PROCESS | 1667 | _x_x_mozilla_cycle_collect,1993 22:44:09 INFO - PROCESS | 1667 | __end_cc_report 22:44:09 INFO - PROCESS | 1667 | __startTimestamp1474523049549__endTimestamp 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | ------- Summary: start ------- 22:44:09 INFO - PROCESS | 1667 | Number of tests: 14 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#0] ai-astar Cycles:10 Average:113.00 Median:113.50 stddev:1.63 (1.4%) stddev-sans-first:1.56 22:44:09 INFO - PROCESS | 1667 | Values: 115.0 114.0 114.0 113.0 111.0 115.0 112.0 113.0 113.0 110.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#1] audio-beat-detection Cycles:10 Average:123.50 Median:135.00 stddev:16.90 (12.5%) stddev-sans-first:15.64 22:44:09 INFO - PROCESS | 1667 | Values: 147.0 109.0 107.0 140.0 134.0 139.0 109.0 108.0 136.0 106.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#2] audio-dft Cycles:10 Average:183.50 Median:184.00 stddev:1.58 (0.9%) stddev-sans-first:1.67 22:44:09 INFO - PROCESS | 1667 | Values: 184.0 183.0 186.0 183.0 185.0 185.0 181.0 182.0 182.0 184.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#3] audio-fft Cycles:10 Average:74.50 Median:76.50 stddev:17.78 (23.2%) stddev-sans-first:18.74 22:44:09 INFO - PROCESS | 1667 | Values: 80.0 58.0 74.0 113.0 86.0 79.0 58.0 58.0 82.0 57.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#4] audio-oscillator Cycles:10 Average:78.00 Median:79.00 stddev:11.50 (14.6%) stddev-sans-first:11.61 22:44:09 INFO - PROCESS | 1667 | Values: 88.0 70.0 93.0 70.0 68.0 69.0 93.0 69.0 69.0 91.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#5] imaging-gaussian-blur Cycles:10 Average:85.60 Median:87.00 stddev:2.27 (2.6%) stddev-sans-first:2.35 22:44:09 INFO - PROCESS | 1667 | Values: 87.0 83.0 88.0 85.0 87.0 84.0 89.0 83.0 87.0 83.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#6] imaging-darkroom Cycles:10 Average:95.90 Median:95.00 stddev:2.60 (2.7%) stddev-sans-first:2.76 22:44:09 INFO - PROCESS | 1667 | Values: 96.0 95.0 96.0 95.0 95.0 103.0 94.0 96.0 95.0 94.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#7] imaging-desaturate Cycles:10 Average:93.40 Median:84.50 stddev:17.63 (20.9%) stddev-sans-first:14.22 22:44:09 INFO - PROCESS | 1667 | Values: 126.0 127.0 85.0 83.0 83.0 84.0 85.0 85.0 84.0 92.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#8] json-parse-financial Cycles:10 Average:57.70 Median:57.00 stddev:6.00 (10.5%) stddev-sans-first:6.35 22:44:09 INFO - PROCESS | 1667 | Values: 59.0 58.0 57.0 57.0 54.0 54.0 55.0 54.0 55.0 74.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#9] json-stringify-tinderbox Cycles:10 Average:78.50 Median:72.50 stddev:14.58 (20.1%) stddev-sans-first:14.86 22:44:09 INFO - PROCESS | 1667 | Values: 67.0 68.0 75.0 75.0 75.0 69.0 70.0 75.0 105.0 106.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#10] stanford-crypto-aes Cycles:10 Average:85.90 Median:86.50 stddev:2.42 (2.8%) stddev-sans-first:2.47 22:44:09 INFO - PROCESS | 1667 | Values: 84.0 87.0 86.0 89.0 85.0 87.0 85.0 82.0 90.0 84.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#11] stanford-crypto-ccm Cycles:10 Average:143.80 Median:139.50 stddev:13.91 (10.0%) stddev-sans-first:14.60 22:44:09 INFO - PROCESS | 1667 | Values: 138.0 137.0 139.0 139.0 138.0 139.0 141.0 140.0 183.0 144.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:171.10 Median:171.00 stddev:2.02 (1.2%) stddev-sans-first:2.15 22:44:09 INFO - PROCESS | 1667 | Values: 171.0 169.0 174.0 171.0 171.0 171.0 173.0 171.0 167.0 173.0 22:44:09 INFO - PROCESS | 1667 | 22:44:09 INFO - PROCESS | 1667 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.70 Median:68.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 22:44:09 INFO - PROCESS | 1667 | Values: 68.0 68.0 68.0 69.0 68.0 68.0 68.0 67.0 67.0 66.0 22:44:09 INFO - PROCESS | 1667 | -------- Summary: end -------- 22:44:09 INFO - PROCESS | 1667 | 22:44:10 INFO - TEST-INFO | 1667: exit 0 22:44:10 INFO - TEST-OK | kraken | took 59340ms 22:44:10 INFO - SUITE-END | took 1176s 22:44:10 INFO - Completed test suite (00:19:37) 22:44:10 INFO - javascript benchmark 22:44:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3197.584314039175, 3236.190952261934, 3262.9999999999986, 3293.407186706395, 3299.8020118792874, 5479.0, 5528.889422211559, 5570.944290557093, 5579.386267510576, 5645.491905728483, 5591.664500129995, 5609.074502707059, 5616.691081990489, 5617.775288988446, 5631.99999999999, 5050.949490505084, 5084.694918304895, 5098.0, 5104.055749686301, 5160.845174644748, 5044.848654540351, 5093.79624815007, 5126.333576635032, 5143.0485360208295, 5204.219367094947, 5144.6655967362185, 5180.197069454241, 5198.194279886605, 5208.374995000586, 5208.921866172011, 414.47628308365506, 5154.329937108189, 5155.896882062357, 5189.896202075937, 5210.583153347723, 5070.72111033893, 5088.1350170471105, 5112.795488180505, 5128.897422051576, 5193.922091168598, 5449.782008719647, 5585.050541407971, 5586.106223004341, 5591.776328946838, 5595.440455954372, 5093.974530127326, 5993.011153159723, 6177.629342239435, 6202.34875338092, 6211.565190436671, 6098.099428985301, 6118.373546475427, 6126.846828829271, 6148.508119350441, 6157.322694503601, 5706.25818643582, 5715.628484148517, 5716.228309178249, 5723.742431590588, 5785.218995435646, 6295.055741638791, 6299.0866324382705, 6299.842503937438, 6307.180066591313, 6334.366563343721, 5875.970620146957, 5927.614705044159, 5940.1683764272175, 6049.969750151221, 6066.636001839904, 6110.938890611037, 6116.028315130255, 6193.597416167935, 6202.193714817045, 6202.689865506707, 5326.866828329245, 5860.531157507475, 6108.402780883668, 6121.360988904203, 6145.600535965237, 6195.907061394083, 6203.906941395882, 6211.3167551569295, 6212.9068063979075, 6215.533834962396, 4773.880652983703, 4842.346283251786, 4845.41854977405, 4848.0, 4908.04293162837, 4363.105563359503, 4444.888877778081, 4460.955390446119, 4489.393931819162, 4490.842820501267, 5650.3502097258515, 5672.659640421588, 5679.148127780783, 5684.8010319638615, 5706.657600543981, 6212.968935155386, 6278.995360742259, 6289.150964619809, 6299.4330510253385, 6305.96847015762, 5488.753006114654, 5581.05122129239, 5584.972075139598, 5594.608377413542, 5629.549636029108, 5319.547838433777, 5362.061639213122, 5367.731613419313, 5367.892642147213, 5371.221172929982, 6277.7175027123885, 6278.183836101369, 6295.905561416583, 6297.937020629826, 6299.622022678654, 6119.632822030692, 6132.141500190066, 6169.598976066542, 6233.625982440886, 6237.594556354004, 6246.312905580292, 6247.0629405589525, 6296.024116261987, 6320.873582528416, 6321.715522801393, 6171.6297022178815, 6178.567500274938, 6191.442770150708, 6195.318514963441, 6197.96901015492, 6041.2750469943885, 6150.231221097363, 6246.281677607133, 6264.467520260829, 6276.843078923063, 5876.44173803488, 5936.228290322403, 5958.821235362946, 5974.761009559569, 6060.636361818305, 5005.949940500694, 6058.606190597597, 6086.056661217518, 6107.175531303395, 6127.172831667668, 5882.088276317178, 5895.7052147393315, 5909.881802364014, 5920.911186332122, 5921.851953701192, 5774.133879918046, 5929.881402372014, 6003.609765365237, 6020.4280593343565, 6073.392660733891, 5889.5582831286965, 5936.732847021808, 5937.050071988461, 5960.76156953717, 5970.552208584287], "value": 5590.6754249064, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.5285596543371, 484.9887983554289, 486.60341821415545, 519.3248776590432, 530.9973450132749, 30415.23961900951, 40353.59646403535, 41548.792256038716, 41753.0, 43764.34353484692, 42125.0, 42705.57294427055, 42765.572344276545, 42791.93020174497, 42853.57146428535, 8277.544735039586, 9833.31166818323, 9840.803183936318, 9851.950740246324, 9860.506974651275, 8820.338474614398, 9039.050899655507, 11935.343556104412, 12112.15214934955, 12124.515019399258, 10247.897521024768, 11995.16033877629, 12040.337781422018, 12129.332886691229, 12144.089193310538, 12032.699182520464, 12080.818787718192, 12110.515579376814, 12114.515419383259, 12122.878771212263, 10016.64941727036, 11869.46587403569, 11983.10126740498, 11999.700007499796, 12008.939955300168, 39861.40207896884, 40205.79897100496, 40232.0, 40246.19507609861, 40338.59661403378, 3332.933341333184, 3386.356592247465, 3386.831418900442, 3388.2376465295356, 3491.9650803491895, 29598.85200574005, 30949.845250773604, 31020.22449438782, 31056.534151987737, 31276.374472510422, 3146.2920842810413, 3163.3198862244726, 3315.1712071982006, 3316.336732653479, 3374.8143852088124, 511928.0, 514749.0, 515369.0, 516090.0, 518350.0, 55182.72408637931, 55471.16793248105, 55659.443405566235, 55871.44128558744, 56031.0, 59790.10314845281, 59887.10169347463, 60028.39971600316, 60032.39967600355, 60133.0, 15629.765553516707, 15872.0, 15874.52376428709, 15877.841221587867, 15884.76172857408, 11319.943400283111, 12403.0, 12454.377281135907, 12468.314242716557, 12484.313362765135, 13708.0, 14104.71790564183, 15247.847521524865, 15560.299786509635, 15621.21893905323, 5887.4701276884425, 5898.852528686732, 6272.6236425814595, 6358.300586935434, 6466.353364663496, 895.8029233568604, 985.2069084387138, 997.9201663867018, 1073.4632683658172, 1116.972075698114, 29049.56425653617, 29477.705222947498, 29511.967081152055, 29512.262193444905, 30261.0, 1077.5635867473686, 1086.538221255963, 1122.6239209864787, 1125.8930401611833, 1129.745807193375, 14813.777793333109, 15363.846361536465, 15368.769468457982, 15397.538073857802, 15788.21058947048, 679864.6006769935, 681194.0, 681326.0, 682151.0, 687791.0, 56608.43391566114, 57141.714291428274, 57192.142117868265, 57200.42799572034, 57219.0, 1074.7962282243936, 1260.3761138236632, 1340.7184491256946, 1370.1299674706634, 1394.874461298488, 30126.304228445384, 30906.845465772527, 31005.534916975812, 31500.212494687818, 31616.841915790275, 47683.76158119187, 48292.0, 48327.75836120797, 48446.75776621094, 48561.5143848557, 568712.0, 569678.0, 569795.1143419921, 570031.1498442481, 570873.0, 25070.0, 25714.742852571235, 25839.870800645876, 26611.0, 38816.6118338813, 46257.0, 46660.53339466562, 47222.0, 47560.810979725786, 47918.5208147928, 38610.613893861824, 38808.61191388163, 38965.0, 39838.60161398349, 40293.59706402898, 37787.43318850274, 38080.80959595184, 38359.42460863033, 39192.0, 39607.80196099001, 37897.81051094727, 37919.0, 38163.42754858735, 38639.42040869333, 38726.0, 27585.724142758314, 31296.530552041284, 32468.837655812516, 34321.0, 34355.82822085873, 16015.199240038184, 18553.35063272833, 18561.721574176667, 19069.618607627493, 24969.625455617817], "value": 19955.053844787602, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [352.22861932368096, 371.08157310656145, 377.4338492261607, 384.4233649525712, 400.4933758795123, 14793.556193314203, 15187.46843860465, 15495.99276047056, 19637.705434418458, 21078.367648970518, 19887.502812429695, 20307.18771249152, 21004.684929726027, 21082.89458552705, 21286.467838304052, 4804.543568361018, 5714.342850572186, 6075.513958883279, 6101.084837274422, 6108.69456527174, 5276.551493123089, 6028.517718582525, 6064.302605200414, 6076.756929722828, 6085.178500902388, 5754.510866576346, 6006.309274433453, 6027.487663548603, 6038.275406951172, 6050.96974515129, 5994.280686317648, 6049.697515124249, 6090.208272924536, 6092.847678808065, 6099.48154406876, 4985.52637499437, 5191.169412893956, 5236.7119808410525, 5249.47505249472, 5260.8684782880355, 25262.621060684105, 26152.476950460885, 26306.342341441425, 26362.077327293646, 26651.866740666173, 2397.764340838579, 2415.3480854799855, 2506.9373265668323, 3021.839251225668, 3150.810951342904, 15789.763153552705, 15830.920845395814, 15873.206339682969, 16191.389842977238, 16420.425285114965, 1844.1885570349139, 1880.8119188081218, 1893.2616279650947, 1905.7713074431158, 1934.3809980806147, 59678.0, 88623.0, 91851.54074229654, 115042.0, 121908.56182876382, 10741.624043158452, 10825.458727063615, 10972.670819875417, 11140.944295278472, 11146.721331966604, 13463.865361346458, 13691.931540342433, 13733.862661373458, 13797.862021379859, 13832.723345533033, 8539.274161696201, 8630.332108853712, 8650.307975361957, 8818.911810881937, 8819.338549608805, 8724.78188045304, 8743.14755310796, 8800.735977920673, 8933.687320943736, 8964.013958464564, 5542.75057622408, 5876.559258055663, 6298.212723409574, 6522.706478208492, 6660.600363978177, 1791.1581556668345, 1824.5073830066074, 1848.7596612440566, 1856.2667746240159, 1863.3478282601252, 896.3904544909394, 897.2194191053825, 902.9142231487998, 928.7399528132133, 942.1097063275139, 21751.238706645192, 22357.441063973532, 22360.888195558917, 22420.43948901258, 22461.663075053886, 909.490685216281, 960.159860122393, 969.96120155193, 989.9059589339001, 997.8004399120205, 8912.777180570538, 10793.40636265013, 11403.828942565868, 11531.019863311712, 11880.108991825648, 521722.0, 605335.0, 617051.0, 621787.0, 624019.0, 101633.0, 105393.0, 114496.0, 121283.0, 151231.2438437823, 934.6121359635811, 969.1374676537746, 997.9401235925868, 998.0767789794411, 998.7203582996773, 18294.35969741106, 18938.810611893703, 19079.427617171506, 19397.709034364212, 19478.513037174183, 37973.0, 46534.76732616315, 54069.45930540802, 54807.90384192214, 55335.0, 83198.0, 83608.36853550727, 84781.1521884798, 85002.0, 85872.57063714642, 7949.960250198712, 7982.521048737094, 8132.2680958714, 8191.385646076449, 8234.300084492885, 10676.359418434919, 11055.391953442639, 12975.0, 13010.934945325213, 13178.166694160802, 11111.944440277746, 11121.388323642277, 11272.774544509228, 11289.322640641587, 11348.432578371214, 10943.233973621771, 10967.012968832843, 10968.616098436516, 11044.392558409365, 11161.218714689894, 9247.630094796134, 11434.828477572677, 11555.2489088209, 11597.594084207012, 11672.241304315192, 7932.563708996061, 8174.754757357289, 8177.713780017909, 8291.917080829115, 8336.333093352396, 10929.781404372026, 10950.616728414467, 11158.0, 11177.640720119323, 11230.04544613717], "value": 9704.276524299254, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1475.6605980624454, 1505.5633866178807, 1525.4965861265784, 1680.344665580423, 1878.0891267735137, 8025.759227223178, 8268.545230012347, 8450.746477605666, 9010.594523246462, 9027.684031058914, 8142.470739401935, 8553.0, 8730.214280714734, 8753.124687531244, 8920.018797932238, 2365.2312998275543, 2393.413613664655, 2397.5564520563667, 2402.987985060081, 2409.132712223598, 2364.609839376501, 2384.6542251373626, 2407.4944261704977, 2411.841401945258, 2419.4556224849357, 2109.599176156533, 2182.5743979923923, 2184.6067707812585, 2321.872297023663, 2404.3147702904675, 2373.0033385977845, 2388.7491813359693, 2391.4499665076955, 2396.652485389609, 2400.231925783741, 2352.870592117433, 2381.083282936082, 2381.57131716291, 2390.306811024801, 2401.1955994741784, 8922.241609463203, 9002.324825638103, 9005.054469280762, 9010.14403631654, 9016.278697704234, 4582.793774280165, 5366.114591092465, 5370.489803468664, 5377.381601115882, 5380.354357477127, 3494.8427320770625, 3585.372559802024, 3716.163863130801, 3725.6460636239512, 3733.253349330145, 1070.4594179939158, 1073.0932362153967, 1084.322298563398, 1095.2114477576213, 1104.9944750276197, 11643.825342619783, 11770.941145294219, 11779.175457717964, 11784.705382365424, 11792.705182370424, 4530.88672783183, 4616.446026476777, 4698.788554514987, 4707.317438971399, 4819.325294458778, 8787.34094942869, 9864.753381165528, 9997.300188986701, 10022.649207277856, 10028.949855250678, 5293.100172970528, 5320.068987927098, 5375.919361209585, 5398.08232600451, 5411.404745477994, 4710.552497512731, 4890.486498917562, 4934.481879402611, 4939.87650308738, 4958.975205124024, 3778.3954567269107, 3809.6190380961684, 3823.2926908522013, 3842.7502212356108, 3877.9030524236555, 1019.2457581389866, 1180.8405865208088, 1185.4843143232722, 1197.4790965929674, 1197.7724232395817, 645.8901986662185, 794.6543253684665, 896.820635872828, 914.0630853375211, 950.50098698184, 5577.553795696334, 5914.172015917775, 5924.851878703067, 5967.701614919237, 6092.939070609325, 461.44856896009617, 462.7269910752672, 467.53947361848554, 468.770302551754, 609.7530500147448, 3838.5201849768778, 3987.0222426003174, 4055.6755459563165, 4073.368627862686, 4126.195391898611, 10417.843732344021, 10658.78682426347, 10661.84007239892, 10925.907409259169, 10928.726781830517, 10592.523336449878, 10624.309419887682, 10818.891811081945, 10868.72828179286, 10891.074258687942, 8021.358291336679, 8136.75589732309, 8142.145074767182, 8210.384221183434, 8300.252977232078, 5092.363454568179, 5842.2405087338375, 5890.293164820249, 5900.203472531153, 5989.1615173874825, 9744.902550974399, 9937.950310248403, 10020.198384129106, 10087.495625218857, 10219.489025548544, 9975.950120249643, 10106.39361638304, 10313.381197128196, 10368.170546356425, 10474.057334839901, 6971.721131154697, 7016.36852683261, 7024.824379390556, 7029.3673569379, 7104.254053324326, 8224.177582241968, 8303.4602750821, 8348.37387195975, 8388.664453421794, 8393.496390216607, 7424.443166762407, 7493.70025198986, 7497.850042999, 7563.848723025619, 7668.849672548939, 6484.935150648433, 7178.24628414009, 7343.70625174987, 7345.767862140641, 7518.699252030076, 6960.060391847036, 6969.0940177776565, 7058.223595404604, 7062.964685176747, 7080.256573059961, 5024.799008039638, 5052.924206136984, 5101.46434624374, 5109.054824857413, 5197.24639927216, 4984.975075124601, 5036.722980236123, 5150.356205474316, 5185.351831021122, 5261.605379596622], "value": 4496.408522530679, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [303.4552977405557, 304.88871561879915, 310.15792124382295, 318.6638096807868, 320.88287774962146, 4640.234361330381, 5221.530062294398, 5257.36911570612, 5292.470752924705, 5307.2569840222395, 4887.951120488794, 4946.332245146904, 5050.171903733903, 5165.483451654833, 5188.37739471263, 3540.858365665383, 3884.2231553689235, 3953.861614843481, 3968.353133449615, 4020.8235493959737, 3370.595528536579, 3700.85196592136, 3951.0, 3967.7817720025387, 4002.2195671844, 3198.824064676442, 3755.061234691327, 3813.2564149990762, 3991.5409727881374, 4012.1574469361467, 3915.5105611798526, 3961.1087505311075, 3966.643002129822, 3973.562908080109, 3982.9331414810435, 2935.6330458692664, 3123.2660324823646, 3315.751318651111, 3471.2536804587135, 3477.7565570410084, 4785.401824771903, 5027.818462661272, 5040.899182016376, 5054.19132938728, 5100.438951715309, 3975.363941769332, 4518.864434066983, 4537.818487260505, 4551.590356867865, 4553.840615578439, 4334.631556317717, 4757.35775670287, 4980.825671101494, 5204.34945631796, 5484.588655850827, 3395.2514455055666, 3567.2865426914723, 3821.8280177392085, 3846.3461211593817, 3855.20968201518, 5511.476409741068, 5597.384287728347, 5687.7156142192725, 5759.2512973313205, 5780.53755699543, 3880.8447662093763, 3883.96116038836, 3892.2993861104706, 3896.279188350164, 3896.8441262349756, 3349.614794298687, 3948.3287841066513, 3988.780617066031, 3990.022444501116, 4005.759654420744, 3855.1711382052995, 4052.9189416212103, 4332.545082766327, 4583.770811459413, 4629.0, 4938.629457860247, 5004.899902001939, 5515.641483303572, 5521.861953451196, 5549.889002219933, 1194.814803705427, 1253.6991122130623, 1305.6083175047447, 1321.3723481346283, 1337.164272329794, 805.2028491793083, 836.6154015105011, 895.0467751844265, 938.2822141062093, 950.3015283766426, 4298.376735373354, 4688.710604583766, 4990.0, 5149.382074151126, 5154.329937108152, 5632.52123569493, 5652.519535839499, 5656.274836174924, 5656.43435656431, 5662.234613981656, 4301.870943871689, 4816.662833601685, 5090.592752579785, 5106.285120083191, 5139.743012849342, 2921.561765735114, 2956.822590644568, 2963.97742778741, 2964.303388703653, 2971.1281892407646, 4727.7163370197895, 5536.52939500147, 5568.8862227554655, 5746.827595172152, 5916.497097746654, 5024.723640199751, 5038.546530812245, 5108.821191258363, 5424.864378390493, 5503.862403439946, 5467.644603100785, 5682.857928551736, 5715.971420142872, 5780.566457515703, 5797.101449275369, 4685.718856868462, 5171.405288391883, 5490.670559766427, 5556.694381809039, 5592.748326325243, 4366.279563871925, 4574.725516469022, 5212.921806172986, 5509.118541033416, 5534.723263836872, 5168.689878607295, 5220.233276363381, 5514.0, 5699.316082070178, 5797.797077102281, 3534.091590541079, 3865.6714179295077, 3882.398228274622, 3894.1822217334675, 3901.8049097545577, 4832.888435659747, 5103.744812759422, 5105.953279577752, 5122.615803814654, 5554.944450555443, 5465.344158700981, 5469.206964990134, 5476.507114359727, 5477.726113694379, 5501.477359650827, 4956.454789973172, 5079.974600126976, 5423.213634022965, 5438.374586922555, 5520.089185284383, 5147.639665223398, 5152.793888244578, 5357.973210133924, 5416.918746218731, 5421.512063914267, 4299.505556860876, 4681.99337142516, 5226.268322434787, 5342.504098852328, 5455.345358556999, 4316.158349121891, 4744.3357929890535, 4810.783514741775, 5384.949934762683, 5522.41043321844], "value": 3971.6126438885235, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.8967749676297, 764.8890910817931, 787.8660627693292, 815.3314282288526, 824.8185399212175, 14882.702345953074, 16060.839391606096, 16129.354825806982, 16145.515634530953, 16156.75764863528, 14329.0, 14438.927805360985, 14457.63855903603, 14460.987730858844, 14473.131612103281, 699.3565919354181, 716.2228981555019, 720.1825927572218, 859.2481578618708, 861.750092473182, 871.0723079919896, 883.5228976352762, 894.342658146262, 897.8383890899636, 898.420518765396, 950.2160717408166, 953.6233187890779, 953.6805170267969, 953.704351650987, 958.1616085924816, 731.7804658602452, 943.4103685196752, 947.6920000999668, 948.6063283737275, 949.4018768176074, 566.9659820410789, 671.2616122265468, 690.1752405874981, 690.6098054599136, 692.003514938487, 13480.595582132551, 13492.257925814076, 13644.386002629906, 13648.658783530393, 13728.65678358039, 13216.867831321755, 13749.645075642467, 13775.311234438239, 13791.172529648253, 14215.289235538181, 12381.566645167466, 12536.373181340987, 12611.684707882285, 12641.178323409042, 12993.415296311689, 4510.120526497334, 4572.839950601746, 4619.353290539327, 4650.976745116287, 4782.856514304576, 123163.0, 125174.0, 129202.3539882304, 129265.0, 129424.0, 10889.945550272198, 10928.453577321261, 10934.343939363664, 10949.069329106957, 11124.165687573302, 17584.64830703379, 20033.899830501046, 21000.264990725253, 21012.26457073995, 21021.78978210229, 7192.7122915083855, 7759.301662850257, 7827.295543401121, 7832.647530861125, 7911.0, 3718.7396882217986, 4149.813258403379, 4153.273177193979, 4275.764832934156, 4389.890252743643, 3394.9830250848586, 3473.756837021435, 3516.226430185355, 3520.313538859949, 3522.0490467574123, 626.8432891777056, 639.7249182851418, 640.2509064394669, 650.1385663995226, 650.7787352300146, 583.8452810005352, 603.7132362127954, 604.694629212245, 611.804222648757, 614.9569530132848, 8374.539400332918, 8628.568571571397, 9004.0, 9022.097790220925, 9025.6891917547, 541.7535021565258, 595.6604735300837, 620.460199626319, 633.395107672172, 642.389729756733, 4223.429836972031, 4372.256716358227, 4378.0, 4396.2569464719645, 4512.0, 96782.5160874191, 99843.50078249708, 101692.98307016835, 102368.97631023594, 151660.0, 106420.0, 110337.89662103436, 113331.0, 135350.0, 141024.0, 76277.0, 103084.48457757663, 104768.47615761873, 106341.46829265804, 106466.0, 9470.51398014048, 9884.901150988399, 10018.448985305587, 10103.54534045955, 10106.8483972739, 42533.78733106315, 60748.0, 63296.367036329044, 64484.0, 64600.0309995341, 79173.60413197897, 82086.0, 87990.56004719935, 89557.0, 90689.09310906807, 13078.019148563706, 13302.467901284228, 13538.052336336363, 13676.0, 13893.513727019506, 11793.823092653789, 12297.508099675913, 12333.691657708629, 14443.638909027359, 15157.393704252148, 13421.0, 13932.651683707989, 14059.297035147996, 14172.503962361268, 14643.0, 13350.866491334962, 13781.448742050206, 14031.36858841375, 14166.504172353918, 14503.057301275383, 13227.206367617973, 13627.79558306646, 13883.514077007256, 14040.929795350958, 14162.929185354007, 11133.498992545194, 11556.94221528887, 11560.710982225512, 11612.361320127475, 11687.0, 11162.776744465227, 11252.805702887857, 11462.770744585228, 11582.88417115818, 11586.47860846247], "value": 7726.88283600103, "unit": "score"}], "name": "dromaeo_css", "value": 7284.408988640108, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 114.0, 113.0, 111.0, 115.0, 112.0, 113.0, 113.0, 110.0], "value": 113.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 109.0, 107.0, 140.0, 134.0, 139.0, 109.0, 108.0, 136.0, 106.0], "value": 123.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 183.0, 186.0, 183.0, 185.0, 185.0, 181.0, 182.0, 182.0, 184.0], "value": 183.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 58.0, 74.0, 113.0, 86.0, 79.0, 58.0, 58.0, 82.0, 57.0], "value": 74.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 70.0, 93.0, 70.0, 68.0, 69.0, 93.0, 69.0, 69.0, 91.0], "value": 78.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 83.0, 88.0, 85.0, 87.0, 84.0, 89.0, 83.0, 87.0, 83.0], "value": 85.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 95.0, 96.0, 95.0, 95.0, 103.0, 94.0, 96.0, 95.0, 94.0], "value": 95.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 127.0, 85.0, 83.0, 83.0, 84.0, 85.0, 85.0, 84.0, 92.0], "value": 93.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 58.0, 57.0, 57.0, 54.0, 54.0, 55.0, 54.0, 55.0, 74.0], "value": 57.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 75.0, 75.0, 75.0, 69.0, 70.0, 75.0, 105.0, 106.0], "value": 78.5, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 87.0, 86.0, 89.0, 85.0, 87.0, 85.0, 82.0, 90.0, 84.0], "value": 85.9, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 139.0, 139.0, 138.0, 139.0, 141.0, 140.0, 183.0, 144.0], "value": 143.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [171.0, 169.0, 174.0, 171.0, 171.0, 171.0, 173.0, 171.0, 167.0, 173.0], "value": 171.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 68.0, 68.0, 68.0, 67.0, 67.0, 66.0], "value": 67.7, "unit": "score"}], "name": "kraken", "value": 1452.1, "alertThreshold": 2.0}]} 22:44:10 INFO - Return code: 0 22:44:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 22:44:10 INFO - # TBPL SUCCESS # 22:44:10 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 22:44:10 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 22:44:10 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 22:44:10 INFO - 2016-09-21 22:44:10.849 screenresolution[1671:10100] starting screenresolution argv=screenresolution get 22:44:10 INFO - 2016-09-21 22:44:10.852 screenresolution[1671:10100] Display 0: 1600x1200x32@60 22:44:10 INFO - 2016-09-21 22:44:10.858 screenresolution[1672:10103] starting screenresolution argv=screenresolution list 22:44:10 INFO - Available Modes on Display 0 22:44:10 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 22:44:10 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 22:44:10 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 22:44:10 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 22:44:11 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 22:44:11 INFO - Intel Iris: 22:44:11 INFO - Chipset Model: Intel Iris 22:44:11 INFO - Type: GPU 22:44:11 INFO - Bus: Built-In 22:44:11 INFO - VRAM (Dynamic, Max): 1536 MB 22:44:11 INFO - Vendor: Intel (0x8086) 22:44:11 INFO - Device ID: 0x0a2e 22:44:11 INFO - Revision ID: 0x0009 22:44:11 INFO - Displays: 22:44:11 INFO - Display: 22:44:11 INFO - Resolution: 1600 x 1200 @ 60 Hz 22:44:11 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 22:44:11 INFO - Display Serial Number: 1600x1200 60 22:44:11 INFO - Main Display: Yes 22:44:11 INFO - Mirror: Off 22:44:11 INFO - Online: Yes 22:44:11 INFO - Rotation: Supported 22:44:11 INFO - Return code: 0 22:44:11 INFO - Running post-action listener: _resource_record_post_action 22:44:11 INFO - [mozharness: 2016-09-22 05:44:11.095406Z] Finished run-tests step (success) 22:44:11 INFO - Running post-run listener: _resource_record_post_run 22:44:11 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 33111040; Write bytes: 706272256; Read time: 311; Write time: 2079 22:44:11 INFO - TinderboxPrint: CPU usage
53.6% 22:44:11 INFO - TinderboxPrint: I/O read bytes / time
33,111,040 / 311 22:44:11 INFO - TinderboxPrint: I/O write bytes / time
706,272,256 / 2,079 22:44:11 INFO - TinderboxPrint: CPU idle
2,248.2 (46.4%) 22:44:11 INFO - TinderboxPrint: CPU system
974.5 (20.1%) 22:44:11 INFO - TinderboxPrint: CPU user
1,627.0 (33.5%) 22:44:11 INFO - TinderboxPrint: Swap in / out
433,827,840 / 0 22:44:11 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 393470464; Write bytes: 387840000; Read time: 29435; Write time: 749 22:44:11 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 31316992; Write bytes: 297419776; Read time: 293; Write time: 1284 22:44:11 INFO - Running post-run listener: _upload_blobber_files 22:44:11 INFO - Blob upload gear active. 22:44:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 22:44:11 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 22:44:11 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'] 22:44:11 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 22:44:11 INFO - (blobuploader) - INFO - Open directory for files ... 22:44:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 22:44:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:12 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 22:44:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:12 INFO - (blobuploader) - INFO - Done attempting. 22:44:12 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 22:44:12 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:12 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:13 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 22:44:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:13 INFO - (blobuploader) - INFO - Done attempting. 22:44:13 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 22:44:13 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 22:44:13 INFO - (blobuploader) - INFO - Uploading, attempt #1. 22:44:17 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 22:44:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 22:44:17 INFO - (blobuploader) - INFO - Done attempting. 22:44:17 INFO - (blobuploader) - INFO - Iteration through files over. 22:44:17 INFO - Return code: 0 22:44:17 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 22:44:17 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 22:44:17 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25ac3ee3dbd721f5a169f73254b49ef81888029a2d64cb0e1d24d699fa95e3b89532eff79c9b68f4199d6c2ee78873243bc92a507a7b3fbab589ff5039002acf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e659ed1bc99e9fbeb13e7ab8365e12198a0dce935ff2c97fb5fe5e519dc70d60a84969f307492a92c346c0a5f8837f0254554f7fb359c44823ef02fc98079c51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e04cad1cbae141d0bc1b6aff66727329f331aa13ad6e1002cf0d7859218398038b3493a96ae7e0dffee47ebc1dad0f3f9a73a012dfc9000ac91416a1c71024c3"} 22:44:17 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 22:44:17 INFO - Writing to file /builds/slave/test/properties/blobber_files 22:44:17 INFO - Contents: 22:44:17 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25ac3ee3dbd721f5a169f73254b49ef81888029a2d64cb0e1d24d699fa95e3b89532eff79c9b68f4199d6c2ee78873243bc92a507a7b3fbab589ff5039002acf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e659ed1bc99e9fbeb13e7ab8365e12198a0dce935ff2c97fb5fe5e519dc70d60a84969f307492a92c346c0a5f8837f0254554f7fb359c44823ef02fc98079c51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e04cad1cbae141d0bc1b6aff66727329f331aa13ad6e1002cf0d7859218398038b3493a96ae7e0dffee47ebc1dad0f3f9a73a012dfc9000ac91416a1c71024c3"} 22:44:17 INFO - Running post-run listener: copy_logs_to_upload_dir 22:44:17 INFO - Copying logs to upload dir... 22:44:17 INFO - mkdir: /builds/slave/test/build/upload/logs 22:44:17 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.418995 ========= master_lag: 2.98 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 18 secs) (at 2016-09-21 22:44:20.162795) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 22:44:20.166665) ========= 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.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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/25ac3ee3dbd721f5a169f73254b49ef81888029a2d64cb0e1d24d699fa95e3b89532eff79c9b68f4199d6c2ee78873243bc92a507a7b3fbab589ff5039002acf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e659ed1bc99e9fbeb13e7ab8365e12198a0dce935ff2c97fb5fe5e519dc70d60a84969f307492a92c346c0a5f8837f0254554f7fb359c44823ef02fc98079c51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e04cad1cbae141d0bc1b6aff66727329f331aa13ad6e1002cf0d7859218398038b3493a96ae7e0dffee47ebc1dad0f3f9a73a012dfc9000ac91416a1c71024c3"} build_url:https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010904 build_url: 'https://queue.taskcluster.net/v1/task/KBFVjOghQDqmjl_I6boKvg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/25ac3ee3dbd721f5a169f73254b49ef81888029a2d64cb0e1d24d699fa95e3b89532eff79c9b68f4199d6c2ee78873243bc92a507a7b3fbab589ff5039002acf", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e659ed1bc99e9fbeb13e7ab8365e12198a0dce935ff2c97fb5fe5e519dc70d60a84969f307492a92c346c0a5f8837f0254554f7fb359c44823ef02fc98079c51", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/e04cad1cbae141d0bc1b6aff66727329f331aa13ad6e1002cf0d7859218398038b3493a96ae7e0dffee47ebc1dad0f3f9a73a012dfc9000ac91416a1c71024c3"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-21 22:44:20.205357) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:44:20.205703) ========= 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.fRBowlGHEu/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.UQcD2WuTp9/Listeners TMPDIR=/var/folders/tp/rw9d9fx970v_2f584_850cxc00000w/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.005326 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-21 22:44:20.254869) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 22:44:20.255193) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-21 22:44:20.255526) ========= ========= Total master_lag: 3.27 =========