builder: pine_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0357 starttime: 1493350724.03 results: success (0) buildid: 20170427113225 builduid: 4be79eac2d4f4fe3b156a4752812ee67 revision: 720f68335aeed63d96f86be7b3f90aba8bbc1f6a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.030919) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.031392) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.031673) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.282066) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.282545) ========= 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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 --2017-04-27 20:38:44-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 22.7M=0.001s 2017-04-27 20:38:44 (22.7 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.156241 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.463872) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.464329) ========= 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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.028204 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:44.515400) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 20:38:44.515813) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 720f68335aeed63d96f86be7b3f90aba8bbc1f6a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 720f68335aeed63d96f86be7b3f90aba8bbc1f6a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 2017-04-27 20:38:44,624 truncating revision to first 12 chars 2017-04-27 20:38:44,624 Setting DEBUG logging. 2017-04-27 20:38:44,624 attempt 1/10 2017-04-27 20:38:44,624 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/720f68335aee?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-27 20:38:45,804 unpacking tar archive at: pine-720f68335aee/testing/mozharness/ program finished with exit code 0 elapsedTime=1.448550 ========= master_lag: 0.07 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-04-27 20:38:46.038552) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:46.038973) ========= script_repo_revision: 720f68335aeed63d96f86be7b3f90aba8bbc1f6a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-27 20:38:46.039401) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-04-27 20:38:46.039673) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 1 secs) (at 2017-04-27 20:38:47.173902) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-27 20:38:47.174330) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:38:47 INFO - MultiFileLogger online at 20170427 20:38:47 in /builds/slave/test 20:38:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 20:38:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:38:47 INFO - {'append_to_log': False, 20:38:47 INFO - 'base_work_dir': '/builds/slave/test', 20:38:47 INFO - 'blob_upload_branch': 'Pine', 20:38:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:38:47 INFO - 'branch': 'Pine', 20:38:47 INFO - 'buildbot_json_path': 'buildprops.json', 20:38:47 INFO - 'config_files': ('talos/mac_config.py',), 20:38:47 INFO - 'default_actions': ('clobber', 20:38:47 INFO - 'read-buildbot-config', 20:38:47 INFO - 'download-and-extract', 20:38:47 INFO - 'populate-webroot', 20:38:47 INFO - 'create-virtualenv', 20:38:47 INFO - 'install', 20:38:47 INFO - 'run-tests'), 20:38:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:38:47 INFO - 'download_minidump_stackwalk': True, 20:38:47 INFO - 'download_symbols': 'ondemand', 20:38:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:38:47 INFO - 'tooltool.py': '/tools/tooltool.py', 20:38:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:38:47 INFO - '/tools/misc-python/virtualenv.py')}, 20:38:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:38:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:38:47 INFO - 'gecko_profile': False, 20:38:47 INFO - 'gecko_profile_interval': 0, 20:38:47 INFO - 'installer_path': 'installer.exe', 20:38:47 INFO - 'log_level': 'info', 20:38:47 INFO - 'log_name': 'talos', 20:38:47 INFO - 'log_to_console': True, 20:38:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:38:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:38:47 INFO - 'opt_config_files': (), 20:38:47 INFO - 'pip_index': False, 20:38:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:47 INFO - 'cmd': ('bash', 20:38:47 INFO - '-c', 20:38:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:47 INFO - 'enabled': True, 20:38:47 INFO - 'halt_on_failure': False, 20:38:47 INFO - 'name': 'check_screen_resolution'},), 20:38:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:38:47 INFO - 'cmd': ('bash', 20:38:47 INFO - '-c', 20:38:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:38:47 INFO - 'enabled': True, 20:38:47 INFO - 'halt_on_failure': False, 20:38:47 INFO - 'name': 'check_screen_resolution'},), 20:38:47 INFO - 'run_cmd_checks_enabled': True, 20:38:47 INFO - 'suite': 'dromaeojs', 20:38:47 INFO - 'system_bits': '32', 20:38:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:38:47 INFO - 'title': 't-yosemite-r7-0357', 20:38:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:38:47 INFO - 'use_talos_json': True, 20:38:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:38:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:38:47 INFO - 'work_dir': 'build'} 20:38:47 INFO - [mozharness: 2017-04-28 03:38:47.392801Z] Running clobber step. 20:38:47 INFO - Running pre-action listener: _resource_record_pre_action 20:38:47 INFO - Running main action method: clobber 20:38:47 INFO - rmtree: /builds/slave/test/build 20:38:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:38:49 INFO - Running post-action listener: _resource_record_post_action 20:38:49 INFO - [mozharness: 2017-04-28 03:38:49.808252Z] Finished clobber step (success) 20:38:49 INFO - [mozharness: 2017-04-28 03:38:49.808376Z] Running read-buildbot-config step. 20:38:49 INFO - Running pre-action listener: _resource_record_pre_action 20:38:49 INFO - Running main action method: read_buildbot_config 20:38:49 INFO - Using buildbot properties: 20:38:49 INFO - { 20:38:49 INFO - "project": "", 20:38:49 INFO - "product": "firefox", 20:38:49 INFO - "script_repo_revision": "default", 20:38:49 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs", 20:38:49 INFO - "repository": "", 20:38:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs", 20:38:49 INFO - "buildid": "20170427113225", 20:38:49 INFO - "pgo_build": "False", 20:38:49 INFO - "basedir": "/builds/slave/test", 20:38:49 INFO - "buildnumber": 9, 20:38:49 INFO - "slavename": "t-yosemite-r7-0357", 20:38:49 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 20:38:49 INFO - "platform": "yosemite_r7", 20:38:49 INFO - "scheduler": "tests-pine-macosx64-talos", 20:38:49 INFO - "branch": "pine", 20:38:49 INFO - "repo_path": "projects/pine", 20:38:49 INFO - "revision": "720f68335aeed63d96f86be7b3f90aba8bbc1f6a", 20:38:49 INFO - "stage_platform": "macosx64", 20:38:49 INFO - "builduid": "4be79eac2d4f4fe3b156a4752812ee67", 20:38:49 INFO - "slavebuilddir": "test" 20:38:49 INFO - } 20:38:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 20:38:49 INFO - Running post-action listener: _resource_record_post_action 20:38:49 INFO - [mozharness: 2017-04-28 03:38:49.811768Z] Finished read-buildbot-config step (success) 20:38:49 INFO - [mozharness: 2017-04-28 03:38:49.811883Z] Running download-and-extract step. 20:38:49 INFO - Running pre-action listener: _resource_record_pre_action 20:38:49 INFO - Running main action method: download_and_extract 20:38:49 INFO - mkdir: /builds/slave/test/build/tests 20:38:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:38:49 INFO - trying https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 20:38:49 INFO - Downloading https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:38:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 20:38:51 INFO - Downloaded 1325 bytes. 20:38:51 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 20:38:51 INFO - Using the following test package requirements: 20:38:51 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 20:38:51 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:38:51 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 20:38:51 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 20:38:51 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'jsshell-mac.zip'], 20:38:51 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 20:38:51 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 20:38:51 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 20:38:51 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 20:38:51 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 20:38:51 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 20:38:51 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 20:38:51 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 20:38:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:38:51 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:38:51 INFO - Fetch https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:38:52 INFO - Content-Length response header: 52532350 20:38:52 INFO - Bytes received: 52532350 20:39:09 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 20:39:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 20:39:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 20:39:09 INFO - Fetch https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 20:39:10 INFO - Content-Length response header: 52532350 20:39:10 INFO - Bytes received: 52532350 20:39:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 20:39:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 20:39:26 INFO - Fetch https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 20:39:27 INFO - Content-Length response header: 14408809 20:39:27 INFO - Bytes received: 14408809 20:39:28 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')]} 20:39:28 INFO - trying https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:39:28 INFO - Downloading https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 20:39:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 20:39:30 INFO - Downloaded 71178055 bytes. 20:39:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:39:30 INFO - mkdir: /builds/slave/test/properties 20:39:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:39:30 INFO - Writing to file /builds/slave/test/properties/build_url 20:39:30 INFO - Contents: 20:39:30 INFO - build_url:https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 20:39:31 INFO - Running post-action listener: _resource_record_post_action 20:39:31 INFO - Running post-action listener: set_extra_try_arguments 20:39:31 INFO - [mozharness: 2017-04-28 03:39:31.190139Z] Finished download-and-extract step (success) 20:39:31 INFO - [mozharness: 2017-04-28 03:39:31.190283Z] Running populate-webroot step. 20:39:31 INFO - Running pre-action listener: _resource_record_pre_action 20:39:31 INFO - Running main action method: populate_webroot 20:39:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:39:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:39:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:39:31 INFO - '--tppagecycles', 20:39:31 INFO - '7'], 20:39:31 INFO - 'tests': ['tsvgm']}}, 20:39:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:39:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:39:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:39:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:39:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:39:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:39:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['damp', 'tps']}, 20:39:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'tests': ['damp', 'tps']}, 20:39:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['dromaeo_dom']}, 20:39:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:39:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 20:39:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 20:39:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'other-e10s': {'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['a11yr', 20:39:31 INFO - 'ts_paint', 20:39:31 INFO - 'tpaint', 20:39:31 INFO - 'sessionrestore', 20:39:31 INFO - 'sessionrestore_no_auto_restore', 20:39:31 INFO - 'tabpaint']}, 20:39:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 20:39:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 20:39:31 INFO - 'bloom_basic_ref']}, 20:39:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['tsvgx', 20:39:31 INFO - 'tsvgr_opacity', 20:39:31 INFO - 'tart', 20:39:31 INFO - 'tscrollx', 20:39:31 INFO - 'cart', 20:39:31 INFO - 'tsvg_static']}, 20:39:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:39:31 INFO - 'tsvgr_opacity', 20:39:31 INFO - 'tart', 20:39:31 INFO - 'tscrollx', 20:39:31 INFO - 'cart', 20:39:31 INFO - 'tsvg_static']}, 20:39:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'talos_options': ['--disable-e10s'], 20:39:31 INFO - 'tests': ['tp5o']}, 20:39:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:39:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'talos_options': ['--disable-e10s', 20:39:31 INFO - '--xperf_path', 20:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:39:31 INFO - 'tests': ['tp5n']}, 20:39:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:39:31 INFO - 'talos_options': ['--xperf_path', 20:39:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:39:31 INFO - 'tests': ['tp5n']}}, 20:39:31 INFO - 'talos.zip': {'path': '', 20:39:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:39:31 INFO - Running post-action listener: _resource_record_post_action 20:39:31 INFO - [mozharness: 2017-04-28 03:39:31.209353Z] Finished populate-webroot step (success) 20:39:31 INFO - [mozharness: 2017-04-28 03:39:31.209439Z] Running create-virtualenv step. 20:39:31 INFO - Running pre-action listener: _resource_record_pre_action 20:39:31 INFO - Running main action method: create_virtualenv 20:39:31 INFO - Creating virtualenv /builds/slave/test/build/venv 20:39:31 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 20:39:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:39:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:39:31 INFO - Using real prefix '/tools/python27' 20:39:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:39:32 INFO - Installing distribute.............................................................................................................................................................................................done. 20:39:34 INFO - Installing pip.................done. 20:39:34 INFO - Return code: 0 20:39:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:39:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:39:35 INFO - Reading from file tmpfile_stdout 20:39:35 INFO - Output received: 20:39:35 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 20:39:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:35 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:35 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 20:39:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 20:39:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:35 INFO - 'HOME': '/Users/cltbld', 20:39:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:35 INFO - 'LOGNAME': 'cltbld', 20:39:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:35 INFO - 'MOZ_NO_REMOTE': '1', 20:39:35 INFO - 'NO_EM_RESTART': '1', 20:39:35 INFO - 'PAGER': '/bin/cat', 20:39:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:35 INFO - 'PWD': '/builds/slave/test', 20:39:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:35 INFO - 'SHELL': '/bin/bash', 20:39:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:35 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:35 INFO - 'USER': 'cltbld', 20:39:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:35 INFO - 'XPC_FLAGS': '0x0', 20:39:35 INFO - 'XPC_SERVICE_NAME': '0', 20:39:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:36 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 20:39:36 INFO - Cleaning up... 20:39:36 INFO - Return code: 0 20:39:36 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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', '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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39: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', 'psutil>=3.1.1'] in /builds/slave/test/build 20:39: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 psutil>=3.1.1 20:39:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:36 INFO - 'HOME': '/Users/cltbld', 20:39:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:36 INFO - 'LOGNAME': 'cltbld', 20:39:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:36 INFO - 'MOZ_NO_REMOTE': '1', 20:39:36 INFO - 'NO_EM_RESTART': '1', 20:39:36 INFO - 'PAGER': '/bin/cat', 20:39:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:36 INFO - 'PWD': '/builds/slave/test', 20:39:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:36 INFO - 'SHELL': '/bin/bash', 20:39:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:36 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:36 INFO - 'USER': 'cltbld', 20:39:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:36 INFO - 'XPC_FLAGS': '0x0', 20:39:36 INFO - 'XPC_SERVICE_NAME': '0', 20:39:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:36 INFO - Downloading/unpacking psutil>=3.1.1 20:39: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 20:39: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 20:39: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 20:39: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 20:39: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 20:39: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 20:39:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:39:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:39:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:39:40 INFO - Installing collected packages: psutil 20:39:40 INFO - Running setup.py install for psutil 20:39:40 INFO - building 'psutil._psutil_osx' extension 20:39:40 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 20:39:41 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 20:39:41 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 20:39:41 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 20:39:41 INFO - building 'psutil._psutil_posix' extension 20:39:41 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 20:39:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:39:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:39:41 INFO - ^ 20:39:41 INFO - 1 warning generated. 20:39:41 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 20:39:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:39:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:39:41 INFO - Successfully installed psutil 20:39:41 INFO - Cleaning up... 20:39:41 INFO - Return code: 0 20:39:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:41 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:41 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 20:39:41 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 20:39:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:41 INFO - 'HOME': '/Users/cltbld', 20:39:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:41 INFO - 'LOGNAME': 'cltbld', 20:39:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:41 INFO - 'MOZ_NO_REMOTE': '1', 20:39:41 INFO - 'NO_EM_RESTART': '1', 20:39:41 INFO - 'PAGER': '/bin/cat', 20:39:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:41 INFO - 'PWD': '/builds/slave/test', 20:39:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:41 INFO - 'SHELL': '/bin/bash', 20:39:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:41 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:41 INFO - 'USER': 'cltbld', 20:39:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:41 INFO - 'XPC_FLAGS': '0x0', 20:39:41 INFO - 'XPC_SERVICE_NAME': '0', 20:39:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:39: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 20:39: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 20:39: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 20:39: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 20:39: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 20:39: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 20:39:44 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:39:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:39:44 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:39:44 INFO - Installing collected packages: mozsystemmonitor 20:39:44 INFO - Running setup.py install for mozsystemmonitor 20:39:44 INFO - Successfully installed mozsystemmonitor 20:39:44 INFO - Cleaning up... 20:39:45 INFO - Return code: 0 20:39:45 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:39:45 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')]} 20:39:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:45 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')]} 20:39:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:45 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:45 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 20:39:45 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 20:39:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:45 INFO - 'HOME': '/Users/cltbld', 20:39:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:45 INFO - 'LOGNAME': 'cltbld', 20:39:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:45 INFO - 'MOZ_NO_REMOTE': '1', 20:39:45 INFO - 'NO_EM_RESTART': '1', 20:39:45 INFO - 'PAGER': '/bin/cat', 20:39:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:45 INFO - 'PWD': '/builds/slave/test', 20:39:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:45 INFO - 'SHELL': '/bin/bash', 20:39:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:45 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:45 INFO - 'USER': 'cltbld', 20:39:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:45 INFO - 'XPC_FLAGS': '0x0', 20:39:45 INFO - 'XPC_SERVICE_NAME': '0', 20:39:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:45 INFO - Downloading/unpacking jsonschema==2.5.1 20:39:45 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 20:39:45 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 20:39:45 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 20:39:45 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 20:39:45 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 20:39:45 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 20:39:47 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:39:47 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:48 INFO - Downloading functools32-3.2.3-2.tar.gz 20:39:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:39:48 INFO - warning: no files found matching '*.txt' 20:39:48 INFO - no previously-included directories found matching 'build' 20:39:48 INFO - no previously-included directories found matching 'dist' 20:39:48 INFO - no previously-included directories found matching '.git*' 20:39:48 INFO - Installing collected packages: jsonschema, functools32 20:39:48 INFO - Running setup.py install for functools32 20:39:48 INFO - warning: no files found matching '*.txt' 20:39:48 INFO - no previously-included directories found matching 'build' 20:39:48 INFO - no previously-included directories found matching 'dist' 20:39:48 INFO - no previously-included directories found matching '.git*' 20:39:48 INFO - Successfully installed jsonschema functools32 20:39:48 INFO - Cleaning up... 20:39:48 INFO - Return code: 0 20:39:48 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:48 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:48 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:48 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:48 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:48 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:48 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 20:39:48 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 20:39:48 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:48 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:48 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:48 INFO - 'HOME': '/Users/cltbld', 20:39:48 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:48 INFO - 'LOGNAME': 'cltbld', 20:39:48 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:48 INFO - 'MOZ_NO_REMOTE': '1', 20:39:48 INFO - 'NO_EM_RESTART': '1', 20:39:48 INFO - 'PAGER': '/bin/cat', 20:39:48 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:48 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:48 INFO - 'PWD': '/builds/slave/test', 20:39:48 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:48 INFO - 'SHELL': '/bin/bash', 20:39:48 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:48 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:48 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:48 INFO - 'USER': 'cltbld', 20:39:48 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:48 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:48 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:48 INFO - 'XPC_FLAGS': '0x0', 20:39:48 INFO - 'XPC_SERVICE_NAME': '0', 20:39:48 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:49 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:39:49 INFO - Cleaning up... 20:39:49 INFO - Return code: 0 20:39:49 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:39:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:39:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:49 INFO - 'HOME': '/Users/cltbld', 20:39:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:49 INFO - 'LOGNAME': 'cltbld', 20:39:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:49 INFO - 'MOZ_NO_REMOTE': '1', 20:39:49 INFO - 'NO_EM_RESTART': '1', 20:39:49 INFO - 'PAGER': '/bin/cat', 20:39:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:49 INFO - 'PWD': '/builds/slave/test', 20:39:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:49 INFO - 'SHELL': '/bin/bash', 20:39:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:49 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:49 INFO - 'USER': 'cltbld', 20:39:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:49 INFO - 'XPC_FLAGS': '0x0', 20:39:49 INFO - 'XPC_SERVICE_NAME': '0', 20:39:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:49 INFO - Downloading/unpacking blobuploader==1.2.4 20:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:39:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:39:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:39:51 INFO - Downloading blobuploader-1.2.4.tar.gz 20:39:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:39:52 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:39:52 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:52 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 20:39:53 INFO - Downloading docopt-0.6.1.tar.gz 20:39:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:39:53 INFO - Installing collected packages: blobuploader, requests, docopt 20:39:53 INFO - Running setup.py install for blobuploader 20:39:53 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:39:53 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:39:53 INFO - Running setup.py install for requests 20:39:53 INFO - Running setup.py install for docopt 20:39:54 INFO - Successfully installed blobuploader requests docopt 20:39:54 INFO - Cleaning up... 20:39:54 INFO - Return code: 0 20:39:54 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:39:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:54 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:54 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 20:39:54 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 20:39:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:54 INFO - 'HOME': '/Users/cltbld', 20:39:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:54 INFO - 'LOGNAME': 'cltbld', 20:39:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:54 INFO - 'MOZ_NO_REMOTE': '1', 20:39:54 INFO - 'NO_EM_RESTART': '1', 20:39:54 INFO - 'PAGER': '/bin/cat', 20:39:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:54 INFO - 'PWD': '/builds/slave/test', 20:39:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:54 INFO - 'SHELL': '/bin/bash', 20:39:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:54 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:54 INFO - 'USER': 'cltbld', 20:39:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:54 INFO - 'XPC_FLAGS': '0x0', 20:39:54 INFO - 'XPC_SERVICE_NAME': '0', 20:39:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-rIm6K0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-BkYWpZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-iswsdu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-J0XdqO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-L5M74o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:54 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:54 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-FBPRdF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-FV9HNp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-9vl7Gr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-k03B33-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-kjabKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-kedYKz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-1LxC8r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-dwwiJ6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-cir4i0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:39:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:55 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-9GvqfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:39:56 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-72iadD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:39:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:39:56 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-LPYsYF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:39:56 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:39:56 INFO - Running setup.py install for manifestparser 20:39:56 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:39:56 INFO - Running setup.py install for mozcrash 20:39:56 INFO - Running setup.py install for mozdebug 20:39:56 INFO - Running setup.py install for mozdevice 20:39:56 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:39:56 INFO - Running setup.py install for mozfile 20:39:56 INFO - Running setup.py install for mozhttpd 20:39:57 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Running setup.py install for mozinfo 20:39:57 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Running setup.py install for mozInstall 20:39:57 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Running setup.py install for mozleak 20:39:57 INFO - Running setup.py install for mozlog 20:39:57 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Running setup.py install for moznetwork 20:39:57 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:39:57 INFO - Running setup.py install for mozprocess 20:39:58 INFO - Running setup.py install for mozprofile 20:39:58 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:39:58 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:39:58 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:39:58 INFO - Running setup.py install for mozrunner 20:39:58 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:39:58 INFO - Running setup.py install for mozscreenshot 20:39:58 INFO - Running setup.py install for moztest 20:39:58 INFO - Running setup.py install for mozversion 20:39:58 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:39:58 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:39:58 INFO - Cleaning up... 20:39:58 INFO - Return code: 0 20:39:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:39: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')]} 20:39:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:39:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39: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')]} 20:39:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:39:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:39:58 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:39:58 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 20:39:58 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 20:39:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:39:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:39:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:39:58 INFO - 'HOME': '/Users/cltbld', 20:39:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:39:58 INFO - 'LOGNAME': 'cltbld', 20:39:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:39:58 INFO - 'MOZ_NO_REMOTE': '1', 20:39:58 INFO - 'NO_EM_RESTART': '1', 20:39:58 INFO - 'PAGER': '/bin/cat', 20:39:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:39:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:39:58 INFO - 'PWD': '/builds/slave/test', 20:39:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:39:58 INFO - 'SHELL': '/bin/bash', 20:39:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:39:58 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:39:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:39:58 INFO - 'USER': 'cltbld', 20:39:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:39:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:39:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:39:58 INFO - 'XPC_FLAGS': '0x0', 20:39:58 INFO - 'XPC_SERVICE_NAME': '0', 20:39:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:39:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-zvTJrZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-I4gAFr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-npc22d-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-Wx653u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:39:59 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ZpYV3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-GM7f45-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-HjbGnJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ZKH2qC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:39:59 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)) 20:39:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:39:59 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-ZAGDGO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:40:00 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-pzrTeh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:40:00 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-1iPU0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:40:00 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-7A9d_T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-KIwIKZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:40:00 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-nEX1Ll-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-zmZClr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:40:00 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-YMG4wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 20:40:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:40:00 INFO - Running setup.py (path:/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/pip-fjk7Iz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:40:00 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)) 20:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:40:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 20:40:00 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)) 20:40:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:40:00 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 20:40:00 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:40:00 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:40:00 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:40:00 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:40:00 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:40:03 INFO - Downloading blessings-1.6.tar.gz 20:40:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:40:03 INFO - Installing collected packages: blessings 20:40:03 INFO - Running setup.py install for blessings 20:40:03 INFO - Successfully installed blessings 20:40:03 INFO - Cleaning up... 20:40:03 INFO - Return code: 0 20:40:03 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:40:03 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:40:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:40:04 INFO - Reading from file tmpfile_stdout 20:40:04 INFO - Current package versions: 20:40:04 INFO - blessings == 1.6 20:40:04 INFO - blobuploader == 1.2.4 20:40:04 INFO - docopt == 0.6.1 20:40:04 INFO - functools32 == 3.2.3-2 20:40:04 INFO - jsonschema == 2.5.1 20:40:04 INFO - manifestparser == 1.1 20:40:04 INFO - mozInstall == 1.12 20:40:04 INFO - mozcrash == 1.0 20:40:04 INFO - mozdebug == 0.1 20:40:04 INFO - mozdevice == 0.50 20:40:04 INFO - mozfile == 1.2 20:40:04 INFO - mozhttpd == 0.7 20:40:04 INFO - mozinfo == 0.9 20:40:04 INFO - mozleak == 0.1 20:40:04 INFO - mozlog == 3.4 20:40:04 INFO - moznetwork == 0.27 20:40:04 INFO - mozprocess == 0.25 20:40:04 INFO - mozprofile == 0.28 20:40:04 INFO - mozrunner == 6.13 20:40:04 INFO - mozscreenshot == 0.1 20:40:04 INFO - mozsystemmonitor == 0.3 20:40:04 INFO - moztest == 0.8 20:40:04 INFO - mozversion == 1.4 20:40:04 INFO - psutil == 3.1.1 20:40:04 INFO - requests == 1.2.3 20:40:04 INFO - wsgiref == 0.1.2 20:40:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:40: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')]} 20:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:40: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')]} 20:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:40:04 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:40:04 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 20:40:04 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 20:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:40:04 INFO - 'HOME': '/Users/cltbld', 20:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:40:04 INFO - 'LOGNAME': 'cltbld', 20:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:40:04 INFO - 'MOZ_NO_REMOTE': '1', 20:40:04 INFO - 'NO_EM_RESTART': '1', 20:40:04 INFO - 'PAGER': '/bin/cat', 20:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:40:04 INFO - 'PWD': '/builds/slave/test', 20:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:40:04 INFO - 'SHELL': '/bin/bash', 20:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:40:04 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:40:04 INFO - 'USER': 'cltbld', 20:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:40:04 INFO - 'XPC_FLAGS': '0x0', 20:40:04 INFO - 'XPC_SERVICE_NAME': '0', 20:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 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)) 20:40:04 INFO - Cleaning up... 20:40:04 INFO - Return code: 0 20:40:04 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:40: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')]} 20:40:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:40:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:40: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')]} 20:40:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:40:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:40:04 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 0x108059b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10805c768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc9e845b480>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, '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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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 20:40:04 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 20:40:04 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 20:40:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:40:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:40:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:40:04 INFO - 'HOME': '/Users/cltbld', 20:40:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:40:04 INFO - 'LOGNAME': 'cltbld', 20:40:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:40:04 INFO - 'MOZ_NO_REMOTE': '1', 20:40:04 INFO - 'NO_EM_RESTART': '1', 20:40:04 INFO - 'PAGER': '/bin/cat', 20:40:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:40:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:40:04 INFO - 'PWD': '/builds/slave/test', 20:40:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:40:04 INFO - 'SHELL': '/bin/bash', 20:40:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:40:04 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:40:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:40:04 INFO - 'USER': 'cltbld', 20:40:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:40:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:40:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:40:04 INFO - 'XPC_FLAGS': '0x0', 20:40:04 INFO - 'XPC_SERVICE_NAME': '0', 20:40:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:40:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:40:04 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:40:04 INFO - Cleaning up... 20:40:04 INFO - Return code: 0 20:40:04 INFO - Running post-action listener: _resource_record_post_action 20:40:04 INFO - Running post-action listener: _start_resource_monitoring 20:40:04 INFO - Starting resource monitoring. 20:40:04 INFO - [mozharness: 2017-04-28 03:40:04.705920Z] Finished create-virtualenv step (success) 20:40:04 INFO - [mozharness: 2017-04-28 03:40:04.706843Z] Running install step. 20:40:04 INFO - Running pre-action listener: _resource_record_pre_action 20:40:04 INFO - Running main action method: install 20:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:40:04 INFO - Reading from file tmpfile_stdout 20:40:04 INFO - Detecting whether we're running mozinstall >=1.0... 20:40:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:40:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:40:05 INFO - Reading from file tmpfile_stdout 20:40:05 INFO - Output received: 20:40:05 INFO - Usage: mozinstall [options] installer 20:40:05 INFO - Options: 20:40:05 INFO - -h, --help show this help message and exit 20:40:05 INFO - -d DEST, --destination=DEST 20:40:05 INFO - Directory to install application into. [default: 20:40:05 INFO - "/builds/slave/test"] 20:40:05 INFO - --app=APP Application being installed. [default: firefox] 20:40:05 INFO - mkdir: /builds/slave/test/build/application 20:40:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:40:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 20:40:25 INFO - Reading from file tmpfile_stdout 20:40:25 INFO - Output received: 20:40:25 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:40:25 INFO - Running post-action listener: _resource_record_post_action 20:40:25 INFO - [mozharness: 2017-04-28 03:40:25.391887Z] Finished install step (success) 20:40:25 INFO - [mozharness: 2017-04-28 03:40:25.392011Z] Running run-tests step. 20:40:25 INFO - Running pre-action listener: _resource_record_pre_action 20:40:25 INFO - Running main action method: run_tests 20:40:25 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:40:25 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:40:25 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:40:25 INFO - 2017-04-27 20:40:25.405 screenresolution[1636:7552] starting screenresolution argv=screenresolution get 20:40:25 INFO - 2017-04-27 20:40:25.408 screenresolution[1636:7552] Display 0: 1600x1200x32@60 20:40:25 INFO - 2017-04-27 20:40:25.413 screenresolution[1637:7554] starting screenresolution argv=screenresolution list 20:40:25 INFO - Available Modes on Display 0 20:40:25 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:40:25 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:40:25 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:40:25 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:40:25 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:40:25 INFO - Intel Iris: 20:40:25 INFO - Chipset Model: Intel Iris 20:40:25 INFO - Type: GPU 20:40:25 INFO - Bus: Built-In 20:40:25 INFO - VRAM (Dynamic, Max): 1536 MB 20:40:25 INFO - Vendor: Intel (0x8086) 20:40:25 INFO - Device ID: 0x0a2e 20:40:25 INFO - Revision ID: 0x0009 20:40:25 INFO - Displays: 20:40:25 INFO - Display: 20:40:25 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:40:25 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:40:25 INFO - Display Serial Number: 1600x1200 60 20:40:25 INFO - Main Display: Yes 20:40:25 INFO - Mirror: Off 20:40:25 INFO - Online: Yes 20:40:25 INFO - Rotation: Supported 20:40:25 INFO - Return code: 0 20:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:40:25 INFO - Python 2.7.3 20:40:25 INFO - Return code: 0 20:40:25 INFO - grabbing minidump binary from tooltool 20:40:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:40:25 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 0x107e37d30>, '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 0x107d5ddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x107e2cbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:40:25 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 20:40:25 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 20:40:25 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 20:40:25 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:40:25 INFO - Return code: 0 20:40:25 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:40:25 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:40:25 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:40:25 INFO - ENV: RUST_BACKTRACE is now 1 20:40:25 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:40:25 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:40:25 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 20:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0357 --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 20:40:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.aRKcGFX3Ww/Render', 20:40:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:40:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:40:25 INFO - 'HOME': '/Users/cltbld', 20:40:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:40:25 INFO - 'LOGNAME': 'cltbld', 20:40:25 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:40:25 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:40:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:40:25 INFO - 'MOZ_NO_REMOTE': '1', 20:40:25 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:40:25 INFO - 'NO_EM_RESTART': '1', 20:40:25 INFO - 'PAGER': '/bin/cat', 20:40:25 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:40:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:40:25 INFO - 'PWD': '/builds/slave/test', 20:40:25 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:40:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:40:25 INFO - 'RUST_BACKTRACE': '1', 20:40:25 INFO - 'SHELL': '/bin/bash', 20:40:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.dgO7t5hdOx/Listeners', 20:40:25 INFO - 'TMPDIR': '/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/', 20:40:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:40:25 INFO - 'USER': 'cltbld', 20:40:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:40:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:40:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:40:25 INFO - 'XPC_FLAGS': '0x0', 20:40:25 INFO - 'XPC_SERVICE_NAME': '0', 20:40:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:40:25 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0357', '--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 20:40:26 INFO - mozversion application_buildid: 20170427113225 20:40:26 INFO - mozversion application_changeset: 720f68335aeed63d96f86be7b3f90aba8bbc1f6a 20:40:26 INFO - mozversion application_display_name: Nightly 20:40:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:40:26 INFO - mozversion application_name: Firefox 20:40:26 INFO - mozversion application_remotingname: firefox 20:40:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 20:40:26 INFO - mozversion application_vendor: Mozilla 20:40:26 INFO - mozversion application_version: 55.0a1 20:40:26 INFO - mozversion platform_buildid: 20170427113225 20:40:26 INFO - mozversion platform_changeset: 720f68335aeed63d96f86be7b3f90aba8bbc1f6a 20:40:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 20:40:26 INFO - mozversion platform_version: 55.0a1 20:40:26 INFO - using testdate: 1493350826 20:40:26 INFO - actual date: 1493350826 20:40:26 INFO - starting webserver on 'localhost:49234' 20:40:26 INFO - SUITE-START | Running 2 tests 20:40:26 INFO - TEST-START | dromaeo_css 20:40:26 INFO - operating with platform_type : mac_ 20:40:26 INFO - Initialising browser for dromaeo_css test... 20:40:26 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpDt1Gdb/profile http://localhost:49234/getInfo.html) 20:40:29 INFO - PID 1650 | __metrics Screen width/height:1600/1200 20:40:29 INFO - PID 1650 | colorDepth:24 20:40:29 INFO - PID 1650 | Browser inner width/height: 1024/689 20:40:29 INFO - PID 1650 | __metrics 20:40:29 INFO - PID 1650 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:40:29 INFO - PID 1650 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:40:30 INFO - TEST-INFO | 1650: exit 0 20:40:30 INFO - Browser initialized. 20:40:30 INFO - Running cycle 1/1 for dromaeo_css test... 20:40:30 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpDt1Gdb/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:40:42 INFO - PID 1652 | RSS: Main: 242896896 20:40:42 INFO - PID 1652 | 20:43:47 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49234/tests/dromaeo/cssquery-ext.html) 20:43:49 INFO - PID 1652 | RSS: Main: 442146816 20:43:49 INFO - PID 1652 | 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 20:45:32 INFO - PID 1652 | 20:45:32 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:45:32 INFO - PID 1652 | Full stack: 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 20:45:32 INFO - PID 1652 | 20:45:32 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:45:32 INFO - PID 1652 | Full stack: 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 20:45:32 INFO - PID 1652 | 20:45:32 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:45:32 INFO - PID 1652 | Full stack: 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | ************************* 20:45:32 INFO - PID 1652 | A coding exception was thrown and uncaught in a Task. 20:45:32 INFO - PID 1652 | 20:45:32 ERROR - PID 1652 | Full message: TypeError: NetworkError when attempting to fetch resource. 20:45:32 INFO - PID 1652 | Full stack: 20:45:32 INFO - PID 1652 | ************************* 20:46:52 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-ext.html (next: http://localhost:49234/tests/dromaeo/cssquery-jquery.html) 20:46:53 INFO - PID 1652 | RSS: Main: 369364992 20:46:53 INFO - PID 1652 | 20:49:55 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49234/tests/dromaeo/cssquery-mootools.html) 20:49:55 INFO - PID 1652 | RSS: Main: 340402176 20:49:55 INFO - PID 1652 | 20:52:57 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49234/tests/dromaeo/cssquery-prototype.html) 20:52:58 INFO - PID 1652 | RSS: Main: 327634944 20:52:58 INFO - PID 1652 | 20:56:02 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49234/tests/dromaeo/cssquery-yui.html) 20:56:02 INFO - PID 1652 | RSS: Main: 338800640 20:56:02 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | Cycle 1(1): loaded http://localhost:49234/tests/dromaeo/cssquery-yui.html (next: http://localhost:49234/tests/dromaeo/cssquery-dojo.html) 20:59:05 INFO - PID 1652 | RSS: Main: 372338688 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | __start_tp_report 20:59:05 INFO - PID 1652 | _x_x_mozilla_page_load 20:59:05 INFO - PID 1652 | _x_x_mozilla_page_load_details 20:59:05 INFO - PID 1652 | |i|pagename|runs| 20:59:05 INFO - PID 1652 | |0;dojo.html;2904.9273768155795;3494.755367124302;3527.7652821512456;3533.3110043541506;3554.9289014219717;5969.970150149243;6082.665453400061;6109;6163.568550201488;6169.691515424228;6112.047575716891;6132.908006379907;6163.938360616404;6173.81478555644;6178.13506109144;5120.718360490171;5198.974005129969;5279.841604751864;5308.70802105884;5393.460653934614;5352.384475785296;5387.353517577896;5392.946070539284;5396.541293990015;5411.053065713504;5256.395514515842;5271.367435907678;5291.100512912814;5312.389075256357;5356.651817631861;5298.523132918056;5305.4164041955355;5312.76092575835;5316.282301889234;5325.573954083703;5228.843134705965;5251.422343542207;5260.079486089957;5293.179557168645;5294.867628309285;6000.279966404016;6015.939840601582;6017.2779266488305;6021.849453763647;6050.273967123973;6369.6496692681885;6372.299047104815;6386.584871983307;6390.456811171056;6395.712192951281;6523.575967562093;6533.836654083639;6544.67276636171;6560;6572.5399222054475;6244.438000579969;6264.342244064399;6264.718087686019;6269.686515674198;6274.780382686584;6354.17395738551;6410;6596.967015164893;6664.5334826561675;6675.86648267032;6292;6330.746770129143;6331;6365.840853978595;6380.872382552323;6464.25661048976;6470.09418681385;6497.740090396425;6504.317046710122;6520.706568204442;5388.515033646991;6392.936070639327;6394.968025159845;6399.296077431479;6401.615903045832;6264.62412255257;6334.588251763621;6355.809325720235;6374.936250637435;6376.39424254695;5170.689758614495;5170.922436163461;5178.818741344035;5207.895842083213;5211.42674305826;4607.95392046082;4626.259798432235;4632.25883858581;4634.629229661619;4666.253399456071;5918.378570250148;5999.340072592011;6051.576389652682;6085.908711369333;6087.482563982111;6393.360663933569;6487.935120648827;6544.607323560602;6547.050677651714;6550.868982620321;6053.122297266872;6069.0592958091565;6072.21061262042;6073.574849760563;6113.816585502442;5736.856578585569;5748.223989761413;5752.7698892043845;5754.4533269339345;5772.9711351442975;6434.485241180788;6582.17722784652;6582.605043697394;6681.465482761465;6742.595444273359;6372.87254254903;6412.743490260337;6502.959526475741;6539.411452969255;6558.606483610808;6528.608283503005;6695.464362851058;6736.494762892705;6794.286599906938;6798.252192258947;5018.698878067327;6408.423241908251;6420;6421.678916054272;6436.9034464484;6324.525660575568;6387.041943708481;6537.378948999837;6552.246491653521;6559.0817285579105;6302.684865756785;6330.778422755366;6336.461400780985;6354.26925903527;6371.55399122057;6313.2424109107205;6339.492840572835;6347.0479428086155;6362.936370636419;6365.331640177715;6296.653684047238;6297.149884765498;6339.397757213057;6360.809175724736;6365.172527571386;6255.374462553708;6345.841353966188;6352.0789485525265;6358.205224346957;6366.331535188924;6269.96865015672;6340.112384266298;6357.90463143044;6357.904631430625;6375.139356186855 20:59:05 INFO - PID 1652 | |1;ext.html;733.361975081679;801.8917446144773;1108.5122546079729;1120.9887901120987;1128.7121783945092;32198.195045123884;35521.644783552125;35553.2889342213;35662.57349706012;35673.46489802655;37603.87188384346;37999.05002374942;38021.619783802154;38030.809845950804;38085.80957095211;5977.7608895644125;8380.497170169809;8520.934883139607;8532.616032278562;8533.487990720545;6044.546659000571;8263.504189748604;8395.496270223775;8396.538190399526;8400.033996090468;8445.071042185355;8478.957605211996;8483.66065357385;8487.872681909776;8532.445391049592;8389.03526094501;8443.704470343539;8452.957735211347;8454.915450845536;8494.787630309293;7338.77983660491;8372.706955256597;8403.705870294572;8430.98828140621;8441.493510389335;34734.30531389358;34952;34958.82520587406;34962.9511114667;35003;3148.5277208418693;3571.0358203285227;3575.2481284170567;3578.6063533011347;3582.8208589570677;29029;29081.709182908322;29110;29122.340425532024;29132.563011554845;3047.283888286251;3082.4913889208256;3123.063081075691;3126.06218134561;3130.624325080982;200382;263702;263947;264074.9999999981;264756;37023.81488092542;37497;46226;46279.53720462752;46327.30509042367;38007.2398552033;38021.42967855484;38022.80988595095;38034.62601800828;38053.61946380556;12819.551315704091;12939.935300323627;13011.804822927663;13017.544385946447;13030.869691303154;10747.032767051003;10758.977897099763;10761.246712730033;10776.245662803685;10783.352998820095;11226;11236.438178091063;11276.830847537294;11349.262297950607;11362.091032717362;4736.218523943511;5272.6836389816735;5364.356277246756;5367.677939323653;5411.431799661058;1016.6492560066765;1023.5854478936043;1028.9967281900088;1029.2229366827996;1044.8171569975225;19669.01654917277;23696.40758981039;23712.525749484914;23770.524589508113;23795;1092.3882625729616;1098.1434481104752;1139.8689150747605;1140.5722853929776;1163;11600;12219.816702749466;12272.754544909052;15562.766558501631;15579.14314712679;266734;266935;267050.9942350866;267362;267972;50521.49478505241;50612;50646.24030639543;50651.227207047574;50966.235506467434;1469.162577330911;1514.5002149290929;1514.5532068039954;1526.8167819861687;1551.332926841469;26462.735372646028;27074.864625676746;27377.726222738314;27941.44117117687;28076.85961570179;35445.64554354423;35494.64505354913;35516;35540.644593553734;35545;244168;244297;244354.1129177442;244514;244588;27092;27252.454950900476;30231.093067208018;30258.697413025588;30809.38381232318;39661;39782.2043559133;39881.401778973915;39904.80047599859;39918.80040599778;24143;27821.721782782723;28835.84656613796;29614.851925740233;30723.846380768846;23101.88449057744;30020.3995920076;30358.848205759714;30469.23826904345;30691.846540767154;34711.132221693646;35450.468242976895;35541.1114722134;35743;36208;29749.256268593457;29793.851030745576;30030;30379.78480860741;30755;23178;23526.647100293852;23672.408189794704;23719.881400592887;23755.52488950246 20:59:05 INFO - PID 1652 | |2;jquery.html;407.2486262845052;424.7219490235798;426.43284431705837;436.498027268641;438.73895032455687;18885.62228755426;20755.792442075573;21209.363719088415;21388.57222855544;21501.92490375479;21317.68138089117;21840.453988650253;21911.452213694625;22217.2223972161;22391.328260152222;6027.939720602803;6319.368063193667;6323.715432805512;6332.145160403333;6335.366463353674;5972.25346831646;6138.447539721425;6170.413810687977;6237.656928868911;6239.064140378957;6189.907151392733;6192.473639740628;6194.668146348534;6198.876022479548;6201.348858369874;5611.551075913917;6859.279775623562;6919.8962015569805;6943;6958.304169583002;6371.171747672773;6667.099941507883;6755.1556055493065;6770.830729231809;6777;26917.865410673017;28452.861885524555;28597.57103643447;28730.569041464394;29024.27439314013;2281.5094754627667;2374.7981421579016;2377.5601513720017;2378.7621237876247;2379.6787433696404;18428.986405747677;18574.814251857442;18911.690454170464;19014.95417752023;19140.94724790136;2106.6945292932437;2132.402927180392;2176.793204645556;2195.242641288753;2201.526671765578;136671;136783.31608342094;137528;137821.3108934449;138010;19033.238670453025;21243.36269911905;21287.36137915834;22173.891305434663;22402.327930162122;37897.052573685876;38016.42975355372;38165.42751858725;38173;38183.61816381856;8325.084240733515;8331.166883311742;8665.913340866637;8678.956605216934;10582.312149710244;6551.574147680385;7829.060512738508;7881.054273487218;8020.11778704342;8038.7186448475195;7619.276168763959;7737.497062690907;7785.260400261966;7790.883136752953;7806.57063861493;2189.9343019709436;2199.1533259695134;2203.2839327218717;2208.403730992623;2364.172539611157;916.4180745226829;936.2369668719972;956.7041541888267;958.5015791788231;959.9040095990345;18314.17586208624;20294.69557956632;20404.591908161754;20407.795922040885;20768.273110441063;883.9071897450804;949.1362859797689;976.4580657734883;986.556049777603;987.3779518903189;10220.182385409298;10489.737756555995;10498.527566259383;10531.210159238088;10700.89299106999;498787;500577;503392;520237;521828;118242;118740;120767.39616302038;121554.17668734975;123435;1123.5112725964232;1128.1087940527057;1154.9711257218637;1156.115571587736;1158.8783177766381;17714.202860871035;17750.46748597544;17752;17871.84184527106;17973.281068757628;51705.7414712924;51958;52500.474995249555;52548.686282843235;53201;67852.66073669797;68551.65724171347;68908.3109168922;70594;72174.55650886848;8319.584020799057;8595.323911837382;8643.783905402164;8677.956610216908;8832.116788321373;13121.868781312065;13335.53325633637;13401;13790;13819.723605528034;11510.482028308914;11623.941880290544;11737.88262117402;11798.988343963094;11810.17328787011;10746.408947507962;10751.0324070834;10873.293235939638;11047.337159770439;11198.776024479628;11578.942105289756;11735.765284694087;12105.273683579013;12114.15200935926;12124.757504849676;8448.450850694931;8618;8742.56287185651;8821.294296456139;8912.509811960144;11169.106471482424;11530.654080377602;11676.949074583328;11942.761144776881;11960.700982475508 20:59:05 INFO - PID 1652 | |3;mootools.html;1226.7055906582411;1261.3819228577997;1467.075742282362;1469.9412023519053;1488.5013520470638;9610.951945240273;10021.749456263597;10025.849612255804;10028.498575071244;10032.799344013116;9747.220222382193;11011.889881101186;11351.375674337909;11357.432128393588;11419.828702569448;2321.908702909638;2437.597796363607;2483.1309041835393;2529.4435224250637;2532.101104108043;2081.7501899771974;2370.7684253158513;2541.3900663840636;2541.961870571943;2543.5421624107653;2143.7641859395453;2175.3800166952424;2353.2234362660365;2531.455737016541;2532.3795670060767;2479.950400991979;2485.875706214682;2491.9003239870385;2497.612870005152;2498.4128729748495;2398.3524448398834;2437.6709144265474;2442.890069946857;2444.9755502444928;2486.465409936872;11385.772284554345;11501.194916355858;11510.827337589943;11515.539378424854;11525.135614828921;6433.613983161025;6701.899471507932;6708;6709.9329006709795;6715.059891615178;3991.0600255427635;4143.3784932260105;4253.766042867641;4324.070324880135;4327.112941846914;1228.1648479034254;1235.5984305100878;1245.5266998540587;1263.551439239066;1278.5013844600612;16475.83524164755;16480.928739631887;16487.67024659512;16554.917225413796;16582.005079695256;5789.652620842763;5949.553783466257;5957.642541447441;6072.36240194782;6107.450329470369;13651.795223071464;13669.863301367059;13670.316484175752;13688.178709277476;13698.931505342409;6772.255051944282;6777.932220677829;6781.559198652072;6783.3555812197765;6783.525153239226;5127.846164615067;6139.293981192131;6150.661713605795;6159.599626024294;6202.193714817045;4492.820287188541;4610.05493873755;4612.469565999886;4668.346431499593;4684.180268453007;1274.8215249865027;1429.3782204740762;1438.7913752505829;1446.146773403697;1471.7939488471623;782.3037496628006;793.8293266947634;800.6677228950037;805.5649949027577;815.2581151152423;6374.968125159345;6667.099941507834;6853.965730171417;6880.218010652793;6911.550749201286;568.5934556791914;595.3272801734099;598.1924402057275;607.8328459673537;615.1603061820583;3725.691707492307;3780.8109594520165;3805.581386047588;3817.828197731109;3868.207017561393;14013.929930350283;14820.258987050604;14820.3330850112;14837.480688175861;14847.218333800012;14191.503297384543;14706.63233419173;14761.704765904837;14781.630459238606;14799.778003329959;8374.706885258987;8638.56807159652;8644.870326945102;8662.263707584925;8685.39202255836;6455.289918108911;6549.541532092708;6641.169853768279;6649.867002660017;6695.631740254138;13143.73712525725;13242;13383.53157639478;13388.531401400904;13393.732125357634;13400.128991615515;13527.458901643824;13642.317884105954;13884;13988.720225595634;9163.266938645025;9411.247100232102;9424.387414818015;9426.905730942603;9434.85847712271;10308.433036183082;10720.927907209216;10758.946205268923;10865.945670271598;10888.61889833852;9169.266458683423;9183.127602877716;9283.907160928575;9289.16397524226;9297.72106836796;8865.290776737716;9012.459252444874;9015.05341939087;9032.954835225783;9083.727488175366;7991.160928102466;8000.4399692021;8119.228673276029;8121.715739949073;8139.389545784208;5889.116632504987;6060.424259695322;6066.15073889647;6113.84715382119;6130.877382452237;6059.545534084873;6086.695665216633;6188.752449902134;6192.659403732838;6272.560920735505 20:59:05 INFO - PID 1652 | |4;prototype.html;389.1361178184431;393.2174971806105;397.82495701891185;402.7885360185902;403.15941262467754;5177.378714554249;5556.249906262658;5787.24765780448;5823.592348535599;5841.2114364560775;5334.466553344663;5354.116570765819;5428.212909128184;5627.887442251163;5698.8005419810315;3728.8694895678655;3888.6694630956404;4366.890827729317;4401.16377888201;4420.425344705184;3705.3696373595667;4423.712458690175;4426.402435671175;4449.84425545106;4459.665525085616;4326.156399502099;4362.5637436256275;4385.758783266935;4392.12157568486;4424.446944131983;4228.302330115527;4328.826846926151;4338.067315527177;4375.278079116942;4377.956220437787;3901.9219615607813;3902.765834049966;3906.1211227473877;3911.9804400978096;3921.725479216456;5112.977404519111;5280.551153151987;5544.398149535916;5597.188407680904;5618.438156184349;4307.698461107724;4729.290606409032;4975.328330675348;4988.5260900214425;5255.290535777697;6018.638881667114;6127.632342059491;6140.600860944024;6169.236765572997;6178.455386153461;4268;4396.790882507336;4406.22890994078;4433.069055498349;4461.5092339842595;6264.3422440643535;6391.616503009881;6401.775937842153;6404.7117879695525;6411;4032.536258330271;4144.48193975753;4577.315261214688;4609.6542759292515;4617.376654151713;4113.465249517543;4452.5992660660695;4667.229907065364;4697.506761789963;4704.364910737075;4064.6138616831354;5143.27994080829;5288.603354748409;5317.776911310343;5324.1747529133045;6242.687865606792;6248.844703119944;6279.403456671609;6281.057841323746;6287.371262873768;1560.1964988031173;1573.229117732303;1577.897436666636;1582.5045332161228;1591.299828075643;990.7622170678985;1074.8015962202244;1108.778244351133;1113.493360520961;1115.1524841120806;5792.652440853562;5829.854253643608;5846.210761547221;5861.238039054895;5875.500582450454;5716.628419152742;6178.62928224308;6422.45409140219;6423.614583125028;6431.228252609717;5579.246801681721;5701.800436984686;5767.711614419262;5841.707914604252;5853.443922827325;2637.8944842206097;2942.911712648624;3312.287858110518;3319.6762266413607;3359.4456914609304;6099.956514567581;6519.771807986698;6537.0062131049535;6593.7581222384615;6668.733250670016;6093.39066093396;6248.4376406123665;6479.611223326521;6566.5731727438515;6691.230508491489;6195.81412557624;6412.839679007969;6594.571352862049;6598.680263947133;6620.205575330991;6397;6398.048595178329;6408.198975128109;6416.582922110048;6442.452391546771;4979.427365852974;5909.468147866713;6042.912275790224;6283.120363149072;6349.174607301022;5810.5061069809535;6305.684715764101;6390.712417941111;6644.335566443317;6701.731930722716;4100.610442008005;4117.691173161965;4124.1133156371525;4127.2570937231585;4140.23405669952;6387.968060159669;6412.743490260337;6439.259485159267;6468.611883287018;6519.771807986887;4979.402471703419;5927.081302398135;6250.906236406367;6259.810635979149;6271.247450305992;5351.973240133774;5540.445955404427;6126.203593532812;6193.225846769154;6237.28271248794;6075.119107729266;6089.939100608937;6092.2689277285235;6096.481799047131;6109.51123910095;4652;5175.896482070413;6168.131818704324;6249.78125765596;6306.590071645512;4816.446108697544;6082.60463069899;6090.847728806815;6096.561375449855;6100.359462256577 20:59:05 INFO - PID 1652 | |5;yui.html;712.7469748239378;791.7426836278211;863.7106569299284;866.657670220263;879.9076097009814;12811.103222774409;17849.018303993274;17989.730154047684;18535;18570.628587428244;16742.330306787746;16927.576810579743;17341.39305124321;17352.392666256685;17417.303307867704;956.8947415784276;987.9012098790135;988.2637435110853;988.5156273426005;994.1102713071797;610.4658423879106;658.9209294884621;664.4850241063166;1009.530568285747;1012.488693209928;999.8250306196422;1033.3180101133253;1035.1511760356511;1035.182206057214;1037.2065369991963;937.0114529171722;1003.4230317567392;1032.1484775060605;1032.5250384822991;1033.297357796698;763.9388848892075;764.9235076492362;766.9501482403626;767.301755402581;770.4645271536284;15537.844621553753;15884.285207165705;16064;16409.58976025597;16647.250873710713;16098.919505402398;16184.271707773178;16343;16576.502704918872;16618.83381166197;13464.86535134646;13745.587632370945;13749.587512374645;13820.654483637889;13990.230537320444;3367.983160084184;3379.138319728478;3389.1188291044505;3395.3718562066097;3401.4557670772556;119222;121869.39065304617;121973;122187;123717.9999999991;8095.878561821577;8849.469031858109;10959.958803913616;11419.48612312448;12014.33921134346;23877.16429924944;24304.7569524306;24373.39066523351;24438.75561244365;24837.5032499349;7911.485753426009;7962.004749406325;7966.28303452692;7970.681172753025;7973.282404583615;3705.314516814344;3788.4696142540065;3839.155385815116;3844.615538446133;3847.403652433877;4159.521655009652;4278.144371125787;4278.764667943231;4279.251131052053;4280.464941882265;960.0159836167844;985.2413641496008;998.6454808543008;999.5052449037628;999.6301368493704;645.6481217736344;656.743869890743;669.1535207961932;671.415868194664;685.0614657918684;10917.563297468012;11359.375234362189;11586.942065289619;11914.748951360156;12110;651.3876955661758;654.9246836613755;656.061831580844;662.2384258103219;683.8734834055715;5373;5500.147477141049;5613.242212301368;5663.734506197554;5666.829995100071;102742.48628756958;104840;105190;106448.40327395096;108300;90753;91747.5412622946;92157;94457.527712361;94894;60138.699306503186;62724.372756272765;75902.62048689721;77721;78003.60998195087;9868.309218354647;10922.508487118257;11155.609553665907;11223.878211531362;11328.263662861887;35978.38097285576;36586.8170659145;36692.63307366999;36830.263394732494;37163.07092322713;45078.0984380304;46399;49028.50971490239;49627.75186124046;50435.49564504308;13823.30883455803;14077.577672669837;14594.927025364805;14605.85394146045;14624.561263162122;15418.768718469008;15420.92289538545;15669.451569195024;15782.684346313239;15879.841201587837;14495.78256326135;14744.189069601274;14789.852101478848;14798.926005369904;14959.17724525118;14281.785773213618;14400.485521413035;14654;14704.852951470348;14873.628159295671;14055.437782488585;14163.150210987375;14455.49405770793;14491.565253042425;14541.781873271697;10065.144462720751;10288.434136122285;10502.4223667699;10582.894171058191;10760.730981725519;13423.127496712683;13637.863621364057;13890.58328250154;13892.23592702411;13908.026438149232 20:59:05 INFO - PID 1652 | __end_tp_report 20:59:05 INFO - PID 1652 | __start_cc_report 20:59:05 INFO - PID 1652 | _x_x_mozilla_cycle_collect,2061 20:59:05 INFO - PID 1652 | __end_cc_report 20:59:05 INFO - PID 1652 | __startTimestamp1493351945418__endTimestamp 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | ------- Summary: start ------- 20:59:05 INFO - PID 1652 | Number of tests: 6 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#0] dojo.html Cycles:165 Average:5987.37 Median:6297.15 stddev:696.47 (11.1%) stddev-sans-first:655.28 20:59:05 INFO - PID 1652 | Values: 2904.9 3494.8 3527.8 3533.3 3554.9 5970.0 6082.7 6109.0 6163.6 6169.7 6112.0 6132.9 6163.9 6173.8 6178.1 5120.7 5199.0 5279.8 5308.7 5393.5 5352.4 5387.4 5392.9 5396.5 5411.1 5256.4 5271.4 5291.1 5312.4 5356.7 5298.5 5305.4 5312.8 5316.3 5325.6 5228.8 5251.4 5260.1 5293.2 5294.9 6000.3 6015.9 6017.3 6021.8 6050.3 6369.6 6372.3 6386.6 6390.5 6395.7 6523.6 6533.8 6544.7 6560.0 6572.5 6244.4 6264.3 6264.7 6269.7 6274.8 6354.2 6410.0 6597.0 6664.5 6675.9 6292.0 6330.7 6331.0 6365.8 6380.9 6464.3 6470.1 6497.7 6504.3 6520.7 5388.5 6392.9 6395.0 6399.3 6401.6 6264.6 6334.6 6355.8 6374.9 6376.4 5170.7 5170.9 5178.8 5207.9 5211.4 4608.0 4626.3 4632.3 4634.6 4666.3 5918.4 5999.3 6051.6 6085.9 6087.5 6393.4 6487.9 6544.6 6547.1 6550.9 6053.1 6069.1 6072.2 6073.6 6113.8 5736.9 5748.2 5752.8 5754.5 5773.0 6434.5 6582.2 6582.6 6681.5 6742.6 6372.9 6412.7 6503.0 6539.4 6558.6 6528.6 6695.5 6736.5 6794.3 6798.3 5018.7 6408.4 6420.0 6421.7 6436.9 6324.5 6387.0 6537.4 6552.2 6559.1 6302.7 6330.8 6336.5 6354.3 6371.6 6313.2 6339.5 6347.0 6362.9 6365.3 6296.7 6297.1 6339.4 6360.8 6365.2 6255.4 6345.8 6352.1 6358.2 6366.3 6270.0 6340.1 6357.9 6357.9 6375.1 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#1] ext.html Cycles:180 Average:39954.80 Median:30707.85 stddev:66521.01 (216.6%) stddev-sans-first:66642.44 20:59:05 INFO - PID 1652 | Values: 733.4 801.9 1108.5 1121.0 1128.7 32198.2 35521.6 35553.3 35662.6 35673.5 37603.9 37999.1 38021.6 38030.8 38085.8 5977.8 8380.5 8520.9 8532.6 8533.5 6044.5 8263.5 8395.5 8396.5 8400.0 8445.1 8479.0 8483.7 8487.9 8532.4 8389.0 8443.7 8453.0 8454.9 8494.8 7338.8 8372.7 8403.7 8431.0 8441.5 34734.3 34952.0 34958.8 34963.0 35003.0 3148.5 3571.0 3575.2 3578.6 3582.8 29029.0 29081.7 29110.0 29122.3 29132.6 3047.3 3082.5 3123.1 3126.1 3130.6 200382.0 263702.0 263947.0 264075.0 264756.0 37023.8 37497.0 46226.0 46279.5 46327.3 38007.2 38021.4 38022.8 38034.6 38053.6 12819.6 12939.9 13011.8 13017.5 13030.9 10747.0 10759.0 10761.2 10776.2 10783.4 11226.0 11236.4 11276.8 11349.3 11362.1 4736.2 5272.7 5364.4 5367.7 5411.4 1016.6 1023.6 1029.0 1029.2 1044.8 19669.0 23696.4 23712.5 23770.5 23795.0 1092.4 1098.1 1139.9 1140.6 1163.0 11600.0 12219.8 12272.8 15562.8 15579.1 266734.0 266935.0 267051.0 267362.0 267972.0 50521.5 50612.0 50646.2 50651.2 50966.2 1469.2 1514.5 1514.6 1526.8 1551.3 26462.7 27074.9 27377.7 27941.4 28076.9 35445.6 35494.6 35516.0 35540.6 35545.0 244168.0 244297.0 244354.1 244514.0 244588.0 27092.0 27252.5 30231.1 30258.7 30809.4 39661.0 39782.2 39881.4 39904.8 39918.8 24143.0 27821.7 28835.8 29614.9 30723.8 23101.9 30020.4 30358.8 30469.2 30691.8 34711.1 35450.5 35541.1 35743.0 36208.0 29749.3 29793.9 30030.0 30379.8 30755.0 23178.0 23526.6 23672.4 23719.9 23755.5 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#2] jquery.html Cycles:180 Average:34163.63 Median:14648.77 stddev:85908.89 (586.5%) stddev-sans-first:86112.50 20:59:05 INFO - PID 1652 | Values: 407.2 424.7 426.4 436.5 438.7 18885.6 20755.8 21209.4 21388.6 21501.9 21317.7 21840.5 21911.5 22217.2 22391.3 6027.9 6319.4 6323.7 6332.1 6335.4 5972.3 6138.4 6170.4 6237.7 6239.1 6189.9 6192.5 6194.7 6198.9 6201.3 5611.6 6859.3 6919.9 6943.0 6958.3 6371.2 6667.1 6755.2 6770.8 6777.0 26917.9 28452.9 28597.6 28730.6 29024.3 2281.5 2374.8 2377.6 2378.8 2379.7 18429.0 18574.8 18911.7 19015.0 19140.9 2106.7 2132.4 2176.8 2195.2 2201.5 136671.0 136783.3 137528.0 137821.3 138010.0 19033.2 21243.4 21287.4 22173.9 22402.3 37897.1 38016.4 38165.4 38173.0 38183.6 8325.1 8331.2 8665.9 8679.0 10582.3 6551.6 7829.1 7881.1 8020.1 8038.7 7619.3 7737.5 7785.3 7790.9 7806.6 2189.9 2199.2 2203.3 2208.4 2364.2 916.4 936.2 956.7 958.5 959.9 18314.2 20294.7 20404.6 20407.8 20768.3 883.9 949.1 976.5 986.6 987.4 10220.2 10489.7 10498.5 10531.2 10700.9 498787.0 500577.0 503392.0 520237.0 521828.0 118242.0 118740.0 120767.4 121554.2 123435.0 1123.5 1128.1 1155.0 1156.1 1158.9 17714.2 17750.5 17752.0 17871.8 17973.3 51705.7 51958.0 52500.5 52548.7 53201.0 67852.7 68551.7 68908.3 70594.0 72174.6 8319.6 8595.3 8643.8 8678.0 8832.1 13121.9 13335.5 13401.0 13790.0 13819.7 11510.5 11623.9 11737.9 11799.0 11810.2 10746.4 10751.0 10873.3 11047.3 11198.8 11578.9 11735.8 12105.3 12114.2 12124.8 8448.5 8618.0 8742.6 8821.3 8912.5 11169.1 11530.7 11676.9 11942.8 11960.7 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#3] mootools.html Cycles:180 Average:7084.76 Median:3811.70 stddev:4491.19 (117.8%) stddev-sans-first:4482.22 20:59:05 INFO - PID 1652 | Values: 1226.7 1261.4 1467.1 1469.9 1488.5 9611.0 10021.7 10025.8 10028.5 10032.8 9747.2 11011.9 11351.4 11357.4 11419.8 2321.9 2437.6 2483.1 2529.4 2532.1 2081.8 2370.8 2541.4 2542.0 2543.5 2143.8 2175.4 2353.2 2531.5 2532.4 2480.0 2485.9 2491.9 2497.6 2498.4 2398.4 2437.7 2442.9 2445.0 2486.5 11385.8 11501.2 11510.8 11515.5 11525.1 6433.6 6701.9 6708.0 6709.9 6715.1 3991.1 4143.4 4253.8 4324.1 4327.1 1228.2 1235.6 1245.5 1263.6 1278.5 16475.8 16480.9 16487.7 16554.9 16582.0 5789.7 5949.6 5957.6 6072.4 6107.5 13651.8 13669.9 13670.3 13688.2 13698.9 6772.3 6777.9 6781.6 6783.4 6783.5 5127.8 6139.3 6150.7 6159.6 6202.2 4492.8 4610.1 4612.5 4668.3 4684.2 1274.8 1429.4 1438.8 1446.1 1471.8 782.3 793.8 800.7 805.6 815.3 6375.0 6667.1 6854.0 6880.2 6911.6 568.6 595.3 598.2 607.8 615.2 3725.7 3780.8 3805.6 3817.8 3868.2 14013.9 14820.3 14820.3 14837.5 14847.2 14191.5 14706.6 14761.7 14781.6 14799.8 8374.7 8638.6 8644.9 8662.3 8685.4 6455.3 6549.5 6641.2 6649.9 6695.6 13143.7 13242.0 13383.5 13388.5 13393.7 13400.1 13527.5 13642.3 13884.0 13988.7 9163.3 9411.2 9424.4 9426.9 9434.9 10308.4 10720.9 10758.9 10865.9 10888.6 9169.3 9183.1 9283.9 9289.2 9297.7 8865.3 9012.5 9015.1 9033.0 9083.7 7991.2 8000.4 8119.2 8121.7 8139.4 5889.1 6060.4 6066.2 6113.8 6130.9 6059.5 6086.7 6188.8 6192.7 6272.6 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#4] prototype.html Cycles:180 Average:5047.27 Median:5607.81 stddev:1539.75 (27.5%) stddev-sans-first:1503.85 20:59:05 INFO - PID 1652 | Values: 389.1 393.2 397.8 402.8 403.2 5177.4 5556.2 5787.2 5823.6 5841.2 5334.5 5354.1 5428.2 5627.9 5698.8 3728.9 3888.7 4366.9 4401.2 4420.4 3705.4 4423.7 4426.4 4449.8 4459.7 4326.2 4362.6 4385.8 4392.1 4424.4 4228.3 4328.8 4338.1 4375.3 4378.0 3901.9 3902.8 3906.1 3912.0 3921.7 5113.0 5280.6 5544.4 5597.2 5618.4 4307.7 4729.3 4975.3 4988.5 5255.3 6018.6 6127.6 6140.6 6169.2 6178.5 4268.0 4396.8 4406.2 4433.1 4461.5 6264.3 6391.6 6401.8 6404.7 6411.0 4032.5 4144.5 4577.3 4609.7 4617.4 4113.5 4452.6 4667.2 4697.5 4704.4 4064.6 5143.3 5288.6 5317.8 5324.2 6242.7 6248.8 6279.4 6281.1 6287.4 1560.2 1573.2 1577.9 1582.5 1591.3 990.8 1074.8 1108.8 1113.5 1115.2 5792.7 5829.9 5846.2 5861.2 5875.5 5716.6 6178.6 6422.5 6423.6 6431.2 5579.2 5701.8 5767.7 5841.7 5853.4 2637.9 2942.9 3312.3 3319.7 3359.4 6100.0 6519.8 6537.0 6593.8 6668.7 6093.4 6248.4 6479.6 6566.6 6691.2 6195.8 6412.8 6594.6 6598.7 6620.2 6397.0 6398.0 6408.2 6416.6 6442.5 4979.4 5909.5 6042.9 6283.1 6349.2 5810.5 6305.7 6390.7 6644.3 6701.7 4100.6 4117.7 4124.1 4127.3 4140.2 6388.0 6412.7 6439.3 6468.6 6519.8 4979.4 5927.1 6250.9 6259.8 6271.2 5352.0 5540.4 6126.2 6193.2 6237.3 6075.1 6089.9 6092.3 6096.5 6109.5 4652.0 5175.9 6168.1 6249.8 6306.6 4816.4 6082.6 6090.8 6096.6 6100.4 20:59:05 INFO - PID 1652 | 20:59:05 INFO - PID 1652 | [#5] yui.html Cycles:180 Average:20665.07 Median:18552.81 stddev:29783.31 (160.5%) stddev-sans-first:29829.18 20:59:05 INFO - PID 1652 | Values: 712.7 791.7 863.7 866.7 879.9 12811.1 17849.0 17989.7 18535.0 18570.6 16742.3 16927.6 17341.4 17352.4 17417.3 956.9 987.9 988.3 988.5 994.1 610.5 658.9 664.5 1009.5 1012.5 999.8 1033.3 1035.2 1035.2 1037.2 937.0 1003.4 1032.1 1032.5 1033.3 763.9 764.9 767.0 767.3 770.5 15537.8 15884.3 16064.0 16409.6 16647.3 16098.9 16184.3 16343.0 16576.5 16618.8 13464.9 13745.6 13749.6 13820.7 13990.2 3368.0 3379.1 3389.1 3395.4 3401.5 119222.0 121869.4 121973.0 122187.0 123718.0 8095.9 8849.5 10960.0 11419.5 12014.3 23877.2 24304.8 24373.4 24438.8 24837.5 7911.5 7962.0 7966.3 7970.7 7973.3 3705.3 3788.5 3839.2 3844.6 3847.4 4159.5 4278.1 4278.8 4279.3 4280.5 960.0 985.2 998.6 999.5 999.6 645.6 656.7 669.2 671.4 685.1 10917.6 11359.4 11586.9 11914.7 12110.0 651.4 654.9 656.1 662.2 683.9 5373.0 5500.1 5613.2 5663.7 5666.8 102742.5 104840.0 105190.0 106448.4 108300.0 90753.0 91747.5 92157.0 94457.5 94894.0 60138.7 62724.4 75902.6 77721.0 78003.6 9868.3 10922.5 11155.6 11223.9 11328.3 35978.4 36586.8 36692.6 36830.3 37163.1 45078.1 46399.0 49028.5 49627.8 50435.5 13823.3 14077.6 14594.9 14605.9 14624.6 15418.8 15420.9 15669.5 15782.7 15879.8 14495.8 14744.2 14789.9 14798.9 14959.2 14281.8 14400.5 14654.0 14704.9 14873.6 14055.4 14163.2 14455.5 14491.6 14541.8 10065.1 10288.4 10502.4 10582.9 10760.7 13423.1 13637.9 13890.6 13892.2 13908.0 20:59:05 INFO - PID 1652 | -------- Summary: end -------- 20:59:05 INFO - PID 1652 | 20:59:06 INFO - TEST-INFO | 1652: exit 0 20:59:06 INFO - TEST-OK | dromaeo_css | took 1119817ms 20:59:06 INFO - TEST-START | kraken 20:59:06 INFO - operating with platform_type : mac_ 20:59:06 INFO - Initialising browser for kraken test... 20:59:06 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpAX7knR/profile http://localhost:49234/getInfo.html) 20:59:08 INFO - PID 1658 | __metrics Screen width/height:1600/1200 20:59:08 INFO - PID 1658 | colorDepth:24 20:59:08 INFO - PID 1658 | Browser inner width/height: 1024/689 20:59:08 INFO - PID 1658 | __metrics 20:59:08 INFO - PID 1658 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 673: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 20:59:08 INFO - PID 1658 | JavaScript error: resource:///modules/BrowserUsageTelemetry.jsm, line 334: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:59:09 INFO - TEST-INFO | 1658: exit 0 20:59:09 INFO - Browser initialized. 20:59:09 INFO - Running cycle 1/1 for kraken test... 20:59:09 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/97/rkh_tff557l6vj3yx2fd921000000w/T/tmpAX7knR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:59:22 INFO - PID 1659 | RSS: Main: 242626560 20:59:22 INFO - PID 1659 | 20:59:24 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection) 20:59:25 INFO - PID 1659 | RSS: Main: 312782848 20:59:25 INFO - PID 1659 | 20:59:28 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-dft) 20:59:28 INFO - PID 1659 | RSS: Main: 296919040 20:59:28 INFO - PID 1659 | 20:59:32 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-fft) 20:59:32 INFO - PID 1659 | RSS: Main: 389439488 20:59:32 INFO - PID 1659 | 20:59:34 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator) 20:59:35 INFO - PID 1659 | RSS: Main: 290697216 20:59:35 INFO - PID 1659 | 20:59:37 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:59:38 INFO - PID 1659 | RSS: Main: 293335040 20:59:38 INFO - PID 1659 | 20:59:40 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom) 20:59:41 INFO - PID 1659 | RSS: Main: 357613568 20:59:41 INFO - PID 1659 | 20:59:44 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate) 20:59:45 INFO - PID 1659 | RSS: Main: 372748288 20:59:45 INFO - PID 1659 | 20:59:47 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial) 20:59:48 INFO - PID 1659 | RSS: Main: 378093568 20:59:48 INFO - PID 1659 | 20:59:50 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:59:50 INFO - PID 1659 | RSS: Main: 333549568 20:59:50 INFO - PID 1659 | 20:59:52 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:59:53 INFO - PID 1659 | RSS: Main: 335327232 20:59:53 INFO - PID 1659 | 20:59:55 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:59:55 INFO - PID 1659 | RSS: Main: 337436672 20:59:55 INFO - PID 1659 | 20:59:58 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:59:59 INFO - PID 1659 | RSS: Main: 354947072 20:59:59 INFO - PID 1659 | 21:00:02 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 21:00:03 INFO - PID 1659 | RSS: Main: 347090944 21:00:03 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | Cycle 1(1): loaded http://localhost:49234/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49234/tests/kraken/driver.html?testName=ai-astar) 21:00:05 INFO - PID 1659 | RSS: Main: 390131712 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | __start_tp_report 21:00:05 INFO - PID 1659 | _x_x_mozilla_page_load 21:00:05 INFO - PID 1659 | _x_x_mozilla_page_load_details 21:00:05 INFO - PID 1659 | |i|pagename|runs| 21:00:05 INFO - PID 1659 | |0;ai-astar;110;111;110;110;109;110;111;111;110;111 21:00:05 INFO - PID 1659 | |1;audio-beat-detection;143;109;108;151;114;133;131;108;135;108 21:00:05 INFO - PID 1659 | |2;audio-dft;196;186;181;187;185;184;186;182;180;182 21:00:05 INFO - PID 1659 | |3;audio-fft;81;52;62;110;81;83;66;59;83;60 21:00:05 INFO - PID 1659 | |4;audio-oscillator;90;70;85;69;68;68;89;68;68;99 21:00:05 INFO - PID 1659 | |5;imaging-gaussian-blur;88;88;87;87;87;87;88;87;89;88 21:00:05 INFO - PID 1659 | |6;imaging-darkroom;95;95;95;96;94;95;95;95;94;94 21:00:05 INFO - PID 1659 | |7;imaging-desaturate;84;84;84;87;86;85;87;85;86;85 21:00:05 INFO - PID 1659 | |8;json-parse-financial;50;49;49;47;49;49;48;49;48;49 21:00:05 INFO - PID 1659 | |9;json-stringify-tinderbox;53;60;58;67;65;62;58;58;57;58 21:00:05 INFO - PID 1659 | |10;stanford-crypto-aes;83;84;83;81;82;82;82;81;82;98 21:00:05 INFO - PID 1659 | |11;stanford-crypto-ccm;151;147;126;141;131;152;146;154;228;162 21:00:05 INFO - PID 1659 | |12;stanford-crypto-pbkdf2;222;184;188;190;190;194;189;170;183;184 21:00:05 INFO - PID 1659 | |13;stanford-crypto-sha256-iterative;69;68;66;68;68;68;69;81;89;91 21:00:05 INFO - PID 1659 | __end_tp_report 21:00:05 INFO - PID 1659 | __start_cc_report 21:00:05 INFO - PID 1659 | _x_x_mozilla_cycle_collect,2248 21:00:05 INFO - PID 1659 | __end_cc_report 21:00:05 INFO - PID 1659 | __startTimestamp1493352005448__endTimestamp 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | ------- Summary: start ------- 21:00:05 INFO - PID 1659 | Number of tests: 14 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#0] ai-astar Cycles:10 Average:110.30 Median:110.50 stddev:0.67 (0.6%) stddev-sans-first:0.71 21:00:05 INFO - PID 1659 | Values: 110.0 111.0 110.0 110.0 109.0 110.0 111.0 111.0 110.0 111.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#1] audio-beat-detection Cycles:10 Average:124.00 Median:132.00 stddev:16.45 (12.5%) stddev-sans-first:15.94 21:00:05 INFO - PID 1659 | Values: 143.0 109.0 108.0 151.0 114.0 133.0 131.0 108.0 135.0 108.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#2] audio-dft Cycles:10 Average:184.90 Median:185.50 stddev:4.56 (2.5%) stddev-sans-first:2.50 21:00:05 INFO - PID 1659 | Values: 196.0 186.0 181.0 187.0 185.0 184.0 186.0 182.0 180.0 182.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#3] audio-fft Cycles:10 Average:73.70 Median:73.50 stddev:17.22 (23.4%) stddev-sans-first:18.06 21:00:05 INFO - PID 1659 | Values: 81.0 52.0 62.0 110.0 81.0 83.0 66.0 59.0 83.0 60.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#4] audio-oscillator Cycles:10 Average:77.40 Median:77.50 stddev:12.00 (15.5%) stddev-sans-first:11.83 21:00:05 INFO - PID 1659 | Values: 90.0 70.0 85.0 69.0 68.0 68.0 89.0 68.0 68.0 99.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:87.60 Median:88.00 stddev:0.70 (0.8%) stddev-sans-first:0.73 21:00:05 INFO - PID 1659 | Values: 88.0 88.0 87.0 87.0 87.0 87.0 88.0 87.0 89.0 88.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#6] imaging-darkroom Cycles:10 Average:94.80 Median:95.00 stddev:0.63 (0.7%) stddev-sans-first:0.67 21:00:05 INFO - PID 1659 | Values: 95.0 95.0 95.0 96.0 94.0 95.0 95.0 95.0 94.0 94.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#7] imaging-desaturate Cycles:10 Average:85.30 Median:85.50 stddev:1.16 (1.4%) stddev-sans-first:1.13 21:00:05 INFO - PID 1659 | Values: 84.0 84.0 84.0 87.0 86.0 85.0 87.0 85.0 86.0 85.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#8] json-parse-financial Cycles:10 Average:48.70 Median:49.00 stddev:0.82 (1.7%) stddev-sans-first:0.73 21:00:05 INFO - PID 1659 | Values: 50.0 49.0 49.0 47.0 49.0 49.0 48.0 49.0 48.0 49.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:59.60 Median:59.00 stddev:4.09 (6.9%) stddev-sans-first:3.57 21:00:05 INFO - PID 1659 | Values: 53.0 60.0 58.0 67.0 65.0 62.0 58.0 58.0 57.0 58.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.80 Median:82.50 stddev:5.07 (6.1%) stddev-sans-first:5.37 21:00:05 INFO - PID 1659 | Values: 83.0 84.0 83.0 81.0 82.0 82.0 82.0 81.0 82.0 98.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:153.80 Median:151.50 stddev:28.18 (18.6%) stddev-sans-first:29.87 21:00:05 INFO - PID 1659 | Values: 151.0 147.0 126.0 141.0 131.0 152.0 146.0 154.0 228.0 162.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.40 Median:189.50 stddev:13.18 (7.0%) stddev-sans-first:6.91 21:00:05 INFO - PID 1659 | Values: 222.0 184.0 188.0 190.0 190.0 194.0 189.0 170.0 183.0 184.0 21:00:05 INFO - PID 1659 | 21:00:05 INFO - PID 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.70 Median:69.00 stddev:9.55 (13.8%) stddev-sans-first:9.97 21:00:05 INFO - PID 1659 | Values: 69.0 68.0 66.0 68.0 68.0 68.0 69.0 81.0 89.0 91.0 21:00:05 INFO - PID 1659 | -------- Summary: end -------- 21:00:05 INFO - PID 1659 | 21:00:06 INFO - TEST-INFO | 1659: exit 0 21:00:06 INFO - TEST-OK | kraken | took 60483ms 21:00:06 INFO - SUITE-END | took 1180s 21:00:06 INFO - Completed test suite (00:19:40) 21:00:06 INFO - javascript benchmark 21:00:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7670.624347971165, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2904.9273768155795, 3494.755367124302, 3527.7652821512456, 3533.3110043541506, 3554.9289014219717, 5969.970150149243, 6082.665453400061, 6109.0, 6163.568550201488, 6169.691515424228, 6112.047575716891, 6132.908006379907, 6163.938360616404, 6173.81478555644, 6178.13506109144, 5120.718360490171, 5198.974005129969, 5279.841604751864, 5308.70802105884, 5393.460653934614, 5352.384475785296, 5387.353517577896, 5392.946070539284, 5396.541293990015, 5411.053065713504, 5256.395514515842, 5271.367435907678, 5291.100512912814, 5312.389075256357, 5356.651817631861, 5298.523132918056, 5305.4164041955355, 5312.76092575835, 5316.282301889234, 5325.573954083703, 5228.843134705965, 5251.422343542207, 5260.079486089957, 5293.179557168645, 5294.867628309285, 6000.279966404016, 6015.939840601582, 6017.2779266488305, 6021.849453763647, 6050.273967123973, 6369.6496692681885, 6372.299047104815, 6386.584871983307, 6390.456811171056, 6395.712192951281, 6523.575967562093, 6533.836654083639, 6544.67276636171, 6560.0, 6572.5399222054475, 6244.438000579969, 6264.342244064399, 6264.718087686019, 6269.686515674198, 6274.780382686584, 6354.17395738551, 6410.0, 6596.967015164893, 6664.5334826561675, 6675.86648267032, 6292.0, 6330.746770129143, 6331.0, 6365.840853978595, 6380.872382552323, 6464.25661048976, 6470.09418681385, 6497.740090396425, 6504.317046710122, 6520.706568204442, 5388.515033646991, 6392.936070639327, 6394.968025159845, 6399.296077431479, 6401.615903045832, 6264.62412255257, 6334.588251763621, 6355.809325720235, 6374.936250637435, 6376.39424254695, 5170.689758614495, 5170.922436163461, 5178.818741344035, 5207.895842083213, 5211.42674305826, 4607.95392046082, 4626.259798432235, 4632.25883858581, 4634.629229661619, 4666.253399456071, 5918.378570250148, 5999.340072592011, 6051.576389652682, 6085.908711369333, 6087.482563982111, 6393.360663933569, 6487.935120648827, 6544.607323560602, 6547.050677651714, 6550.868982620321, 6053.122297266872, 6069.0592958091565, 6072.21061262042, 6073.574849760563, 6113.816585502442, 5736.856578585569, 5748.223989761413, 5752.7698892043845, 5754.4533269339345, 5772.9711351442975, 6434.485241180788, 6582.17722784652, 6582.605043697394, 6681.465482761465, 6742.595444273359, 6372.87254254903, 6412.743490260337, 6502.959526475741, 6539.411452969255, 6558.606483610808, 6528.608283503005, 6695.464362851058, 6736.494762892705, 6794.286599906938, 6798.252192258947, 5018.698878067327, 6408.423241908251, 6420.0, 6421.678916054272, 6436.9034464484, 6324.525660575568, 6387.041943708481, 6537.378948999837, 6552.246491653521, 6559.0817285579105, 6302.684865756785, 6330.778422755366, 6336.461400780985, 6354.26925903527, 6371.55399122057, 6313.2424109107205, 6339.492840572835, 6347.0479428086155, 6362.936370636419, 6365.331640177715, 6296.653684047238, 6297.149884765498, 6339.397757213057, 6360.809175724736, 6365.172527571386, 6255.374462553708, 6345.841353966188, 6352.0789485525265, 6358.205224346957, 6366.331535188924, 6269.96865015672, 6340.112384266298, 6357.90463143044, 6357.904631430625, 6375.139356186855], "value": 5940.581117290741, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [733.361975081679, 801.8917446144773, 1108.5122546079729, 1120.9887901120987, 1128.7121783945092, 32198.195045123884, 35521.644783552125, 35553.2889342213, 35662.57349706012, 35673.46489802655, 37603.87188384346, 37999.05002374942, 38021.619783802154, 38030.809845950804, 38085.80957095211, 5977.7608895644125, 8380.497170169809, 8520.934883139607, 8532.616032278562, 8533.487990720545, 6044.546659000571, 8263.504189748604, 8395.496270223775, 8396.538190399526, 8400.033996090468, 8445.071042185355, 8478.957605211996, 8483.66065357385, 8487.872681909776, 8532.445391049592, 8389.03526094501, 8443.704470343539, 8452.957735211347, 8454.915450845536, 8494.787630309293, 7338.77983660491, 8372.706955256597, 8403.705870294572, 8430.98828140621, 8441.493510389335, 34734.30531389358, 34952.0, 34958.82520587406, 34962.9511114667, 35003.0, 3148.5277208418693, 3571.0358203285227, 3575.2481284170567, 3578.6063533011347, 3582.8208589570677, 29029.0, 29081.709182908322, 29110.0, 29122.340425532024, 29132.563011554845, 3047.283888286251, 3082.4913889208256, 3123.063081075691, 3126.06218134561, 3130.624325080982, 200382.0, 263702.0, 263947.0, 264074.9999999981, 264756.0, 37023.81488092542, 37497.0, 46226.0, 46279.53720462752, 46327.30509042367, 38007.2398552033, 38021.42967855484, 38022.80988595095, 38034.62601800828, 38053.61946380556, 12819.551315704091, 12939.935300323627, 13011.804822927663, 13017.544385946447, 13030.869691303154, 10747.032767051003, 10758.977897099763, 10761.246712730033, 10776.245662803685, 10783.352998820095, 11226.0, 11236.438178091063, 11276.830847537294, 11349.262297950607, 11362.091032717362, 4736.218523943511, 5272.6836389816735, 5364.356277246756, 5367.677939323653, 5411.431799661058, 1016.6492560066765, 1023.5854478936043, 1028.9967281900088, 1029.2229366827996, 1044.8171569975225, 19669.01654917277, 23696.40758981039, 23712.525749484914, 23770.524589508113, 23795.0, 1092.3882625729616, 1098.1434481104752, 1139.8689150747605, 1140.5722853929776, 1163.0, 11600.0, 12219.816702749466, 12272.754544909052, 15562.766558501631, 15579.14314712679, 266734.0, 266935.0, 267050.9942350866, 267362.0, 267972.0, 50521.49478505241, 50612.0, 50646.24030639543, 50651.227207047574, 50966.235506467434, 1469.162577330911, 1514.5002149290929, 1514.5532068039954, 1526.8167819861687, 1551.332926841469, 26462.735372646028, 27074.864625676746, 27377.726222738314, 27941.44117117687, 28076.85961570179, 35445.64554354423, 35494.64505354913, 35516.0, 35540.644593553734, 35545.0, 244168.0, 244297.0, 244354.1129177442, 244514.0, 244588.0, 27092.0, 27252.454950900476, 30231.093067208018, 30258.697413025588, 30809.38381232318, 39661.0, 39782.2043559133, 39881.401778973915, 39904.80047599859, 39918.80040599778, 24143.0, 27821.721782782723, 28835.84656613796, 29614.851925740233, 30723.846380768846, 23101.88449057744, 30020.3995920076, 30358.848205759714, 30469.23826904345, 30691.846540767154, 34711.132221693646, 35450.468242976895, 35541.1114722134, 35743.0, 36208.0, 29749.256268593457, 29793.851030745576, 30030.0, 30379.78480860741, 30755.0, 23178.0, 23526.647100293852, 23672.408189794704, 23719.881400592887, 23755.52488950246], "value": 16544.68870410419, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [407.2486262845052, 424.7219490235798, 426.43284431705837, 436.498027268641, 438.73895032455687, 18885.62228755426, 20755.792442075573, 21209.363719088415, 21388.57222855544, 21501.92490375479, 21317.68138089117, 21840.453988650253, 21911.452213694625, 22217.2223972161, 22391.328260152222, 6027.939720602803, 6319.368063193667, 6323.715432805512, 6332.145160403333, 6335.366463353674, 5972.25346831646, 6138.447539721425, 6170.413810687977, 6237.656928868911, 6239.064140378957, 6189.907151392733, 6192.473639740628, 6194.668146348534, 6198.876022479548, 6201.348858369874, 5611.551075913917, 6859.279775623562, 6919.8962015569805, 6943.0, 6958.304169583002, 6371.171747672773, 6667.099941507883, 6755.1556055493065, 6770.830729231809, 6777.0, 26917.865410673017, 28452.861885524555, 28597.57103643447, 28730.569041464394, 29024.27439314013, 2281.5094754627667, 2374.7981421579016, 2377.5601513720017, 2378.7621237876247, 2379.6787433696404, 18428.986405747677, 18574.814251857442, 18911.690454170464, 19014.95417752023, 19140.94724790136, 2106.6945292932437, 2132.402927180392, 2176.793204645556, 2195.242641288753, 2201.526671765578, 136671.0, 136783.31608342094, 137528.0, 137821.3108934449, 138010.0, 19033.238670453025, 21243.36269911905, 21287.36137915834, 22173.891305434663, 22402.327930162122, 37897.052573685876, 38016.42975355372, 38165.42751858725, 38173.0, 38183.61816381856, 8325.084240733515, 8331.166883311742, 8665.913340866637, 8678.956605216934, 10582.312149710244, 6551.574147680385, 7829.060512738508, 7881.054273487218, 8020.11778704342, 8038.7186448475195, 7619.276168763959, 7737.497062690907, 7785.260400261966, 7790.883136752953, 7806.57063861493, 2189.9343019709436, 2199.1533259695134, 2203.2839327218717, 2208.403730992623, 2364.172539611157, 916.4180745226829, 936.2369668719972, 956.7041541888267, 958.5015791788231, 959.9040095990345, 18314.17586208624, 20294.69557956632, 20404.591908161754, 20407.795922040885, 20768.273110441063, 883.9071897450804, 949.1362859797689, 976.4580657734883, 986.556049777603, 987.3779518903189, 10220.182385409298, 10489.737756555995, 10498.527566259383, 10531.210159238088, 10700.89299106999, 498787.0, 500577.0, 503392.0, 520237.0, 521828.0, 118242.0, 118740.0, 120767.39616302038, 121554.17668734975, 123435.0, 1123.5112725964232, 1128.1087940527057, 1154.9711257218637, 1156.115571587736, 1158.8783177766381, 17714.202860871035, 17750.46748597544, 17752.0, 17871.84184527106, 17973.281068757628, 51705.7414712924, 51958.0, 52500.474995249555, 52548.686282843235, 53201.0, 67852.66073669797, 68551.65724171347, 68908.3109168922, 70594.0, 72174.55650886848, 8319.584020799057, 8595.323911837382, 8643.783905402164, 8677.956610216908, 8832.116788321373, 13121.868781312065, 13335.53325633637, 13401.0, 13790.0, 13819.723605528034, 11510.482028308914, 11623.941880290544, 11737.88262117402, 11798.988343963094, 11810.17328787011, 10746.408947507962, 10751.0324070834, 10873.293235939638, 11047.337159770439, 11198.776024479628, 11578.942105289756, 11735.765284694087, 12105.273683579013, 12114.15200935926, 12124.757504849676, 8448.450850694931, 8618.0, 8742.56287185651, 8821.294296456139, 8912.509811960144, 11169.106471482424, 11530.654080377602, 11676.949074583328, 11942.761144776881, 11960.700982475508], "value": 10702.900966643894, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1226.7055906582411, 1261.3819228577997, 1467.075742282362, 1469.9412023519053, 1488.5013520470638, 9610.951945240273, 10021.749456263597, 10025.849612255804, 10028.498575071244, 10032.799344013116, 9747.220222382193, 11011.889881101186, 11351.375674337909, 11357.432128393588, 11419.828702569448, 2321.908702909638, 2437.597796363607, 2483.1309041835393, 2529.4435224250637, 2532.101104108043, 2081.7501899771974, 2370.7684253158513, 2541.3900663840636, 2541.961870571943, 2543.5421624107653, 2143.7641859395453, 2175.3800166952424, 2353.2234362660365, 2531.455737016541, 2532.3795670060767, 2479.950400991979, 2485.875706214682, 2491.9003239870385, 2497.612870005152, 2498.4128729748495, 2398.3524448398834, 2437.6709144265474, 2442.890069946857, 2444.9755502444928, 2486.465409936872, 11385.772284554345, 11501.194916355858, 11510.827337589943, 11515.539378424854, 11525.135614828921, 6433.613983161025, 6701.899471507932, 6708.0, 6709.9329006709795, 6715.059891615178, 3991.0600255427635, 4143.3784932260105, 4253.766042867641, 4324.070324880135, 4327.112941846914, 1228.1648479034254, 1235.5984305100878, 1245.5266998540587, 1263.551439239066, 1278.5013844600612, 16475.83524164755, 16480.928739631887, 16487.67024659512, 16554.917225413796, 16582.005079695256, 5789.652620842763, 5949.553783466257, 5957.642541447441, 6072.36240194782, 6107.450329470369, 13651.795223071464, 13669.863301367059, 13670.316484175752, 13688.178709277476, 13698.931505342409, 6772.255051944282, 6777.932220677829, 6781.559198652072, 6783.3555812197765, 6783.525153239226, 5127.846164615067, 6139.293981192131, 6150.661713605795, 6159.599626024294, 6202.193714817045, 4492.820287188541, 4610.05493873755, 4612.469565999886, 4668.346431499593, 4684.180268453007, 1274.8215249865027, 1429.3782204740762, 1438.7913752505829, 1446.146773403697, 1471.7939488471623, 782.3037496628006, 793.8293266947634, 800.6677228950037, 805.5649949027577, 815.2581151152423, 6374.968125159345, 6667.099941507834, 6853.965730171417, 6880.218010652793, 6911.550749201286, 568.5934556791914, 595.3272801734099, 598.1924402057275, 607.8328459673537, 615.1603061820583, 3725.691707492307, 3780.8109594520165, 3805.581386047588, 3817.828197731109, 3868.207017561393, 14013.929930350283, 14820.258987050604, 14820.3330850112, 14837.480688175861, 14847.218333800012, 14191.503297384543, 14706.63233419173, 14761.704765904837, 14781.630459238606, 14799.778003329959, 8374.706885258987, 8638.56807159652, 8644.870326945102, 8662.263707584925, 8685.39202255836, 6455.289918108911, 6549.541532092708, 6641.169853768279, 6649.867002660017, 6695.631740254138, 13143.73712525725, 13242.0, 13383.53157639478, 13388.531401400904, 13393.732125357634, 13400.128991615515, 13527.458901643824, 13642.317884105954, 13884.0, 13988.720225595634, 9163.266938645025, 9411.247100232102, 9424.387414818015, 9426.905730942603, 9434.85847712271, 10308.433036183082, 10720.927907209216, 10758.946205268923, 10865.945670271598, 10888.61889833852, 9169.266458683423, 9183.127602877716, 9283.907160928575, 9289.16397524226, 9297.72106836796, 8865.290776737716, 9012.459252444874, 9015.05341939087, 9032.954835225783, 9083.727488175366, 7991.160928102466, 8000.4399692021, 8119.228673276029, 8121.715739949073, 8139.389545784208, 5889.116632504987, 6060.424259695322, 6066.15073889647, 6113.84715382119, 6130.877382452237, 6059.545534084873, 6086.695665216633, 6188.752449902134, 6192.659403732838, 6272.560920735505], "value": 5308.186569327893, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [389.1361178184431, 393.2174971806105, 397.82495701891185, 402.7885360185902, 403.15941262467754, 5177.378714554249, 5556.249906262658, 5787.24765780448, 5823.592348535599, 5841.2114364560775, 5334.466553344663, 5354.116570765819, 5428.212909128184, 5627.887442251163, 5698.8005419810315, 3728.8694895678655, 3888.6694630956404, 4366.890827729317, 4401.16377888201, 4420.425344705184, 3705.3696373595667, 4423.712458690175, 4426.402435671175, 4449.84425545106, 4459.665525085616, 4326.156399502099, 4362.5637436256275, 4385.758783266935, 4392.12157568486, 4424.446944131983, 4228.302330115527, 4328.826846926151, 4338.067315527177, 4375.278079116942, 4377.956220437787, 3901.9219615607813, 3902.765834049966, 3906.1211227473877, 3911.9804400978096, 3921.725479216456, 5112.977404519111, 5280.551153151987, 5544.398149535916, 5597.188407680904, 5618.438156184349, 4307.698461107724, 4729.290606409032, 4975.328330675348, 4988.5260900214425, 5255.290535777697, 6018.638881667114, 6127.632342059491, 6140.600860944024, 6169.236765572997, 6178.455386153461, 4268.0, 4396.790882507336, 4406.22890994078, 4433.069055498349, 4461.5092339842595, 6264.3422440643535, 6391.616503009881, 6401.775937842153, 6404.7117879695525, 6411.0, 4032.536258330271, 4144.48193975753, 4577.315261214688, 4609.6542759292515, 4617.376654151713, 4113.465249517543, 4452.5992660660695, 4667.229907065364, 4697.506761789963, 4704.364910737075, 4064.6138616831354, 5143.27994080829, 5288.603354748409, 5317.776911310343, 5324.1747529133045, 6242.687865606792, 6248.844703119944, 6279.403456671609, 6281.057841323746, 6287.371262873768, 1560.1964988031173, 1573.229117732303, 1577.897436666636, 1582.5045332161228, 1591.299828075643, 990.7622170678985, 1074.8015962202244, 1108.778244351133, 1113.493360520961, 1115.1524841120806, 5792.652440853562, 5829.854253643608, 5846.210761547221, 5861.238039054895, 5875.500582450454, 5716.628419152742, 6178.62928224308, 6422.45409140219, 6423.614583125028, 6431.228252609717, 5579.246801681721, 5701.800436984686, 5767.711614419262, 5841.707914604252, 5853.443922827325, 2637.8944842206097, 2942.911712648624, 3312.287858110518, 3319.6762266413607, 3359.4456914609304, 6099.956514567581, 6519.771807986698, 6537.0062131049535, 6593.7581222384615, 6668.733250670016, 6093.39066093396, 6248.4376406123665, 6479.611223326521, 6566.5731727438515, 6691.230508491489, 6195.81412557624, 6412.839679007969, 6594.571352862049, 6598.680263947133, 6620.205575330991, 6397.0, 6398.048595178329, 6408.198975128109, 6416.582922110048, 6442.452391546771, 4979.427365852974, 5909.468147866713, 6042.912275790224, 6283.120363149072, 6349.174607301022, 5810.5061069809535, 6305.684715764101, 6390.712417941111, 6644.335566443317, 6701.731930722716, 4100.610442008005, 4117.691173161965, 4124.1133156371525, 4127.2570937231585, 4140.23405669952, 6387.968060159669, 6412.743490260337, 6439.259485159267, 6468.611883287018, 6519.771807986887, 4979.402471703419, 5927.081302398135, 6250.906236406367, 6259.810635979149, 6271.247450305992, 5351.973240133774, 5540.445955404427, 6126.203593532812, 6193.225846769154, 6237.28271248794, 6075.119107729266, 6089.939100608937, 6092.2689277285235, 6096.481799047131, 6109.51123910095, 4652.0, 5175.896482070413, 6168.131818704324, 6249.78125765596, 6306.590071645512, 4816.446108697544, 6082.60463069899, 6090.847728806815, 6096.561375449855, 6100.359462256577], "value": 4582.744511569216, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [712.7469748239378, 791.7426836278211, 863.7106569299284, 866.657670220263, 879.9076097009814, 12811.103222774409, 17849.018303993274, 17989.730154047684, 18535.0, 18570.628587428244, 16742.330306787746, 16927.576810579743, 17341.39305124321, 17352.392666256685, 17417.303307867704, 956.8947415784276, 987.9012098790135, 988.2637435110853, 988.5156273426005, 994.1102713071797, 610.4658423879106, 658.9209294884621, 664.4850241063166, 1009.530568285747, 1012.488693209928, 999.8250306196422, 1033.3180101133253, 1035.1511760356511, 1035.182206057214, 1037.2065369991963, 937.0114529171722, 1003.4230317567392, 1032.1484775060605, 1032.5250384822991, 1033.297357796698, 763.9388848892075, 764.9235076492362, 766.9501482403626, 767.301755402581, 770.4645271536284, 15537.844621553753, 15884.285207165705, 16064.0, 16409.58976025597, 16647.250873710713, 16098.919505402398, 16184.271707773178, 16343.0, 16576.502704918872, 16618.83381166197, 13464.86535134646, 13745.587632370945, 13749.587512374645, 13820.654483637889, 13990.230537320444, 3367.983160084184, 3379.138319728478, 3389.1188291044505, 3395.3718562066097, 3401.4557670772556, 119222.0, 121869.39065304617, 121973.0, 122187.0, 123717.9999999991, 8095.878561821577, 8849.469031858109, 10959.958803913616, 11419.48612312448, 12014.33921134346, 23877.16429924944, 24304.7569524306, 24373.39066523351, 24438.75561244365, 24837.5032499349, 7911.485753426009, 7962.004749406325, 7966.28303452692, 7970.681172753025, 7973.282404583615, 3705.314516814344, 3788.4696142540065, 3839.155385815116, 3844.615538446133, 3847.403652433877, 4159.521655009652, 4278.144371125787, 4278.764667943231, 4279.251131052053, 4280.464941882265, 960.0159836167844, 985.2413641496008, 998.6454808543008, 999.5052449037628, 999.6301368493704, 645.6481217736344, 656.743869890743, 669.1535207961932, 671.415868194664, 685.0614657918684, 10917.563297468012, 11359.375234362189, 11586.942065289619, 11914.748951360156, 12110.0, 651.3876955661758, 654.9246836613755, 656.061831580844, 662.2384258103219, 683.8734834055715, 5373.0, 5500.147477141049, 5613.242212301368, 5663.734506197554, 5666.829995100071, 102742.48628756958, 104840.0, 105190.0, 106448.40327395096, 108300.0, 90753.0, 91747.5412622946, 92157.0, 94457.527712361, 94894.0, 60138.699306503186, 62724.372756272765, 75902.62048689721, 77721.0, 78003.60998195087, 9868.309218354647, 10922.508487118257, 11155.609553665907, 11223.878211531362, 11328.263662861887, 35978.38097285576, 36586.8170659145, 36692.63307366999, 36830.263394732494, 37163.07092322713, 45078.0984380304, 46399.0, 49028.50971490239, 49627.75186124046, 50435.49564504308, 13823.30883455803, 14077.577672669837, 14594.927025364805, 14605.85394146045, 14624.561263162122, 15418.768718469008, 15420.92289538545, 15669.451569195024, 15782.684346313239, 15879.841201587837, 14495.78256326135, 14744.189069601274, 14789.852101478848, 14798.926005369904, 14959.17724525118, 14281.785773213618, 14400.485521413035, 14654.0, 14704.852951470348, 14873.628159295671, 14055.437782488585, 14163.150210987375, 14455.49405770793, 14491.565253042425, 14541.781873271697, 10065.144462720751, 10288.434136122285, 10502.4223667699, 10582.894171058191, 10760.730981725519, 13423.127496712683, 13637.863621364057, 13890.58328250154, 13892.23592702411, 13908.026438149232], "value": 7959.615004377476, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1447.0000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 111.0, 110.0, 110.0, 109.0, 110.0, 111.0, 111.0, 110.0, 111.0], "value": 110.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 109.0, 108.0, 151.0, 114.0, 133.0, 131.0, 108.0, 135.0, 108.0], "value": 124.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 186.0, 181.0, 187.0, 185.0, 184.0, 186.0, 182.0, 180.0, 182.0], "value": 184.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 52.0, 62.0, 110.0, 81.0, 83.0, 66.0, 59.0, 83.0, 60.0], "value": 73.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 85.0, 69.0, 68.0, 68.0, 89.0, 68.0, 68.0, 99.0], "value": 77.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 88.0, 87.0, 87.0, 87.0, 87.0, 88.0, 87.0, 89.0, 88.0], "value": 87.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 96.0, 94.0, 95.0, 95.0, 95.0, 94.0, 94.0], "value": 94.8, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 84.0, 87.0, 86.0, 85.0, 87.0, 85.0, 86.0, 85.0], "value": 85.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [50.0, 49.0, 49.0, 47.0, 49.0, 49.0, 48.0, 49.0, 48.0, 49.0], "value": 48.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [53.0, 60.0, 58.0, 67.0, 65.0, 62.0, 58.0, 58.0, 57.0, 58.0], "value": 59.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 84.0, 83.0, 81.0, 82.0, 82.0, 82.0, 81.0, 82.0, 98.0], "value": 83.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 147.0, 126.0, 141.0, 131.0, 152.0, 146.0, 154.0, 228.0, 162.0], "value": 153.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [222.0, 184.0, 188.0, 190.0, 190.0, 194.0, 189.0, 170.0, 183.0, 184.0], "value": 189.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 68.0, 66.0, 68.0, 68.0, 68.0, 69.0, 81.0, 89.0, 91.0], "value": 73.7, "unit": "score"}]}]} 21:00:06 INFO - Return code: 0 21:00:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 21:00:06 ERROR - # TBPL SUCCESS # 21:00:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 21:00:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 21:00:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 21:00:06 INFO - 2017-04-27 21:00:06.731 screenresolution[1663:9908] starting screenresolution argv=screenresolution get 21:00:06 INFO - 2017-04-27 21:00:06.733 screenresolution[1663:9908] Display 0: 1600x1200x32@60 21:00:06 INFO - 2017-04-27 21:00:06.740 screenresolution[1664:9911] starting screenresolution argv=screenresolution list 21:00:06 INFO - Available Modes on Display 0 21:00:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 21:00:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 21:00:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 21:00:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 21:00:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 21:00:06 INFO - Intel Iris: 21:00:06 INFO - Chipset Model: Intel Iris 21:00:06 INFO - Type: GPU 21:00:06 INFO - Bus: Built-In 21:00:06 INFO - VRAM (Dynamic, Max): 1536 MB 21:00:06 INFO - Vendor: Intel (0x8086) 21:00:06 INFO - Device ID: 0x0a2e 21:00:06 INFO - Revision ID: 0x0009 21:00:06 INFO - Displays: 21:00:06 INFO - Display: 21:00:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 21:00:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 21:00:06 INFO - Display Serial Number: 1600x1200 60 21:00:06 INFO - Main Display: Yes 21:00:06 INFO - Mirror: Off 21:00:06 INFO - Online: Yes 21:00:06 INFO - Rotation: Supported 21:00:06 INFO - Return code: 0 21:00:06 INFO - Running post-action listener: _resource_record_post_action 21:00:06 INFO - [mozharness: 2017-04-28 04:00:06.990076Z] Finished run-tests step (success) 21:00:06 INFO - Running post-run listener: _resource_record_post_run 21:00:07 INFO - Total resource usage - Wall time: 1201s; CPU: 53.0%; Read bytes: 39068672; Write bytes: 507162624; Read time: 247; Write time: 1585 21:00:07 INFO - TinderboxPrint: CPU usage
53.4% 21:00:07 INFO - TinderboxPrint: I/O read bytes / time
39,068,672 / 247 21:00:07 INFO - TinderboxPrint: I/O write bytes / time
507,162,624 / 1,585 21:00:07 INFO - TinderboxPrint: CPU idle
2,241.1 (46.6%) 21:00:07 INFO - TinderboxPrint: CPU system
953.0 (19.8%) 21:00:07 INFO - TinderboxPrint: CPU user
1,611.5 (33.5%) 21:00:07 INFO - TinderboxPrint: Swap in / out
245,772,288 / 0 21:00:07 INFO - install - Wall time: 21s; CPU: 54.0%; Read bytes: 201497088; Write bytes: 193736704; Read time: 15582; Write time: 408 21:00:07 INFO - run-tests - Wall time: 1182s; CPU: 53.0%; Read bytes: 38040576; Write bytes: 297635840; Read time: 233; Write time: 1128 21:00:07 INFO - Running post-run listener: _upload_blobber_files 21:00:07 INFO - Blob upload gear active. 21:00:07 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 21:00:07 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 21:00:07 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 21:00:07 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 21:00:07 INFO - (blobuploader) - INFO - Open directory for files ... 21:00:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 21:00:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:00:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:00:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 21:00:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:00:09 INFO - (blobuploader) - INFO - Done attempting. 21:00:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 21:00:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:00:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:00:10 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 21:00:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:00:10 INFO - (blobuploader) - INFO - Done attempting. 21:00:10 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 21:00:10 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:00:10 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:00:11 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 21:00:11 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:00:11 INFO - (blobuploader) - INFO - Done attempting. 21:00:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 21:00:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 21:00:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 21:00:13 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 21:00:13 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 21:00:13 INFO - (blobuploader) - INFO - Done attempting. 21:00:13 INFO - (blobuploader) - INFO - Iteration through files over. 21:00:13 INFO - Return code: 0 21:00:13 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 21:00:13 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 21:00:13 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2d594515126af0137a3d8b3541b5b8d81fd1fe5ac5d066fce7c240955cda9adf736682d31990de8ec4bfe1f9a73208c8bdec057af8ea6bb5460701da37696d90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a46a95a3463e6c83ee81e6d3b6ee5e18be563f5c1ecf5ff40f6b772d2204d0196d3b95c693deaad5750eab076c8541522adfeec978ea11c715306579a4280b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3c9ea01be8e11fa6f98edb6c32f95174121b67eb7b5bbb074d9706a370c5a4ce8f35d996b8fa20fa603c7ae4768cec91a183c054c397d95c41eec8eb16f281ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e852662011b904e849840a75ee4e2f7bb157646dc45d9edbf809e28f0dcc9c145dfa11b6a3808f0240d3be8f9308f93ddff34b9d2ed480985708e6c7247709d4"} 21:00:13 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 21:00:13 INFO - Writing to file /builds/slave/test/properties/blobber_files 21:00:13 INFO - Contents: 21:00:13 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2d594515126af0137a3d8b3541b5b8d81fd1fe5ac5d066fce7c240955cda9adf736682d31990de8ec4bfe1f9a73208c8bdec057af8ea6bb5460701da37696d90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a46a95a3463e6c83ee81e6d3b6ee5e18be563f5c1ecf5ff40f6b772d2204d0196d3b95c693deaad5750eab076c8541522adfeec978ea11c715306579a4280b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3c9ea01be8e11fa6f98edb6c32f95174121b67eb7b5bbb074d9706a370c5a4ce8f35d996b8fa20fa603c7ae4768cec91a183c054c397d95c41eec8eb16f281ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e852662011b904e849840a75ee4e2f7bb157646dc45d9edbf809e28f0dcc9c145dfa11b6a3808f0240d3be8f9308f93ddff34b9d2ed480985708e6c7247709d4"} 21:00:13 INFO - Running post-run listener: copy_logs_to_upload_dir 21:00:13 INFO - Copying logs to upload dir... 21:00:13 INFO - mkdir: /builds/slave/test/build/upload/logs 21:00:13 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1286.695810 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 26 secs) (at 2017-04-27 21:00:13.918579) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 21:00:13.921848) ========= 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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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/Pine/sha512/2d594515126af0137a3d8b3541b5b8d81fd1fe5ac5d066fce7c240955cda9adf736682d31990de8ec4bfe1f9a73208c8bdec057af8ea6bb5460701da37696d90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a46a95a3463e6c83ee81e6d3b6ee5e18be563f5c1ecf5ff40f6b772d2204d0196d3b95c693deaad5750eab076c8541522adfeec978ea11c715306579a4280b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3c9ea01be8e11fa6f98edb6c32f95174121b67eb7b5bbb074d9706a370c5a4ce8f35d996b8fa20fa603c7ae4768cec91a183c054c397d95c41eec8eb16f281ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e852662011b904e849840a75ee4e2f7bb157646dc45d9edbf809e28f0dcc9c145dfa11b6a3808f0240d3be8f9308f93ddff34b9d2ed480985708e6c7247709d4"} build_url:https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011116 build_url: 'https://queue.taskcluster.net/v1/task/G3cqTc1HTMKr2CVVD1j3NA/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/2d594515126af0137a3d8b3541b5b8d81fd1fe5ac5d066fce7c240955cda9adf736682d31990de8ec4bfe1f9a73208c8bdec057af8ea6bb5460701da37696d90", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/7a46a95a3463e6c83ee81e6d3b6ee5e18be563f5c1ecf5ff40f6b772d2204d0196d3b95c693deaad5750eab076c8541522adfeec978ea11c715306579a4280b0", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/3c9ea01be8e11fa6f98edb6c32f95174121b67eb7b5bbb074d9706a370c5a4ce8f35d996b8fa20fa603c7ae4768cec91a183c054c397d95c41eec8eb16f281ce", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/e852662011b904e849840a75ee4e2f7bb157646dc45d9edbf809e28f0dcc9c145dfa11b6a3808f0240d3be8f9308f93ddff34b9d2ed480985708e6c7247709d4"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-27 21:00:13.958232) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 21:00:13.958640) ========= 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.aRKcGFX3Ww/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.dgO7t5hdOx/Listeners TMPDIR=/var/folders/97/rkh_tff557l6vj3yx2fd921000000w/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.005118 ========= master_lag: 0.14 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-27 21:00:14.100807) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 21:00:14.101133) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-27 21:00:14.101542) ========= ========= Total master_lag: 0.33 =========