builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0003 starttime: 1473686343.04 results: success (0) buildid: 20160912043314 builduid: 8d670bc5993f4525805de53123e90f55 revision: 05bab13e18504f8c0b69076c10e0ef6a6df29333 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:03.041127) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:03.041578) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-09-12 06:19:03.041854) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 1 secs) (at 2016-09-12 06:19:04.159669) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:04.160029) ========= 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.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-12 06:19:04-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 9.45M=0.001s 2016-09-12 06:19:04 (9.45 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.083306 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:04.267529) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:04.267947) ========= 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.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.028432 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:04.318759) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 06:19:04.325243) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 05bab13e18504f8c0b69076c10e0ef6a6df29333 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 05bab13e18504f8c0b69076c10e0ef6a6df29333 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-12 06:19:04,412 truncating revision to first 12 chars 2016-09-12 06:19:04,412 Setting DEBUG logging. 2016-09-12 06:19:04,412 attempt 1/10 2016-09-12 06:19:04,412 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/05bab13e1850?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 06:19:05,951 unpacking tar archive at: fx-team-05bab13e1850/testing/mozharness/ program finished with exit code 0 elapsedTime=1.811256 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-12 06:19:06.158630) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:06.158971) ========= script_repo_revision: 05bab13e18504f8c0b69076c10e0ef6a6df29333 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:06.159363) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:06.166012) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 06:19:06.197716) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-12 06:19:06.198114) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:06 INFO - MultiFileLogger online at 20160912 06:19:06 in /builds/slave/test 06:19:06 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 06:19:06 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 06:19:06 INFO - {'append_to_log': False, 06:19:06 INFO - 'base_work_dir': '/builds/slave/test', 06:19:06 INFO - 'blob_upload_branch': 'Fx-Team', 06:19:06 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 06:19:06 INFO - 'branch': 'Fx-Team', 06:19:06 INFO - 'buildbot_json_path': 'buildprops.json', 06:19:06 INFO - 'config_files': ('talos/mac_config.py',), 06:19:06 INFO - 'default_actions': ('clobber', 06:19:06 INFO - 'read-buildbot-config', 06:19:06 INFO - 'download-and-extract', 06:19:06 INFO - 'populate-webroot', 06:19:06 INFO - 'create-virtualenv', 06:19:06 INFO - 'install', 06:19:06 INFO - 'run-tests'), 06:19:06 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 06:19:06 INFO - 'download_minidump_stackwalk': True, 06:19:06 INFO - 'download_symbols': 'ondemand', 06:19:06 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 06:19:06 INFO - 'tooltool.py': '/tools/tooltool.py', 06:19:06 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 06:19:06 INFO - '/tools/misc-python/virtualenv.py')}, 06:19:06 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 06:19:06 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 06:19:06 INFO - 'installer_path': 'installer.exe', 06:19:06 INFO - 'log_level': 'info', 06:19:06 INFO - 'log_name': 'talos', 06:19:06 INFO - 'log_to_console': True, 06:19:06 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 06:19:06 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 06:19:06 INFO - 'opt_config_files': (), 06:19:06 INFO - 'pip_index': False, 06:19:06 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:19:06 INFO - 'cmd': ('bash', 06:19:06 INFO - '-c', 06:19:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:19:06 INFO - 'enabled': True, 06:19:06 INFO - 'halt_on_failure': False, 06:19:06 INFO - 'name': 'check_screen_resolution'},), 06:19:06 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 06:19:06 INFO - 'cmd': ('bash', 06:19:06 INFO - '-c', 06:19:06 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 06:19:06 INFO - 'enabled': True, 06:19:06 INFO - 'halt_on_failure': False, 06:19:06 INFO - 'name': 'check_screen_resolution'},), 06:19:06 INFO - 'run_cmd_checks_enabled': True, 06:19:06 INFO - 'sps_profile': False, 06:19:06 INFO - 'sps_profile_interval': 0, 06:19:06 INFO - 'suite': 'dromaeojs', 06:19:06 INFO - 'system_bits': '32', 06:19:06 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 06:19:06 INFO - 'title': 't-yosemite-r7-0003', 06:19:06 INFO - 'tooltool_cache': '/builds/tooltool_cache', 06:19:06 INFO - 'use_talos_json': True, 06:19:06 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 06:19:06 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 06:19:06 INFO - 'work_dir': 'build'} 06:19:06 INFO - [mozharness: 2016-09-12 13:19:06.415558Z] Running clobber step. 06:19:06 INFO - Running pre-action listener: _resource_record_pre_action 06:19:06 INFO - Running main action method: clobber 06:19:06 INFO - rmtree: /builds/slave/test/build 06:19:06 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 06:19:08 INFO - Running post-action listener: _resource_record_post_action 06:19:08 INFO - [mozharness: 2016-09-12 13:19:08.628786Z] Finished clobber step (success) 06:19:08 INFO - [mozharness: 2016-09-12 13:19:08.628975Z] Running read-buildbot-config step. 06:19:08 INFO - Running pre-action listener: _resource_record_pre_action 06:19:08 INFO - Running main action method: read_buildbot_config 06:19:08 INFO - Using buildbot properties: 06:19:08 INFO - { 06:19:08 INFO - "project": "", 06:19:08 INFO - "product": "firefox", 06:19:08 INFO - "script_repo_revision": "production", 06:19:08 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 06:19:08 INFO - "repository": "", 06:19:08 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 06:19:08 INFO - "buildid": "20160912043314", 06:19:08 INFO - "pgo_build": "False", 06:19:08 INFO - "basedir": "/builds/slave/test", 06:19:08 INFO - "buildnumber": 152, 06:19:08 INFO - "slavename": "t-yosemite-r7-0003", 06:19:08 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 06:19:08 INFO - "platform": "yosemite_r7", 06:19:08 INFO - "scheduler": "tests-fx-team-macosx64-talos", 06:19:08 INFO - "branch": "fx-team", 06:19:08 INFO - "repo_path": "integration/fx-team", 06:19:08 INFO - "revision": "05bab13e18504f8c0b69076c10e0ef6a6df29333", 06:19:08 INFO - "stage_platform": "macosx64", 06:19:08 INFO - "builduid": "8d670bc5993f4525805de53123e90f55", 06:19:08 INFO - "slavebuilddir": "test" 06:19:08 INFO - } 06:19:08 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 06:19:08 INFO - Running post-action listener: _resource_record_post_action 06:19:08 INFO - [mozharness: 2016-09-12 13:19:08.632202Z] Finished read-buildbot-config step (success) 06:19:08 INFO - [mozharness: 2016-09-12 13:19:08.632295Z] Running download-and-extract step. 06:19:08 INFO - Running pre-action listener: _resource_record_pre_action 06:19:08 INFO - Running main action method: download_and_extract 06:19:08 INFO - mkdir: /builds/slave/test/build/tests 06:19:08 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')]} 06:19:08 INFO - trying https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 06:19:08 INFO - Downloading https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 06:19:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 06:19:09 INFO - Downloaded 1199 bytes. 06:19:09 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 06:19:09 INFO - Using the following test package requirements: 06:19:09 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:19:09 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 06:19:09 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 06:19:09 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'jsshell-mac.zip'], 06:19:09 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 06:19:09 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 06:19:09 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 06:19:09 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 06:19:09 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 06:19:09 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 06:19:09 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 06:19:09 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 06:19:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:19:10 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:19:17 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 06:19:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 06:19:17 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:19:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 06:19:25 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 06:19: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')]} 06:19:25 INFO - trying https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:19:25 INFO - Downloading https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 06:19:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 06:19:28 INFO - Downloaded 111630436 bytes. 06:19:28 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:19:28 INFO - mkdir: /builds/slave/test/properties 06:19:28 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 06:19:28 INFO - Writing to file /builds/slave/test/properties/build_url 06:19:28 INFO - Contents: 06:19:28 INFO - build_url:https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 06:19:29 INFO - Running post-action listener: _resource_record_post_action 06:19:29 INFO - Running post-action listener: set_extra_try_arguments 06:19:29 INFO - [mozharness: 2016-09-12 13:19:29.103760Z] Finished download-and-extract step (success) 06:19:29 INFO - [mozharness: 2016-09-12 13:19:29.103903Z] Running populate-webroot step. 06:19:29 INFO - Running pre-action listener: _resource_record_pre_action 06:19:29 INFO - Running main action method: populate_webroot 06:19:29 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 06:19:29 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 06:19:29 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 06:19:29 INFO - '--tppagecycles', 06:19:29 INFO - '7'], 06:19:29 INFO - 'tests': ['tsvgm']}}, 06:19:29 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['tresize', 'tcanvasmark']}, 06:19:29 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 06:19:29 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['dromaeo_css', 'kraken']}, 06:19:29 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 06:19:29 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:29 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 06:19:29 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['damp', 'tps']}, 06:19:29 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'tests': ['damp', 'tps']}, 06:19:29 INFO - 'g3': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['dromaeo_dom']}, 06:19:29 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 06:19:29 INFO - 'g4': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['basic_compositor_video']}, 06:19:29 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 06:19:29 INFO - 'other': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'other-e10s': {'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'other-e10s_l64': {'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['a11yr', 06:19:29 INFO - 'ts_paint', 06:19:29 INFO - 'tpaint', 06:19:29 INFO - 'sessionrestore', 06:19:29 INFO - 'sessionrestore_no_auto_restore', 06:19:29 INFO - 'tabpaint']}, 06:19:29 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['tsvgx', 06:19:29 INFO - 'tsvgr_opacity', 06:19:29 INFO - 'tart', 06:19:29 INFO - 'tscrollx', 06:19:29 INFO - 'cart']}, 06:19:29 INFO - 'svgr-e10s': {'tests': ['tsvgx', 06:19:29 INFO - 'tsvgr_opacity', 06:19:29 INFO - 'tart', 06:19:29 INFO - 'tscrollx', 06:19:29 INFO - 'cart']}, 06:19:29 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'talos_options': ['--disable-e10s'], 06:19:29 INFO - 'tests': ['tp5o']}, 06:19:29 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'tests': ['tp5o']}, 06:19:29 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'talos_options': ['--disable-e10s', 06:19:29 INFO - '--xperf_path', 06:19:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:29 INFO - 'tests': ['tp5n']}, 06:19:29 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 06:19:29 INFO - 'talos_options': ['--xperf_path', 06:19:29 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 06:19:29 INFO - 'tests': ['tp5n']}}, 06:19:29 INFO - 'talos.zip': {'path': '', 06:19:29 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 06:19:29 INFO - Running post-action listener: _resource_record_post_action 06:19:29 INFO - [mozharness: 2016-09-12 13:19:29.122353Z] Finished populate-webroot step (success) 06:19:29 INFO - [mozharness: 2016-09-12 13:19:29.122466Z] Running create-virtualenv step. 06:19:29 INFO - Running pre-action listener: _resource_record_pre_action 06:19:29 INFO - Running main action method: create_virtualenv 06:19:29 INFO - Creating virtualenv /builds/slave/test/build/venv 06:19:29 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 06:19:29 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 06:19:29 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 06:19:29 INFO - Using real prefix '/tools/python27' 06:19:29 INFO - New python executable in /builds/slave/test/build/venv/bin/python 06:19:29 INFO - Installing distribute.............................................................................................................................................................................................done. 06:19:32 INFO - Installing pip.................done. 06:19:32 INFO - Return code: 0 06:19:32 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 06:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:32 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:32 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 06:19:32 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 06:19:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:32 INFO - 'HOME': '/Users/cltbld', 06:19:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:32 INFO - 'LOGNAME': 'cltbld', 06:19:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:32 INFO - 'MOZ_NO_REMOTE': '1', 06:19:32 INFO - 'NO_EM_RESTART': '1', 06:19:32 INFO - 'PAGER': '/bin/cat', 06:19:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:32 INFO - 'PWD': '/builds/slave/test', 06:19:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:32 INFO - 'SHELL': '/bin/bash', 06:19:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:32 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:32 INFO - 'USER': 'cltbld', 06:19:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:32 INFO - 'XPC_FLAGS': '0x0', 06:19:32 INFO - 'XPC_SERVICE_NAME': '0', 06:19:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:33 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 06:19:33 INFO - Cleaning up... 06:19:33 INFO - Return code: 0 06:19:33 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 06:19:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:33 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:33 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 06:19:33 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 06:19:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:33 INFO - 'HOME': '/Users/cltbld', 06:19:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:33 INFO - 'LOGNAME': 'cltbld', 06:19:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:33 INFO - 'MOZ_NO_REMOTE': '1', 06:19:33 INFO - 'NO_EM_RESTART': '1', 06:19:33 INFO - 'PAGER': '/bin/cat', 06:19:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:33 INFO - 'PWD': '/builds/slave/test', 06:19:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:33 INFO - 'SHELL': '/bin/bash', 06:19:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:33 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:33 INFO - 'USER': 'cltbld', 06:19:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:33 INFO - 'XPC_FLAGS': '0x0', 06:19:33 INFO - 'XPC_SERVICE_NAME': '0', 06:19:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:33 INFO - Downloading/unpacking psutil>=3.1.1 06:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:36 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 06:19:36 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:36 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:36 INFO - Installing collected packages: psutil 06:19:36 INFO - Running setup.py install for psutil 06:19:36 INFO - building 'psutil._psutil_osx' extension 06:19:36 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 06:19:37 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 06:19:37 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 06:19:37 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 06:19:37 INFO - building 'psutil._psutil_posix' extension 06:19:37 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 06:19:37 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 06:19:37 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 06:19:37 INFO - ^ 06:19:37 INFO - 1 warning generated. 06:19:37 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 06:19:37 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 06:19:37 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 06:19:37 INFO - Successfully installed psutil 06:19:37 INFO - Cleaning up... 06:19:38 INFO - Return code: 0 06:19:38 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 06:19:38 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')]} 06:19:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:38 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')]} 06:19:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:38 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:38 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 06:19:38 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 06:19:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:38 INFO - 'HOME': '/Users/cltbld', 06:19:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:38 INFO - 'LOGNAME': 'cltbld', 06:19:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:38 INFO - 'MOZ_NO_REMOTE': '1', 06:19:38 INFO - 'NO_EM_RESTART': '1', 06:19:38 INFO - 'PAGER': '/bin/cat', 06:19:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:38 INFO - 'PWD': '/builds/slave/test', 06:19:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:38 INFO - 'SHELL': '/bin/bash', 06:19:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:38 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:38 INFO - 'USER': 'cltbld', 06:19:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:38 INFO - 'XPC_FLAGS': '0x0', 06:19:38 INFO - 'XPC_SERVICE_NAME': '0', 06:19:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:38 INFO - Downloading/unpacking mozsystemmonitor==0.3 06:19:38 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 06:19:38 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 06:19:38 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 06:19:38 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 06:19:38 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 06:19:38 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 06:19:40 INFO - Downloading mozsystemmonitor-0.3.tar.gz 06:19:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 06:19:40 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 06:19:40 INFO - Installing collected packages: mozsystemmonitor 06:19:40 INFO - Running setup.py install for mozsystemmonitor 06:19:40 INFO - Successfully installed mozsystemmonitor 06:19:40 INFO - Cleaning up... 06:19:41 INFO - Return code: 0 06:19:41 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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', '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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19: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', 'jsonschema==2.5.1'] in /builds/slave/test/build 06:19: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 jsonschema==2.5.1 06:19:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:41 INFO - 'HOME': '/Users/cltbld', 06:19:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:41 INFO - 'LOGNAME': 'cltbld', 06:19:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:41 INFO - 'MOZ_NO_REMOTE': '1', 06:19:41 INFO - 'NO_EM_RESTART': '1', 06:19:41 INFO - 'PAGER': '/bin/cat', 06:19:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:41 INFO - 'PWD': '/builds/slave/test', 06:19:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:41 INFO - 'SHELL': '/bin/bash', 06:19:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:41 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:41 INFO - 'USER': 'cltbld', 06:19:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:41 INFO - 'XPC_FLAGS': '0x0', 06:19:41 INFO - 'XPC_SERVICE_NAME': '0', 06:19:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:41 INFO - Downloading/unpacking jsonschema==2.5.1 06:19:41 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 06:19:41 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 06:19:41 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 06:19:41 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 06:19:41 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 06:19:41 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 06:19:43 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 06:19:43 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 06:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:44 INFO - Downloading functools32-3.2.3-2.tar.gz 06:19:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 06:19:44 INFO - warning: no files found matching '*.txt' 06:19:44 INFO - no previously-included directories found matching 'build' 06:19:44 INFO - no previously-included directories found matching 'dist' 06:19:44 INFO - no previously-included directories found matching '.git*' 06:19:44 INFO - Installing collected packages: jsonschema, functools32 06:19:44 INFO - Running setup.py install for functools32 06:19:44 INFO - warning: no files found matching '*.txt' 06:19:44 INFO - no previously-included directories found matching 'build' 06:19:44 INFO - no previously-included directories found matching 'dist' 06:19:44 INFO - no previously-included directories found matching '.git*' 06:19:44 INFO - Successfully installed jsonschema functools32 06:19:44 INFO - Cleaning up... 06:19:44 INFO - Return code: 0 06:19:44 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 06:19:44 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')]} 06:19:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:44 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')]} 06:19:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:44 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:44 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 06:19:44 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 06:19:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:44 INFO - 'HOME': '/Users/cltbld', 06:19:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:44 INFO - 'LOGNAME': 'cltbld', 06:19:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:44 INFO - 'MOZ_NO_REMOTE': '1', 06:19:44 INFO - 'NO_EM_RESTART': '1', 06:19:44 INFO - 'PAGER': '/bin/cat', 06:19:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:44 INFO - 'PWD': '/builds/slave/test', 06:19:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:44 INFO - 'SHELL': '/bin/bash', 06:19:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:44 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:44 INFO - 'USER': 'cltbld', 06:19:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:44 INFO - 'XPC_FLAGS': '0x0', 06:19:44 INFO - 'XPC_SERVICE_NAME': '0', 06:19:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:45 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 06:19:45 INFO - Cleaning up... 06:19:45 INFO - Return code: 0 06:19:45 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 06:19: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')]} 06:19:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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')]} 06:19:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19: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', '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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19: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', 'blobuploader==1.2.4'] in /builds/slave/test/build 06:19: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 blobuploader==1.2.4 06:19:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:45 INFO - 'HOME': '/Users/cltbld', 06:19:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:45 INFO - 'LOGNAME': 'cltbld', 06:19:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:45 INFO - 'MOZ_NO_REMOTE': '1', 06:19:45 INFO - 'NO_EM_RESTART': '1', 06:19:45 INFO - 'PAGER': '/bin/cat', 06:19:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:45 INFO - 'PWD': '/builds/slave/test', 06:19:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:45 INFO - 'SHELL': '/bin/bash', 06:19:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:45 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:45 INFO - 'USER': 'cltbld', 06:19:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:45 INFO - 'XPC_FLAGS': '0x0', 06:19:45 INFO - 'XPC_SERVICE_NAME': '0', 06:19:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:45 INFO - Downloading/unpacking blobuploader==1.2.4 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19: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 06:19:48 INFO - Downloading blobuploader-1.2.4.tar.gz 06:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 06:19:48 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 06:19:48 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:48 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 06:19:49 INFO - Downloading docopt-0.6.1.tar.gz 06:19:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 06:19:49 INFO - Installing collected packages: blobuploader, requests, docopt 06:19:49 INFO - Running setup.py install for blobuploader 06:19:49 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 06:19:49 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 06:19:49 INFO - Running setup.py install for requests 06:19:50 INFO - Running setup.py install for docopt 06:19:50 INFO - Successfully installed blobuploader requests docopt 06:19:50 INFO - Cleaning up... 06:19:50 INFO - Return code: 0 06:19:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:50 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:50 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 06:19:50 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 06:19:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:50 INFO - 'HOME': '/Users/cltbld', 06:19:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:50 INFO - 'LOGNAME': 'cltbld', 06:19:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:50 INFO - 'MOZ_NO_REMOTE': '1', 06:19:50 INFO - 'NO_EM_RESTART': '1', 06:19:50 INFO - 'PAGER': '/bin/cat', 06:19:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:50 INFO - 'PWD': '/builds/slave/test', 06:19:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:50 INFO - 'SHELL': '/bin/bash', 06:19:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:50 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:50 INFO - 'USER': 'cltbld', 06:19:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:50 INFO - 'XPC_FLAGS': '0x0', 06:19:50 INFO - 'XPC_SERVICE_NAME': '0', 06:19:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:50 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-EK3UxY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:50 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-zXIPXE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:50 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-gAkZ0m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-0qKCX3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-gmeMCb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-3stSRh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-jpHEmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-dTuzDv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-e9t7ou-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-iQbsrA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-nm9RDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-2P5wmS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:51 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-xMJ9ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:52 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ri_n1u-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:52 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-LfcWyA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:52 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-IQ3gFL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:52 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-AswQNQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:52 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 06:19:52 INFO - Running setup.py install for manifestparser 06:19:52 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 06:19:52 INFO - Running setup.py install for mozcrash 06:19:52 INFO - Running setup.py install for mozdebug 06:19:52 INFO - Running setup.py install for mozdevice 06:19:53 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Installing dm script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Running setup.py install for mozfile 06:19:53 INFO - Running setup.py install for mozhttpd 06:19:53 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Running setup.py install for mozinfo 06:19:53 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Running setup.py install for mozInstall 06:19:53 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Running setup.py install for mozleak 06:19:53 INFO - Running setup.py install for mozlog 06:19:53 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 06:19:53 INFO - Running setup.py install for moznetwork 06:19:54 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 06:19:54 INFO - Running setup.py install for mozprocess 06:19:54 INFO - Running setup.py install for mozprofile 06:19:54 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 06:19:54 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 06:19:54 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 06:19:54 INFO - Running setup.py install for mozrunner 06:19:54 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 06:19:54 INFO - Running setup.py install for mozscreenshot 06:19:54 INFO - Running setup.py install for moztest 06:19:54 INFO - Running setup.py install for mozversion 06:19:55 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 06:19:55 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 06:19:55 INFO - Cleaning up... 06:19:55 INFO - Return code: 0 06:19:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:19:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:19:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:19:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:19:55 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:19:55 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 06:19:55 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 06:19:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:19:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:19:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:19:55 INFO - 'HOME': '/Users/cltbld', 06:19:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:19:55 INFO - 'LOGNAME': 'cltbld', 06:19:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:19:55 INFO - 'MOZ_NO_REMOTE': '1', 06:19:55 INFO - 'NO_EM_RESTART': '1', 06:19:55 INFO - 'PAGER': '/bin/cat', 06:19:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:19:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:19:55 INFO - 'PWD': '/builds/slave/test', 06:19:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:19:55 INFO - 'SHELL': '/bin/bash', 06:19:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:19:55 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:19:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:19:55 INFO - 'USER': 'cltbld', 06:19:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:19:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:19:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:19:55 INFO - 'XPC_FLAGS': '0x0', 06:19:55 INFO - 'XPC_SERVICE_NAME': '0', 06:19:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:19:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-GrsIXU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 06:19:55 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)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-0RB82l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 06:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-SMblEb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 06:19:55 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)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Wvq862-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 06:19:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-PTlC5Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 06:19:55 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)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-UJ6UaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 06:19:55 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)) 06:19:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 06:19:55 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-6e1YT1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-rQHodu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-Ec5Pn8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-jtU7t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 06:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-0ggNfC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-YYErr1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 06:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-bnHluF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-K6HPcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 06:19:56 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-jWov9S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 06:19:56 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)) 06:19:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 06:19:56 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-ek0Djw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 06:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 06:19:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 06:19:57 INFO - Running setup.py (path:/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/pip-AKE4Lj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 06:19:57 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)) 06:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 06:19:57 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)) 06:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:57 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 06:19:57 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 06:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 06:19:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 06:19:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 06:19:59 INFO - Downloading blessings-1.6.tar.gz 06:19:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 06:19:59 INFO - Installing collected packages: blessings 06:19:59 INFO - Running setup.py install for blessings 06:19:59 INFO - Successfully installed blessings 06:19:59 INFO - Cleaning up... 06:19:59 INFO - Return code: 0 06:19:59 INFO - Done creating virtualenv /builds/slave/test/build/venv. 06:19:59 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:19:59 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:00 INFO - Reading from file tmpfile_stdout 06:20:00 INFO - Current package versions: 06:20:00 INFO - blessings == 1.6 06:20:00 INFO - blobuploader == 1.2.4 06:20:00 INFO - docopt == 0.6.1 06:20:00 INFO - functools32 == 3.2.3-2 06:20:00 INFO - jsonschema == 2.5.1 06:20:00 INFO - manifestparser == 1.1 06:20:00 INFO - mozInstall == 1.12 06:20:00 INFO - mozcrash == 0.17 06:20:00 INFO - mozdebug == 0.1 06:20:00 INFO - mozdevice == 0.48 06:20:00 INFO - mozfile == 1.2 06:20:00 INFO - mozhttpd == 0.7 06:20:00 INFO - mozinfo == 0.9 06:20:00 INFO - mozleak == 0.1 06:20:00 INFO - mozlog == 3.3 06:20:00 INFO - moznetwork == 0.27 06:20:00 INFO - mozprocess == 0.23 06:20:00 INFO - mozprofile == 0.28 06:20:00 INFO - mozrunner == 6.12 06:20:00 INFO - mozscreenshot == 0.1 06:20:00 INFO - mozsystemmonitor == 0.3 06:20:00 INFO - moztest == 0.7 06:20:00 INFO - mozversion == 1.4 06:20:00 INFO - psutil == 3.1.1 06:20:00 INFO - requests == 1.2.3 06:20:00 INFO - wsgiref == 0.1.2 06:20:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 06:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:00 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:20:00 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 06:20:00 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 06:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:00 INFO - 'HOME': '/Users/cltbld', 06:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:00 INFO - 'LOGNAME': 'cltbld', 06:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:00 INFO - 'MOZ_NO_REMOTE': '1', 06:20:00 INFO - 'NO_EM_RESTART': '1', 06:20:00 INFO - 'PAGER': '/bin/cat', 06:20:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:00 INFO - 'PWD': '/builds/slave/test', 06:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:00 INFO - 'SHELL': '/bin/bash', 06:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:20:00 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:00 INFO - 'USER': 'cltbld', 06:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:00 INFO - 'XPC_FLAGS': '0x0', 06:20:00 INFO - 'XPC_SERVICE_NAME': '0', 06:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 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)) 06:20:00 INFO - Cleaning up... 06:20:00 INFO - Return code: 0 06:20:00 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 06:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:20:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 06:20:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:00 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 06:20:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 06:20:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 06:20:00 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 0x1056ff490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105675570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd6e3495e70>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, '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.LF4WMkKz7y/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.jRxURuznX8/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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 06:20:00 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 06:20:00 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 06:20:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:20:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:00 INFO - 'HOME': '/Users/cltbld', 06:20:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:00 INFO - 'LOGNAME': 'cltbld', 06:20:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:00 INFO - 'MOZ_NO_REMOTE': '1', 06:20:00 INFO - 'NO_EM_RESTART': '1', 06:20:00 INFO - 'PAGER': '/bin/cat', 06:20:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:00 INFO - 'PWD': '/builds/slave/test', 06:20:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:00 INFO - 'SHELL': '/bin/bash', 06:20:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:20:00 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:20:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:00 INFO - 'USER': 'cltbld', 06:20:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:00 INFO - 'XPC_FLAGS': '0x0', 06:20:00 INFO - 'XPC_SERVICE_NAME': '0', 06:20:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 06:20:00 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 06:20:00 INFO - Cleaning up... 06:20:00 INFO - Return code: 0 06:20:00 INFO - Running post-action listener: _resource_record_post_action 06:20:00 INFO - Running post-action listener: _start_resource_monitoring 06:20:00 INFO - Starting resource monitoring. 06:20:00 INFO - [mozharness: 2016-09-12 13:20:00.794056Z] Finished create-virtualenv step (success) 06:20:00 INFO - [mozharness: 2016-09-12 13:20:00.795094Z] Running install step. 06:20:00 INFO - Running pre-action listener: _resource_record_pre_action 06:20:00 INFO - Running main action method: install 06:20:00 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 06:20:00 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 06:20:01 INFO - Reading from file tmpfile_stdout 06:20:01 INFO - Detecting whether we're running mozinstall >=1.0... 06:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 06:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 06:20:01 INFO - Reading from file tmpfile_stdout 06:20:01 INFO - Output received: 06:20:01 INFO - Usage: mozinstall [options] installer 06:20:01 INFO - Options: 06:20:01 INFO - -h, --help show this help message and exit 06:20:01 INFO - -d DEST, --destination=DEST 06:20:01 INFO - Directory to install application into. [default: 06:20:01 INFO - "/builds/slave/test"] 06:20:01 INFO - --app=APP Application being installed. [default: firefox] 06:20:01 INFO - mkdir: /builds/slave/test/build/application 06:20:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 06:20:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 06:20:35 INFO - Reading from file tmpfile_stdout 06:20:35 INFO - Output received: 06:20:35 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 06:20:35 INFO - Running post-action listener: _resource_record_post_action 06:20:35 INFO - [mozharness: 2016-09-12 13:20:35.347525Z] Finished install step (success) 06:20:35 INFO - [mozharness: 2016-09-12 13:20:35.347675Z] Running run-tests step. 06:20:35 INFO - Running pre-action listener: _resource_record_pre_action 06:20:35 INFO - Running main action method: run_tests 06:20:35 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:20:35 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:20:35 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:20:35 INFO - 2016-09-12 06:20:35.362 screenresolution[1645:7748] starting screenresolution argv=screenresolution get 06:20:35 INFO - 2016-09-12 06:20:35.365 screenresolution[1645:7748] Display 0: 1600x1200x32@60 06:20:35 INFO - 2016-09-12 06:20:35.371 screenresolution[1646:7750] starting screenresolution argv=screenresolution list 06:20:35 INFO - Available Modes on Display 0 06:20:35 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:20:35 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:20:35 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:20:35 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:20:35 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:20:35 INFO - Intel Iris: 06:20:35 INFO - Chipset Model: Intel Iris 06:20:35 INFO - Type: GPU 06:20:35 INFO - Bus: Built-In 06:20:35 INFO - VRAM (Dynamic, Max): 1536 MB 06:20:35 INFO - Vendor: Intel (0x8086) 06:20:35 INFO - Device ID: 0x0a2e 06:20:35 INFO - Revision ID: 0x0009 06:20:35 INFO - Displays: 06:20:35 INFO - Display: 06:20:35 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:20:35 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:20:35 INFO - Display Serial Number: 1600x1200 60 06:20:35 INFO - Main Display: Yes 06:20:35 INFO - Mirror: Off 06:20:35 INFO - Online: Yes 06:20:35 INFO - Rotation: Supported 06:20:35 INFO - Return code: 0 06:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 06:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 06:20:35 INFO - Python 2.7.3 06:20:35 INFO - Return code: 0 06:20:35 INFO - grabbing minidump binary from tooltool 06:20: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')]} 06:20:35 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 0x105513d30>, '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 0x105339db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10550bb20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 06:20:35 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 06:20:35 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 06:20:35 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 06:20:35 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 06:20:35 INFO - Return code: 0 06:20:35 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 06:20:35 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 06:20:35 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 06:20:35 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 06:20:35 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 06:20:35 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 06:20:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0003 --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 06:20:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.LF4WMkKz7y/Render', 06:20:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 06:20:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 06:20:35 INFO - 'HOME': '/Users/cltbld', 06:20:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 06:20:35 INFO - 'LOGNAME': 'cltbld', 06:20:35 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 06:20:35 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 06:20:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 06:20:35 INFO - 'MOZ_NO_REMOTE': '1', 06:20:35 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 06:20:35 INFO - 'NO_EM_RESTART': '1', 06:20:35 INFO - 'PAGER': '/bin/cat', 06:20:35 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 06:20:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 06:20:35 INFO - 'PWD': '/builds/slave/test', 06:20:35 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 06:20:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 06:20:35 INFO - 'SHELL': '/bin/bash', 06:20:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.jRxURuznX8/Listeners', 06:20:35 INFO - 'TMPDIR': '/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/', 06:20:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 06:20:35 INFO - 'USER': 'cltbld', 06:20:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 06:20:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 06:20:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 06:20:35 INFO - 'XPC_FLAGS': '0x0', 06:20:35 INFO - 'XPC_SERVICE_NAME': '0', 06:20:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 06:20:35 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0003', '--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 06:20:35 INFO - mozversion application_buildid: 20160912043314 06:20:35 INFO - mozversion application_changeset: 05bab13e18504f8c0b69076c10e0ef6a6df29333 06:20:35 INFO - mozversion application_display_name: Nightly 06:20:35 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 06:20:35 INFO - mozversion application_name: Firefox 06:20:35 INFO - mozversion application_remotingname: firefox 06:20:35 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 06:20:35 INFO - mozversion application_vendor: Mozilla 06:20:35 INFO - mozversion application_version: 51.0a1 06:20:35 INFO - mozversion platform_buildid: 20160912043314 06:20:35 INFO - mozversion platform_changeset: 05bab13e18504f8c0b69076c10e0ef6a6df29333 06:20:35 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 06:20:35 INFO - mozversion platform_version: 51.0a1 06:20:35 INFO - using testdate: 1473686435 06:20:35 INFO - actual date: 1473686435 06:20:35 INFO - starting webserver on 'localhost:49271' 06:20:35 INFO - SUITE-START | Running 2 tests 06:20:35 INFO - TEST-START | dromaeo_css 06:20:35 INFO - operating with platform_type : mac_ 06:20:35 INFO - Initialising browser for dromaeo_css test... 06:20:35 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpAl6wug/profile http://localhost:49271/getInfo.html) 06:20:38 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 06:20:38 INFO - PROCESS | 1656 | colorDepth:24 06:20:38 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 06:20:38 INFO - PROCESS | 1656 | __metrics 06:20:38 INFO - PROCESS | 1656 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:20:38 INFO - PROCESS | 1656 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:20:39 INFO - TEST-INFO | 1656: exit 0 06:20:39 INFO - Browser initialized. 06:20:39 INFO - Running cycle 1/1 for dromaeo_css test... 06:20:39 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmpAl6wug/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:20:51 INFO - PROCESS | 1658 | RSS: Main: 232755200 06:20:51 INFO - PROCESS | 1658 | 06:21:42 INFO - PROCESS | 1658 | 1473686502418 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 06:23:53 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 06:23:56 INFO - PROCESS | 1658 | RSS: Main: 416026624 06:23:56 INFO - PROCESS | 1658 | 06:26:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 06:26:59 INFO - PROCESS | 1658 | RSS: Main: 377802752 06:26:59 INFO - PROCESS | 1658 | 06:30:02 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 06:30:02 INFO - PROCESS | 1658 | RSS: Main: 335982592 06:30:02 INFO - PROCESS | 1658 | 06:33:05 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 06:33:05 INFO - PROCESS | 1658 | RSS: Main: 347054080 06:33:05 INFO - PROCESS | 1658 | 06:36:09 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 06:36:09 INFO - PROCESS | 1658 | RSS: Main: 324509696 06:36:09 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 06:39:13 INFO - PROCESS | 1658 | RSS: Main: 583114752 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | __start_tp_report 06:39:13 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 06:39:13 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 06:39:13 INFO - PROCESS | 1658 | |i|pagename|runs| 06:39:13 INFO - PROCESS | 1658 | |0;dojo.html;2803.663560372755;3345.2305969626987;3364.0412482442525;3386.3735208986354;3436.2096717754916;5561.277033985586;5651.067573850315;5693.743781529831;5741.684207368593;5780.566457515687;5707.743151558179;5726.284214473191;5737.3688894221605;5738.770449182028;5757.654540727559;5169.948300517003;5196.948030519684;5218.1650935850275;5229.555487783543;5267.3679158500845;5270.868228294285;5273.077211487994;5276.657017293883;5277.551408130314;5277.656952298107;5176.611754118438;5199.116150254447;5201.609879259052;5249.42256351801;5266.236395722637;5265.0522905877115;5298.15229563268;5300.92048619271;5317.069512835277;5340.866478338034;5033.471485494044;5058.114829904752;5122.92315615266;5200.089984252741;5225.608079394023;5634.605577609569;5732.971335143297;5737.168110623978;5748.683822389767;5770.394108618619;6242.906356404657;6273.40402661751;6275.905861412082;6280.371962803729;6299.244090709099;6174.073888916699;6185.690715464209;6205.503559715257;6287.559870809046;6294.433500984887;5779.450952159538;5784.8843023139725;5793.7972170974235;5875.676837773878;5885.882282354329;6303.716332765037;6310.684465776784;6314.273858506239;6324.430801227912;6342.04869269604;6031.547633927473;6123.510119190454;6176.3514830943;6189.35011823761;6193.845153871099;6118.959776837859;6128.075379514901;6153.138560601519;6176.320604733476;6283.874322513616;6263.906041409383;6264.686765661699;6265.96867015662;6276.592021518678;6287.528435367366;6245.156903818017;6282.308946015934;6286.779962701284;6291.433770960635;6292.716827742763;4892.584130348889;4943.777530011158;4943.777530011158;4972.279019542146;4987.4015118185325;4432.623227025674;4465.687401881837;4491.618212451978;4502.099580083996;4509.842155524541;5705.600607957404;5750.482456578928;5752.02215623345;5760.51035661965;5761.6254943429385;4931.50684931504;6099.218990344071;6131.774283661951;6283;6285.685715714196;4407.713498622579;5738.971305143447;5776.913346299809;5794.8551286217335;5821.53427725781;5470.753816078286;5485.4788795064405;5486.91769623448;5491.835244942658;5501.587380946445;6179.412955769195;6260.467860231931;6398.744050237939;6414.358564143735;6426.29310775805;6227.19046523949;6277.623342599459;6298.244210694746;6298.748050077946;6309.274433440213;6334.113224148715;6350.301466838551;6396.392342727433;6399;6407.327230640715;6106.6946652668075;6239.500839932885;6251.437370636664;6262;6282.246130464374;6173.290071641819;6220.098085777628;6228.688565571794;6243.282022567281;6309.211348581427;6168.228971378578;6176.629402235881;6177.660228687465;6179.134921110958;6187.81436556904;5410.78356865721;6131.816045518642;6173.32093469709;6192.504599631929;6209.720562574605;5863.413658634103;5873.559483038704;5876.529877609695;5915.704214789157;5981.461668449861;6100.572959892765;6105.84735381619;6142.447179753844;6182.629042257479;6197.287311959002;5876.735546900485;5880.794172203952;6022.640834041694;6029.849253768691;6093.99449090895 06:39:13 INFO - PROCESS | 1658 | |1;ext.html;440.9757463339515;492.6206820748022;525.1885836382788;525.7660341148189;557.1447827584657;28914.132576022726;31686.84156579217;39948.40077398841;41090;41327.380089298684;43075.569244307546;43277.7836110819;43295.567044329546;43312.78343608278;43417.34873976893;9819.999999999982;10018.59925602975;10025.749356266078;10034.498275086216;10042.598296068149;8756.343274254425;8955.820883582324;11898.881011189864;12081.395930203498;12107.878921210808;12262.57081002165;12346.629601111981;12370.567030153947;12375.752484950295;12383.56657516987;12207.75584488314;12311.938440307786;12312.26126432417;12320.075994300374;12329.93835030828;12090.40056387872;12191.390430478441;12202.206856554385;12216.389180540937;12218.083643726755;41012.17975640501;41331.38002929959;41422.79288603538;41432.37851432231;41458.756237312926;3300.455424854896;3372.865085396581;3463.0649724574478;3529.347070791912;3725.590185079639;25233;25756.356091097874;30663.540046899314;31305.530417043763;31891;3209.422303985282;3306.35526072416;3335.7831740936763;3417.2311229973307;3421.8460169292443;507596;509090.09099089797;511267.7746445087;512641;515983;62043.68978155171;62611;62865;63024.36975630277;63104.05343919845;64100.358996409435;64120;64213.11041003166;64261.03608445877;64281.35718642754;16132.838671613368;16333.02001879891;16374.345026198818;16374.345026199057;16405.261763220682;11494.482748276181;11699.707507312385;12640.683982900317;12720.300383478998;12753.043521735906;15493.845061549466;17269.39557115495;17732.20205090774;17743.911280443772;18287.26850925948;4611.7677973976;5692.544596432275;6585.275619681831;6719.462443004548;6736.709518763644;911.7310393433953;927.5408672706918;980;1052.5947510208625;1067.295584913953;29917.700822991927;30175.698243017727;30360.84819575888;30580.35492122274;30734.538981915288;1036.6527213383602;1059.9311044782219;1070.9089727373262;1083.7615724540588;1097.7475180708482;13002.739945201043;15520.689586208438;15541.912066155486;15612.76580851288;15981.760273595906;662508;666578;667316;667615;668454;49654.503454964986;49832.50167498351;57113.42886571164;57358.7132064337;57396;1342.8657134286493;1355.159800923414;1386.1544457880684;1394.9999999999798;1395.741787769266;26944;30015.96169193952;31523.684763152076;31551.684483154873;32419.513707294882;51926.74036629793;52520.47479525156;52552.474475254756;52581.211281831565;52606.73696631492;406045;479115;479453;480051;481140;34357;38521.844344669706;40643.98390040272;40907.59092409157;40947.590524094376;38822.4176637345;46729.76635116803;48139.277910830664;48502.272465913746;48608;39117.022074447224;39425.71030957954;39624;40652.59347406488;41065.58934410618;29262;38614.42078368883;39658.60341396549;40038.39942400808;40593.797031014656;38283.61716382801;39144.80427597844;39194.60805392024;39270.41094383644;39293.60706393014;30062.098137055924;30201;32871.52078156441;34593.82703086468;34744.13139671528;25004;25368;25369.74630253748;26039.218823435327;26090.86954565215 06:39:13 INFO - PROCESS | 1658 | |2;jquery.html;327.16899076346084;348.9005633394483;365.43357795417097;377.367908752839;401.12954887893267;15118.924405377971;15130;16350.100744459065;20886.37340879772;21172.788272117312;20042.699359509617;20499.69250461244;21178.99999999996;21217.151313947463;21380.679289810665;5000.574951129159;5911.467967882901;6163.63018218906;6168.506519478454;6169.784057557987;6000.639961602296;6041.667708276043;6087.20866287382;6103.786367477139;6120.387961203888;5793.942060579404;6047.969760151215;6052.606580572248;6067.817965461043;6076.787312444066;6033.69831508425;6075.48358389533;6081.239845019372;6089.421504957022;6093.939060609426;5039.72281524516;5219.530242278213;5270.683758974474;5285.207218917155;5285.2600635911;26247;26417.207483775517;26421;26480.337991550365;26753.331166720793;2209.215728416405;2346.9295921122393;2492.1775813981344;2574.124797568817;2808.775297976157;13046.739065218737;14622;15720.449784257611;16433.753493697604;16626.418075367303;1690.847823695861;1844.437446578793;1849.2140840142886;1861.4508719927653;1876.3714155757837;118153;123201;126890;128827.35586322102;128907.99999999907;8315.04376996641;9294.628214871329;9757.90242097584;9859.211263098934;10106.797864042825;14062.648433789238;14088.647783805487;14106.294685265695;14113;14116.85883141176;8358.289545388589;8537.530435825965;8539.06070332263;8575.699850505327;8692.608832602547;8535.402521823537;8555.31557475413;8595.742127736177;8696.869546956801;8702.260307873901;5723.969685456575;6722.394984451423;6728.6635668216395;6752.5948443093575;6765.830854228684;1518.883620538903;1525.519461369665;1619.3078233246285;1682.8906121102088;1864.7855496617792;836.6277006732067;1002.9197664186847;1013.2299452416216;1015.7866847961863;1059.3855563772993;21457.78542214558;22788.4302892429;22937.541249174923;22951.655725164135;23006.309810705705;874.8381549413378;913.8537833946536;965.7247684409909;1009.6869970309227;1014.8274793285159;9332.860007099898;10657.57369705203;11932.136287290989;12180.390980450942;12189.75620487603;604341;605817;618082;620409;621739;82605.58697206476;90425.1914961697;92264.0773592269;112535.37040184723;124069.37965310297;967.1923943507251;975.7804493988938;1081.1620993729764;1086.4513420722642;1087.7661302819931;17393.913030434767;20246.59506809885;20711.482212944797;20863.79136208679;20977;37842.81078594589;40523.666465051225;54549.72725136349;55555;56555;79538.80691789744;79909.60045199736;80320;81085;81104;7814.53112813233;7839.9216007839195;7867.803304917423;7989.320907722677;8213.630386632496;13099.083064185797;13166.341682915625;13313.001524885865;13337.933310333387;13348.265845378597;9132.543372831198;11524.481398336928;11626.53493860236;11648.825267621161;11685.240459370114;8821.867671984797;10371.807242166946;11225.887741122484;11384.829227561428;11677.416129193676;9853.261005424765;11645.184837061324;11659.125565582784;11887.465064071965;11919.46362413676;7016.614086225102;7287.927120728725;7294.598797066;7465.738699145504;8250.298724608476;8200.835983280182;10742.51658675377;11247.606333778278;11252.718682033015;11254.549818007516 06:39:13 INFO - PROCESS | 1658 | |3;mootools.html;1540.02978123782;1572.536101849954;1789.4810504953564;1843.8340549350555;1940.55367265529;8591.48451092934;9164.541772911345;9164.991850896396;9167.174954254117;9167.266618670506;7984.4810087344285;8169;8190.9590452047805;8719.782005449866;8914.77713057174;2394.724606670229;2465.0139944022444;2467.716212635552;2468.049800826686;2471.283327834926;2391.1707543729062;2452.6566280720626;2459.3359792855917;2466.7656572625574;2467.111839737693;2423.612222044473;2443.1815341860497;2456.3981824452944;2464.2360868130845;2464.3962229253857;2419.63705444183;2430.659707640914;2430.7812296893276;2433.4403087289847;2435;2428.514297140579;2429.878506074689;2430.805535557151;2434.914777982762;2435.695538057743;9013.549322533847;9024.593893274752;9120.407173533766;9126.087391260888;9126.315526335551;5359.651622644555;5389.83830485086;5491.368492623359;5495.120780675113;5505.146702261156;3556.270964452281;3677.4667673187505;3698.278835627054;3716.6655001050035;3783.0920579061103;1071.8338657508098;1098.6868742408376;1116.3636727065496;1132.3432409202642;1136.204656740277;11955.462004209832;12051.21667091645;12053.975412089983;12079.939600301943;12105.213161144586;4731.739754313477;4740.166466618139;4825.734584597811;4931.18635425158;4954.083494553519;10224.233182511342;10229.488525573692;10239;10252.64115755945;10252.846207306897;5495.093309603951;5496.917546236809;5498.477644623755;5499.615026948075;5500.559955203574;4504.617107545895;4598.241290187082;4932.605391568738;5024.949750502448;5036.093503169464;3979.980100099539;3982.522097348337;4087.2847251730827;4096.508418989681;4123.773192474443;1307.4704744578462;1320.6434262749003;1324.8211491448724;1327.4292054416694;1333.3266700316278;790.8655528560158;860.4278155026838;880.067128843428;895.2077411490748;905.524599585223;5783.537317014713;5840.737166827503;5936.050231962951;5957.314908785458;6112.847178820565;434.2270758050675;437.2108344438319;456.4682145300754;460.5187578979974;461.5292401750263;3876.6511014008875;3914.530256369254;3973;4069.593040695907;4082.1631565528996;10554.155667546578;11024.724381890517;11060.557577696964;11080.390578518098;11106.500207490682;11018.228723989243;11028.944855275673;11030.889691103146;11041.889581104086;11056.226064175431;8307.418480706292;8377.45546539473;8386.87419688705;8394.916050839414;8396.160383961555;5627.549796016391;5723;5890.764369425175;5934.43622855828;5963.28440587115;10052.894181639867;10256;10321.948390258001;10334.689959301233;10350.998210518865;10375.014373634493;10421;10469.26715129964;10511.211659125749;10531.842022369518;7019.613921234177;7224.638768061681;7235.927640723526;7662.616869156408;7711;8251.133630968661;8362.121977192308;8541.572921354033;8662.91337086621;8673.436226645248;6620.470362370903;7562.546247225184;7681.347085497795;7721.652525636472;7767.572783496962;7455.739049133471;7460.142083660448;7504.3246107850555;7537.849243015218;7689.615519223905;6906.37842594169;7033.824154396181;7036;7039.859202816017;7040.260772618802;4904.8773780655765;5029.170186919117;5034.395872495177;5098.1842905135;5170.560502357192;5041.193409054534;5081.364829396325;5191.532762051434;5236.423993360651;5313.654612450178 06:39:13 INFO - PROCESS | 1658 | |4;prototype.html;300.5596800686993;301.8701958157991;307.74918441470203;312.6154829559642;314.5344889563448;4397.912041759167;4557.202489564325;5330.600204984632;5336.811907118083;5351.304330437042;4345.3264743964655;4868.805247790084;4894.339264199332;5049.419316778572;5197.480251974801;3381.2392211752403;3936.877989772908;4006.298897692907;4011.913804821491;4035.2333056719276;3968.0675041365257;3974.841006359742;4001.779457265533;4020.3165461871554;4053.4730485037053;3395.473701576247;3806.2681479926705;3961.8019099045077;3979.363301871701;4028.5165780106427;3333.250018745775;3533.2580158166816;3999.7000224982953;4014.7992600369867;4019.9598004019876;2923.7866285491496;3484.337975784593;3487.0646791030968;3494.143934736006;3508.3162679186753;5056.9633225188745;5091.9236211456855;5120.718360490171;5135.0500157470615;5222.608304377149;4094.365373367103;4308.82764689412;4541.795619197144;4616.460946479457;4642.210824159901;4581.61056310214;4740.241561350202;5402.8919421611345;5418.756155972991;5473.480019398151;3811.752236104672;3827.8660246891227;3844.8654297099465;3862.991244552845;3880.2239552089413;4629.606483448911;5052.115879721071;5334.306540149794;5610.523105536035;5678.4605462480995;3894.5131858517684;3909.179072394772;3909.5504017038406;3916.2559113768293;3949;3937.488126543531;3972.4041393791163;3984.2230765000545;4036.8990775230855;4049.493813273341;3906.511686039245;4082.2651922653613;4584.899624090194;4647.279671650899;4666.996595731866;4560.384348113029;4764.356811830427;5138.58891288696;5435.119386725042;5483.314585676791;1324.8012798080172;1345.4281930179634;1358.8369395672385;1359.232033900843;1361.1900918953206;853.9573021348908;896.8161526886972;900.5182227508252;901.6880438961296;903.6701603914524;4243.872683819491;4529.456465224194;4954.405471343462;5049.343585333883;5054.620903432257;4599.2292967207195;4753.809847606126;4837.371971021734;5411.702356370437;5551.946429463779;4127.587241275908;4336.73979561221;4499.032707967803;4689.6717229793585;5082.669626474268;2595.2733234694315;2984.2986898078934;2987.536931775578;2993.383572870669;3027.7880548361845;5264.736763161826;5794.159846822187;5875.529957603381;5886;5889.793857214977;4666.346711460398;4756.453007904066;5327.866803329948;5731.882282954867;5757.424257574209;4838.532348502289;5328.36059672834;5739;5791.797287094932;5839.328477225174;5030.76746197184;5063;5094.36980166358;5587.3015873015875;5592.552595792408;5141.331626888481;5325.840224793263;5544.7782088716;5597.63615365;5691.089425691869;5216.713080780593;5870.471657550841;5878.442877348367;5905.527557795452;5959.582829202088;3602;3796.804006737869;3882.2429626222615;3955.268275368951;3958.18857134281;4939.901201975868;5651.463111004448;5651.717414129195;5680.32430433015;5694.487496125369;4814.257955913353;5525.226468294522;5542.556595472433;5587.776488940397;5599.8880022400135;4805.192897948444;5202.921956170585;5384.919226211531;5448.618596698193;5480.506754392124;4666.300054991779;5118.411382691038;5121.487851214849;5377.596680249075;5488.396276409671;4653.697509661861;5109.616778741683;5385.54222891043;5394.487523685244;5515.806946756844;4284.764337961317;4306.806193721352;5132.153194722829;5224.164133738584;5448.073827449422 06:39:13 INFO - PROCESS | 1658 | |5;yui.html;592.0515831160819;788.6096382290767;796.6016991504251;810.5663470043528;811.9025716913972;15309.770353444694;16590.83409165911;16624.58438539037;16657.916710416444;16729.99620022798;14985;15124.773128403083;15129.092254464713;15140.621484462894;15156.31796569157;665.597313625257;685.5749435350065;826.6858593734372;848.4485084694936;870.8911386076741;845.4589062999654;859.5014891362995;860.2086080805642;860.5826174305457;862.5126803356084;926.8934072581671;928.9489078100702;929.0987741890356;929.1034152043277;929.6513807322254;703.0789665538161;727.574368994139;881.6253092435753;920.2224120618067;920.5949382271779;669.2404121322286;681.6148875885159;682.3551743602297;682.9248782633906;683.9911131081665;15169.772453413208;15217.61955951099;15226.771598425921;15261.61845953849;15267.38930442781;14005.229712365815;14105;14110.50613228532;14144.71710565783;14378.928105359406;12658.303793291365;12757.23456592598;12834.871651283462;12941.870581294255;13335.733285334336;3763.303788799081;4452.332150177467;4566.977165114153;4594.151720565626;4634.39752832133;120872.39563802125;126092;126251;127888.12950899184;128205.35897320454;10896.891031089588;11116.221864469571;11167.436558881764;11296.667483092508;11602.825957610643;22838.200662976717;22839.20062797794;22849.971751271234;22879.084836606682;22901.19845805389;7697.807554811175;7764.06831180262;7847.607619619111;7986.480878742863;8176.059753128347;4413.646908247332;4471.776411179428;4482.596566309048;4551.180787458223;4618.907621847544;3408.1649995750704;3553.466979953028;3558.377283975294;3560.9821950890077;3561;655.5902560899438;669.926308106108;670.5138774388529;673.4275865514273;679.8062552172706;438.8222769778245;481.9132556139929;599.4045781764947;622.950163986889;629.4743888852859;8706.39055266138;8952.328575356874;8963.686270980485;8978.889960859193;8986.86519702205;555.4251349852883;555.4945000050016;559.3455656881458;589.5990726306068;625.6465097220147;3711.406175011985;3994.849845147378;4070.3690927906223;4189.685773566995;4314.848980285675;103227;103462;104159.4792026035;162306;163982.18008910117;111168.44415778031;112122.43938780254;112297.43851280691;140684.85548439523;144419;74801.62599186969;76110.9999999989;76219;92469;113035.34823258805;8903.554822258991;9921.851172232278;10091.949540252546;10100.797984040424;10287.022732840369;62730.372696272454;63838;63901.36098638954;64531.677341612994;65929.34070659232;89449;93682.53158734435;97821.53267701133;99105;99742;12911.954485533848;13040.54358097462;13145.60563183106;13220.272884991418;13303.370339619296;14532.927335363256;14832;14863.479778207711;15545.766813498032;15625.140617265704;10994.560217591526;13369.53206637763;13563.186208827501;13722.108062975874;14131.434742610592;12586.370681466073;12676.366181690693;12760.234385936874;13262.403191856583;13295;12872.678183045498;13411.664708381979;13439.865601343861;13757.936309553646;13854.792178117135;10259.076683098558;10267.463408350075;10355.326903751233;10684.679459616224;10740.677779666623;8179.953019535604;9859.943417540586;11498.94250528742;11509.942450287695;11544.422778861192 06:39:13 INFO - PROCESS | 1658 | __end_tp_report 06:39:13 INFO - PROCESS | 1658 | __start_cc_report 06:39:13 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1809 06:39:13 INFO - PROCESS | 1658 | __end_cc_report 06:39:13 INFO - PROCESS | 1658 | __startTimestamp1473687553533__endTimestamp 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | ------- Summary: start ------- 06:39:13 INFO - PROCESS | 1658 | Number of tests: 6 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#0] dojo.html Cycles:165 Average:5753.69 Median:5915.70 stddev:663.49 (11.2%) stddev-sans-first:623.87 06:39:13 INFO - PROCESS | 1658 | Values: 2803.7 3345.2 3364.0 3386.4 3436.2 5561.3 5651.1 5693.7 5741.7 5780.6 5707.7 5726.3 5737.4 5738.8 5757.7 5169.9 5196.9 5218.2 5229.6 5267.4 5270.9 5273.1 5276.7 5277.6 5277.7 5176.6 5199.1 5201.6 5249.4 5266.2 5265.1 5298.2 5300.9 5317.1 5340.9 5033.5 5058.1 5122.9 5200.1 5225.6 5634.6 5733.0 5737.2 5748.7 5770.4 6242.9 6273.4 6275.9 6280.4 6299.2 6174.1 6185.7 6205.5 6287.6 6294.4 5779.5 5784.9 5793.8 5875.7 5885.9 6303.7 6310.7 6314.3 6324.4 6342.0 6031.5 6123.5 6176.4 6189.4 6193.8 6119.0 6128.1 6153.1 6176.3 6283.9 6263.9 6264.7 6266.0 6276.6 6287.5 6245.2 6282.3 6286.8 6291.4 6292.7 4892.6 4943.8 4943.8 4972.3 4987.4 4432.6 4465.7 4491.6 4502.1 4509.8 5705.6 5750.5 5752.0 5760.5 5761.6 4931.5 6099.2 6131.8 6283.0 6285.7 4407.7 5739.0 5776.9 5794.9 5821.5 5470.8 5485.5 5486.9 5491.8 5501.6 6179.4 6260.5 6398.7 6414.4 6426.3 6227.2 6277.6 6298.2 6298.7 6309.3 6334.1 6350.3 6396.4 6399.0 6407.3 6106.7 6239.5 6251.4 6262.0 6282.2 6173.3 6220.1 6228.7 6243.3 6309.2 6168.2 6176.6 6177.7 6179.1 6187.8 5410.8 6131.8 6173.3 6192.5 6209.7 5863.4 5873.6 5876.5 5915.7 5981.5 6100.6 6105.8 6142.4 6182.6 6197.3 5876.7 5880.8 6022.6 6029.8 6094.0 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#1] ext.html Cycles:180 Average:68972.16 Median:19136.74 stddev:148051.49 (773.7%) stddev-sans-first:148377.40 06:39:13 INFO - PROCESS | 1658 | Values: 441.0 492.6 525.2 525.8 557.1 28914.1 31686.8 39948.4 41090.0 41327.4 43075.6 43277.8 43295.6 43312.8 43417.3 9820.0 10018.6 10025.7 10034.5 10042.6 8756.3 8955.8 11898.9 12081.4 12107.9 12262.6 12346.6 12370.6 12375.8 12383.6 12207.8 12311.9 12312.3 12320.1 12329.9 12090.4 12191.4 12202.2 12216.4 12218.1 41012.2 41331.4 41422.8 41432.4 41458.8 3300.5 3372.9 3463.1 3529.3 3725.6 25233.0 25756.4 30663.5 31305.5 31891.0 3209.4 3306.4 3335.8 3417.2 3421.8 507596.0 509090.1 511267.8 512641.0 515983.0 62043.7 62611.0 62865.0 63024.4 63104.1 64100.4 64120.0 64213.1 64261.0 64281.4 16132.8 16333.0 16374.3 16374.3 16405.3 11494.5 11699.7 12640.7 12720.3 12753.0 15493.8 17269.4 17732.2 17743.9 18287.3 4611.8 5692.5 6585.3 6719.5 6736.7 911.7 927.5 980.0 1052.6 1067.3 29917.7 30175.7 30360.8 30580.4 30734.5 1036.7 1059.9 1070.9 1083.8 1097.7 13002.7 15520.7 15541.9 15612.8 15981.8 662508.0 666578.0 667316.0 667615.0 668454.0 49654.5 49832.5 57113.4 57358.7 57396.0 1342.9 1355.2 1386.2 1395.0 1395.7 26944.0 30016.0 31523.7 31551.7 32419.5 51926.7 52520.5 52552.5 52581.2 52606.7 406045.0 479115.0 479453.0 480051.0 481140.0 34357.0 38521.8 40644.0 40907.6 40947.6 38822.4 46729.8 48139.3 48502.3 48608.0 39117.0 39425.7 39624.0 40652.6 41065.6 29262.0 38614.4 39658.6 40038.4 40593.8 38283.6 39144.8 39194.6 39270.4 39293.6 30062.1 30201.0 32871.5 34593.8 34744.1 25004.0 25368.0 25369.7 26039.2 26090.9 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#2] jquery.html Cycles:180 Average:35043.14 Median:14781.05 stddev:101943.96 (689.7%) stddev-sans-first:102196.61 06:39:13 INFO - PROCESS | 1658 | Values: 327.2 348.9 365.4 377.4 401.1 15118.9 15130.0 16350.1 20886.4 21172.8 20042.7 20499.7 21179.0 21217.2 21380.7 5000.6 5911.5 6163.6 6168.5 6169.8 6000.6 6041.7 6087.2 6103.8 6120.4 5793.9 6048.0 6052.6 6067.8 6076.8 6033.7 6075.5 6081.2 6089.4 6093.9 5039.7 5219.5 5270.7 5285.2 5285.3 26247.0 26417.2 26421.0 26480.3 26753.3 2209.2 2346.9 2492.2 2574.1 2808.8 13046.7 14622.0 15720.4 16433.8 16626.4 1690.8 1844.4 1849.2 1861.5 1876.4 118153.0 123201.0 126890.0 128827.4 128908.0 8315.0 9294.6 9757.9 9859.2 10106.8 14062.6 14088.6 14106.3 14113.0 14116.9 8358.3 8537.5 8539.1 8575.7 8692.6 8535.4 8555.3 8595.7 8696.9 8702.3 5724.0 6722.4 6728.7 6752.6 6765.8 1518.9 1525.5 1619.3 1682.9 1864.8 836.6 1002.9 1013.2 1015.8 1059.4 21457.8 22788.4 22937.5 22951.7 23006.3 874.8 913.9 965.7 1009.7 1014.8 9332.9 10657.6 11932.1 12180.4 12189.8 604341.0 605817.0 618082.0 620409.0 621739.0 82605.6 90425.2 92264.1 112535.4 124069.4 967.2 975.8 1081.2 1086.5 1087.8 17393.9 20246.6 20711.5 20863.8 20977.0 37842.8 40523.7 54549.7 55555.0 56555.0 79538.8 79909.6 80320.0 81085.0 81104.0 7814.5 7839.9 7867.8 7989.3 8213.6 13099.1 13166.3 13313.0 13337.9 13348.3 9132.5 11524.5 11626.5 11648.8 11685.2 8821.9 10371.8 11225.9 11384.8 11677.4 9853.3 11645.2 11659.1 11887.5 11919.5 7016.6 7287.9 7294.6 7465.7 8250.3 8200.8 10742.5 11247.6 11252.7 11254.5 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#3] mootools.html Cycles:180 Average:5827.69 Median:461.02 stddev:3294.76 (714.7%) stddev-sans-first:3288.25 06:39:13 INFO - PROCESS | 1658 | Values: 1540.0 1572.5 1789.5 1843.8 1940.6 8591.5 9164.5 9165.0 9167.2 9167.3 7984.5 8169.0 8191.0 8719.8 8914.8 2394.7 2465.0 2467.7 2468.0 2471.3 2391.2 2452.7 2459.3 2466.8 2467.1 2423.6 2443.2 2456.4 2464.2 2464.4 2419.6 2430.7 2430.8 2433.4 2435.0 2428.5 2429.9 2430.8 2434.9 2435.7 9013.5 9024.6 9120.4 9126.1 9126.3 5359.7 5389.8 5491.4 5495.1 5505.1 3556.3 3677.5 3698.3 3716.7 3783.1 1071.8 1098.7 1116.4 1132.3 1136.2 11955.5 12051.2 12054.0 12079.9 12105.2 4731.7 4740.2 4825.7 4931.2 4954.1 10224.2 10229.5 10239.0 10252.6 10252.8 5495.1 5496.9 5498.5 5499.6 5500.6 4504.6 4598.2 4932.6 5024.9 5036.1 3980.0 3982.5 4087.3 4096.5 4123.8 1307.5 1320.6 1324.8 1327.4 1333.3 790.9 860.4 880.1 895.2 905.5 5783.5 5840.7 5936.1 5957.3 6112.8 434.2 437.2 456.5 460.5 461.5 3876.7 3914.5 3973.0 4069.6 4082.2 10554.2 11024.7 11060.6 11080.4 11106.5 11018.2 11028.9 11030.9 11041.9 11056.2 8307.4 8377.5 8386.9 8394.9 8396.2 5627.5 5723.0 5890.8 5934.4 5963.3 10052.9 10256.0 10321.9 10334.7 10351.0 10375.0 10421.0 10469.3 10511.2 10531.8 7019.6 7224.6 7235.9 7662.6 7711.0 8251.1 8362.1 8541.6 8662.9 8673.4 6620.5 7562.5 7681.3 7721.7 7767.6 7455.7 7460.1 7504.3 7537.8 7689.6 6906.4 7033.8 7036.0 7039.9 7040.3 4904.9 5029.2 5034.4 5098.2 5170.6 5041.2 5081.4 5191.5 5236.4 5313.7 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#4] prototype.html Cycles:180 Average:4388.72 Median:4760.40 stddev:1314.70 (27.6%) stddev-sans-first:1282.08 06:39:13 INFO - PROCESS | 1658 | Values: 300.6 301.9 307.7 312.6 314.5 4397.9 4557.2 5330.6 5336.8 5351.3 4345.3 4868.8 4894.3 5049.4 5197.5 3381.2 3936.9 4006.3 4011.9 4035.2 3968.1 3974.8 4001.8 4020.3 4053.5 3395.5 3806.3 3961.8 3979.4 4028.5 3333.3 3533.3 3999.7 4014.8 4020.0 2923.8 3484.3 3487.1 3494.1 3508.3 5057.0 5091.9 5120.7 5135.1 5222.6 4094.4 4308.8 4541.8 4616.5 4642.2 4581.6 4740.2 5402.9 5418.8 5473.5 3811.8 3827.9 3844.9 3863.0 3880.2 4629.6 5052.1 5334.3 5610.5 5678.5 3894.5 3909.2 3909.6 3916.3 3949.0 3937.5 3972.4 3984.2 4036.9 4049.5 3906.5 4082.3 4584.9 4647.3 4667.0 4560.4 4764.4 5138.6 5435.1 5483.3 1324.8 1345.4 1358.8 1359.2 1361.2 854.0 896.8 900.5 901.7 903.7 4243.9 4529.5 4954.4 5049.3 5054.6 4599.2 4753.8 4837.4 5411.7 5551.9 4127.6 4336.7 4499.0 4689.7 5082.7 2595.3 2984.3 2987.5 2993.4 3027.8 5264.7 5794.2 5875.5 5886.0 5889.8 4666.3 4756.5 5327.9 5731.9 5757.4 4838.5 5328.4 5739.0 5791.8 5839.3 5030.8 5063.0 5094.4 5587.3 5592.6 5141.3 5325.8 5544.8 5597.6 5691.1 5216.7 5870.5 5878.4 5905.5 5959.6 3602.0 3796.8 3882.2 3955.3 3958.2 4939.9 5651.5 5651.7 5680.3 5694.5 4814.3 5525.2 5542.6 5587.8 5599.9 4805.2 5202.9 5384.9 5448.6 5480.5 4666.3 5118.4 5121.5 5377.6 5488.4 4653.7 5109.6 5385.5 5394.5 5515.8 4284.8 4306.8 5132.2 5224.2 5448.1 06:39:13 INFO - PROCESS | 1658 | 06:39:13 INFO - PROCESS | 1658 | [#5] yui.html Cycles:180 Average:24180.02 Median:3560.99 stddev:38030.24 (1068.0%) stddev-sans-first:38095.68 06:39:13 INFO - PROCESS | 1658 | Values: 592.1 788.6 796.6 810.6 811.9 15309.8 16590.8 16624.6 16657.9 16730.0 14985.0 15124.8 15129.1 15140.6 15156.3 665.6 685.6 826.7 848.4 870.9 845.5 859.5 860.2 860.6 862.5 926.9 928.9 929.1 929.1 929.7 703.1 727.6 881.6 920.2 920.6 669.2 681.6 682.4 682.9 684.0 15169.8 15217.6 15226.8 15261.6 15267.4 14005.2 14105.0 14110.5 14144.7 14378.9 12658.3 12757.2 12834.9 12941.9 13335.7 3763.3 4452.3 4567.0 4594.2 4634.4 120872.4 126092.0 126251.0 127888.1 128205.4 10896.9 11116.2 11167.4 11296.7 11602.8 22838.2 22839.2 22850.0 22879.1 22901.2 7697.8 7764.1 7847.6 7986.5 8176.1 4413.6 4471.8 4482.6 4551.2 4618.9 3408.2 3553.5 3558.4 3561.0 3561.0 655.6 669.9 670.5 673.4 679.8 438.8 481.9 599.4 623.0 629.5 8706.4 8952.3 8963.7 8978.9 8986.9 555.4 555.5 559.3 589.6 625.6 3711.4 3994.8 4070.4 4189.7 4314.8 103227.0 103462.0 104159.5 162306.0 163982.2 111168.4 112122.4 112297.4 140684.9 144419.0 74801.6 76111.0 76219.0 92469.0 113035.3 8903.6 9921.9 10091.9 10100.8 10287.0 62730.4 63838.0 63901.4 64531.7 65929.3 89449.0 93682.5 97821.5 99105.0 99742.0 12912.0 13040.5 13145.6 13220.3 13303.4 14532.9 14832.0 14863.5 15545.8 15625.1 10994.6 13369.5 13563.2 13722.1 14131.4 12586.4 12676.4 12760.2 13262.4 13295.0 12872.7 13411.7 13439.9 13757.9 13854.8 10259.1 10267.5 10355.3 10684.7 10740.7 8180.0 9859.9 11498.9 11509.9 11544.4 06:39:13 INFO - PROCESS | 1658 | -------- Summary: end -------- 06:39:13 INFO - PROCESS | 1658 | 06:39:14 INFO - TEST-INFO | 1658: exit 0 06:39:14 INFO - TEST-OK | dromaeo_css | took 1118309ms 06:39:14 INFO - TEST-START | kraken 06:39:14 INFO - operating with platform_type : mac_ 06:39:14 INFO - Initialising browser for kraken test... 06:39:14 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmp5gIIOV/profile http://localhost:49271/getInfo.html) 06:39:16 INFO - PROCESS | 1665 | __metrics Screen width/height:1600/1200 06:39:16 INFO - PROCESS | 1665 | colorDepth:24 06:39:16 INFO - PROCESS | 1665 | Browser inner width/height: 1024/689 06:39:16 INFO - PROCESS | 1665 | __metrics 06:39:16 INFO - PROCESS | 1665 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 06:39:16 INFO - PROCESS | 1665 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 06:39:17 INFO - TEST-INFO | 1665: exit 0 06:39:17 INFO - Browser initialized. 06:39:17 INFO - Running cycle 1/1 for kraken test... 06:39:17 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/tmp5gIIOV/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 06:39:29 INFO - PROCESS | 1666 | RSS: Main: 233111552 06:39:29 INFO - PROCESS | 1666 | 06:39:32 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 06:39:33 INFO - PROCESS | 1666 | RSS: Main: 291106816 06:39:33 INFO - PROCESS | 1666 | 06:39:35 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 06:39:36 INFO - PROCESS | 1666 | RSS: Main: 285716480 06:39:36 INFO - PROCESS | 1666 | 06:39:40 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 06:39:40 INFO - PROCESS | 1666 | RSS: Main: 374939648 06:39:40 INFO - PROCESS | 1666 | 06:39:42 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 06:39:43 INFO - PROCESS | 1666 | RSS: Main: 280236032 06:39:43 INFO - PROCESS | 1666 | 06:39:45 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 06:39:46 INFO - PROCESS | 1666 | RSS: Main: 284106752 06:39:46 INFO - PROCESS | 1666 | 06:39:48 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 06:39:49 INFO - PROCESS | 1666 | RSS: Main: 321454080 06:39:49 INFO - PROCESS | 1666 | 06:39:52 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 06:39:52 INFO - PROCESS | 1666 | RSS: Main: 323424256 06:39:52 INFO - PROCESS | 1666 | 06:39:55 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 06:39:56 INFO - PROCESS | 1666 | RSS: Main: 323043328 06:39:56 INFO - PROCESS | 1666 | 06:39:57 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 06:39:58 INFO - PROCESS | 1666 | RSS: Main: 299024384 06:39:58 INFO - PROCESS | 1666 | 06:40:00 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 06:40:00 INFO - PROCESS | 1666 | RSS: Main: 303632384 06:40:00 INFO - PROCESS | 1666 | 06:40:03 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 06:40:03 INFO - PROCESS | 1666 | RSS: Main: 304254976 06:40:03 INFO - PROCESS | 1666 | 06:40:06 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 06:40:07 INFO - PROCESS | 1666 | RSS: Main: 319082496 06:40:07 INFO - PROCESS | 1666 | 06:40:10 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 06:40:11 INFO - PROCESS | 1666 | RSS: Main: 315174912 06:40:11 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 06:40:13 INFO - PROCESS | 1666 | RSS: Main: 358375424 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | __start_tp_report 06:40:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 06:40:13 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 06:40:13 INFO - PROCESS | 1666 | |i|pagename|runs| 06:40:13 INFO - PROCESS | 1666 | |0;ai-astar;113;113;117;112;112;111;112;111;112;120 06:40:13 INFO - PROCESS | 1666 | |1;audio-beat-detection;158;138;167;164;109;132;129;108;160;109 06:40:13 INFO - PROCESS | 1666 | |2;audio-dft;185;184;197;232;257;208;183;185;183;181 06:40:13 INFO - PROCESS | 1666 | |3;audio-fft;74;80;75;154;59;58;58;65;150;71 06:40:13 INFO - PROCESS | 1666 | |4;audio-oscillator;83;83;130;71;80;75;113;69;70;116 06:40:13 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;89;87;87;85;88;83;87;84;86;83 06:40:13 INFO - PROCESS | 1666 | |6;imaging-darkroom;95;95;95;95;94;96;95;95;106;137 06:40:13 INFO - PROCESS | 1666 | |7;imaging-desaturate;84;86;86;86;84;86;84;85;85;84 06:40:13 INFO - PROCESS | 1666 | |8;json-parse-financial;54;52;55;53;53;55;53;54;54;52 06:40:13 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;63;65;75;72;74;70;71;72;68;69 06:40:13 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;86;84;85;84;88;85;86;86;90;90 06:40:13 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;139;139;141;149;126;152;160;151;187;139 06:40:13 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;172;173;173;171;164;171;240;236;180;177 06:40:13 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;78;84;90;87;87;68;68;80;85;81 06:40:13 INFO - PROCESS | 1666 | __end_tp_report 06:40:13 INFO - PROCESS | 1666 | __start_cc_report 06:40:13 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,2101 06:40:13 INFO - PROCESS | 1666 | __end_cc_report 06:40:13 INFO - PROCESS | 1666 | __startTimestamp1473687613378__endTimestamp 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | ------- Summary: start ------- 06:40:13 INFO - PROCESS | 1666 | Number of tests: 14 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:113.30 Median:112.50 stddev:2.91 (2.6%) stddev-sans-first:3.08 06:40:13 INFO - PROCESS | 1666 | Values: 113.0 113.0 117.0 112.0 112.0 111.0 112.0 111.0 112.0 120.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:137.40 Median:148.00 stddev:23.80 (16.1%) stddev-sans-first:24.04 06:40:13 INFO - PROCESS | 1666 | Values: 158.0 138.0 167.0 164.0 109.0 132.0 129.0 108.0 160.0 109.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:199.50 Median:191.00 stddev:25.80 (13.5%) stddev-sans-first:26.82 06:40:13 INFO - PROCESS | 1666 | Values: 185.0 184.0 197.0 232.0 257.0 208.0 183.0 185.0 183.0 181.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:84.40 Median:68.00 stddev:36.45 (53.6%) stddev-sans-first:38.47 06:40:13 INFO - PROCESS | 1666 | Values: 74.0 80.0 75.0 154.0 59.0 58.0 58.0 65.0 150.0 71.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:89.00 Median:73.00 stddev:22.16 (30.4%) stddev-sans-first:23.40 06:40:13 INFO - PROCESS | 1666 | Values: 83.0 83.0 130.0 71.0 80.0 75.0 113.0 69.0 70.0 116.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:85.90 Median:87.00 stddev:2.08 (2.4%) stddev-sans-first:1.88 06:40:13 INFO - PROCESS | 1666 | Values: 89.0 87.0 87.0 85.0 88.0 83.0 87.0 84.0 86.0 83.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:100.30 Median:95.00 stddev:13.36 (14.1%) stddev-sans-first:14.03 06:40:13 INFO - PROCESS | 1666 | Values: 95.0 95.0 95.0 95.0 94.0 96.0 95.0 95.0 106.0 137.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:85.00 Median:85.50 stddev:0.94 (1.1%) stddev-sans-first:0.93 06:40:13 INFO - PROCESS | 1666 | Values: 84.0 86.0 86.0 86.0 84.0 86.0 84.0 85.0 85.0 84.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:53.50 Median:54.00 stddev:1.08 (2.0%) stddev-sans-first:1.13 06:40:13 INFO - PROCESS | 1666 | Values: 54.0 52.0 55.0 53.0 53.0 55.0 53.0 54.0 54.0 52.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:69.90 Median:71.50 stddev:3.78 (5.3%) stddev-sans-first:3.08 06:40:13 INFO - PROCESS | 1666 | Values: 63.0 65.0 75.0 72.0 74.0 70.0 71.0 72.0 68.0 69.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:86.40 Median:86.00 stddev:2.22 (2.6%) stddev-sans-first:2.35 06:40:13 INFO - PROCESS | 1666 | Values: 86.0 84.0 85.0 84.0 88.0 85.0 86.0 86.0 90.0 90.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:148.30 Median:150.00 stddev:16.55 (11.0%) stddev-sans-first:17.21 06:40:13 INFO - PROCESS | 1666 | Values: 139.0 139.0 141.0 149.0 126.0 152.0 160.0 151.0 187.0 139.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:185.70 Median:175.00 stddev:27.89 (15.9%) stddev-sans-first:29.14 06:40:13 INFO - PROCESS | 1666 | Values: 172.0 173.0 173.0 171.0 164.0 171.0 240.0 236.0 180.0 177.0 06:40:13 INFO - PROCESS | 1666 | 06:40:13 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.80 Median:84.50 stddev:7.64 (9.0%) stddev-sans-first:8.04 06:40:13 INFO - PROCESS | 1666 | Values: 78.0 84.0 90.0 87.0 87.0 68.0 68.0 80.0 85.0 81.0 06:40:13 INFO - PROCESS | 1666 | -------- Summary: end -------- 06:40:13 INFO - PROCESS | 1666 | 06:40:14 INFO - TEST-INFO | 1666: exit 0 06:40:14 INFO - TEST-OK | kraken | took 59920ms 06:40:14 INFO - SUITE-END | took 1178s 06:40:14 INFO - Completed test suite (00:19:38) 06:40:14 INFO - javascript benchmark 06:40:14 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2803.663560372755, 3345.2305969626987, 3364.0412482442525, 3386.3735208986354, 3436.2096717754916, 5561.277033985586, 5651.067573850315, 5693.743781529831, 5741.684207368593, 5780.566457515687, 5707.743151558179, 5726.284214473191, 5737.3688894221605, 5738.770449182028, 5757.654540727559, 5169.948300517003, 5196.948030519684, 5218.1650935850275, 5229.555487783543, 5267.3679158500845, 5270.868228294285, 5273.077211487994, 5276.657017293883, 5277.551408130314, 5277.656952298107, 5176.611754118438, 5199.116150254447, 5201.609879259052, 5249.42256351801, 5266.236395722637, 5265.0522905877115, 5298.15229563268, 5300.92048619271, 5317.069512835277, 5340.866478338034, 5033.471485494044, 5058.114829904752, 5122.92315615266, 5200.089984252741, 5225.608079394023, 5634.605577609569, 5732.971335143297, 5737.168110623978, 5748.683822389767, 5770.394108618619, 6242.906356404657, 6273.40402661751, 6275.905861412082, 6280.371962803729, 6299.244090709099, 6174.073888916699, 6185.690715464209, 6205.503559715257, 6287.559870809046, 6294.433500984887, 5779.450952159538, 5784.8843023139725, 5793.7972170974235, 5875.676837773878, 5885.882282354329, 6303.716332765037, 6310.684465776784, 6314.273858506239, 6324.430801227912, 6342.04869269604, 6031.547633927473, 6123.510119190454, 6176.3514830943, 6189.35011823761, 6193.845153871099, 6118.959776837859, 6128.075379514901, 6153.138560601519, 6176.320604733476, 6283.874322513616, 6263.906041409383, 6264.686765661699, 6265.96867015662, 6276.592021518678, 6287.528435367366, 6245.156903818017, 6282.308946015934, 6286.779962701284, 6291.433770960635, 6292.716827742763, 4892.584130348889, 4943.777530011158, 4943.777530011158, 4972.279019542146, 4987.4015118185325, 4432.623227025674, 4465.687401881837, 4491.618212451978, 4502.099580083996, 4509.842155524541, 5705.600607957404, 5750.482456578928, 5752.02215623345, 5760.51035661965, 5761.6254943429385, 4931.50684931504, 6099.218990344071, 6131.774283661951, 6283.0, 6285.685715714196, 4407.713498622579, 5738.971305143447, 5776.913346299809, 5794.8551286217335, 5821.53427725781, 5470.753816078286, 5485.4788795064405, 5486.91769623448, 5491.835244942658, 5501.587380946445, 6179.412955769195, 6260.467860231931, 6398.744050237939, 6414.358564143735, 6426.29310775805, 6227.19046523949, 6277.623342599459, 6298.244210694746, 6298.748050077946, 6309.274433440213, 6334.113224148715, 6350.301466838551, 6396.392342727433, 6399.0, 6407.327230640715, 6106.6946652668075, 6239.500839932885, 6251.437370636664, 6262.0, 6282.246130464374, 6173.290071641819, 6220.098085777628, 6228.688565571794, 6243.282022567281, 6309.211348581427, 6168.228971378578, 6176.629402235881, 6177.660228687465, 6179.134921110958, 6187.81436556904, 5410.78356865721, 6131.816045518642, 6173.32093469709, 6192.504599631929, 6209.720562574605, 5863.413658634103, 5873.559483038704, 5876.529877609695, 5915.704214789157, 5981.461668449861, 6100.572959892765, 6105.84735381619, 6142.447179753844, 6182.629042257479, 6197.287311959002, 5876.735546900485, 5880.794172203952, 6022.640834041694, 6029.849253768691, 6093.99449090895], "value": 5710.176827819437, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [440.9757463339515, 492.6206820748022, 525.1885836382788, 525.7660341148189, 557.1447827584657, 28914.132576022726, 31686.84156579217, 39948.40077398841, 41090.0, 41327.380089298684, 43075.569244307546, 43277.7836110819, 43295.567044329546, 43312.78343608278, 43417.34873976893, 9819.999999999982, 10018.59925602975, 10025.749356266078, 10034.498275086216, 10042.598296068149, 8756.343274254425, 8955.820883582324, 11898.881011189864, 12081.395930203498, 12107.878921210808, 12262.57081002165, 12346.629601111981, 12370.567030153947, 12375.752484950295, 12383.56657516987, 12207.75584488314, 12311.938440307786, 12312.26126432417, 12320.075994300374, 12329.93835030828, 12090.40056387872, 12191.390430478441, 12202.206856554385, 12216.389180540937, 12218.083643726755, 41012.17975640501, 41331.38002929959, 41422.79288603538, 41432.37851432231, 41458.756237312926, 3300.455424854896, 3372.865085396581, 3463.0649724574478, 3529.347070791912, 3725.590185079639, 25233.0, 25756.356091097874, 30663.540046899314, 31305.530417043763, 31891.0, 3209.422303985282, 3306.35526072416, 3335.7831740936763, 3417.2311229973307, 3421.8460169292443, 507596.0, 509090.09099089797, 511267.7746445087, 512641.0, 515983.0, 62043.68978155171, 62611.0, 62865.0, 63024.36975630277, 63104.05343919845, 64100.358996409435, 64120.0, 64213.11041003166, 64261.03608445877, 64281.35718642754, 16132.838671613368, 16333.02001879891, 16374.345026198818, 16374.345026199057, 16405.261763220682, 11494.482748276181, 11699.707507312385, 12640.683982900317, 12720.300383478998, 12753.043521735906, 15493.845061549466, 17269.39557115495, 17732.20205090774, 17743.911280443772, 18287.26850925948, 4611.7677973976, 5692.544596432275, 6585.275619681831, 6719.462443004548, 6736.709518763644, 911.7310393433953, 927.5408672706918, 980.0, 1052.5947510208625, 1067.295584913953, 29917.700822991927, 30175.698243017727, 30360.84819575888, 30580.35492122274, 30734.538981915288, 1036.6527213383602, 1059.9311044782219, 1070.9089727373262, 1083.7615724540588, 1097.7475180708482, 13002.739945201043, 15520.689586208438, 15541.912066155486, 15612.76580851288, 15981.760273595906, 662508.0, 666578.0, 667316.0, 667615.0, 668454.0, 49654.503454964986, 49832.50167498351, 57113.42886571164, 57358.7132064337, 57396.0, 1342.8657134286493, 1355.159800923414, 1386.1544457880684, 1394.9999999999798, 1395.741787769266, 26944.0, 30015.96169193952, 31523.684763152076, 31551.684483154873, 32419.513707294882, 51926.74036629793, 52520.47479525156, 52552.474475254756, 52581.211281831565, 52606.73696631492, 406045.0, 479115.0, 479453.0, 480051.0, 481140.0, 34357.0, 38521.844344669706, 40643.98390040272, 40907.59092409157, 40947.590524094376, 38822.4176637345, 46729.76635116803, 48139.277910830664, 48502.272465913746, 48608.0, 39117.022074447224, 39425.71030957954, 39624.0, 40652.59347406488, 41065.58934410618, 29262.0, 38614.42078368883, 39658.60341396549, 40038.39942400808, 40593.797031014656, 38283.61716382801, 39144.80427597844, 39194.60805392024, 39270.41094383644, 39293.60706393014, 30062.098137055924, 30201.0, 32871.52078156441, 34593.82703086468, 34744.13139671528, 25004.0, 25368.0, 25369.74630253748, 26039.218823435327, 26090.86954565215], "value": 20449.525960210638, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [327.16899076346084, 348.9005633394483, 365.43357795417097, 377.367908752839, 401.12954887893267, 15118.924405377971, 15130.0, 16350.100744459065, 20886.37340879772, 21172.788272117312, 20042.699359509617, 20499.69250461244, 21178.99999999996, 21217.151313947463, 21380.679289810665, 5000.574951129159, 5911.467967882901, 6163.63018218906, 6168.506519478454, 6169.784057557987, 6000.639961602296, 6041.667708276043, 6087.20866287382, 6103.786367477139, 6120.387961203888, 5793.942060579404, 6047.969760151215, 6052.606580572248, 6067.817965461043, 6076.787312444066, 6033.69831508425, 6075.48358389533, 6081.239845019372, 6089.421504957022, 6093.939060609426, 5039.72281524516, 5219.530242278213, 5270.683758974474, 5285.207218917155, 5285.2600635911, 26247.0, 26417.207483775517, 26421.0, 26480.337991550365, 26753.331166720793, 2209.215728416405, 2346.9295921122393, 2492.1775813981344, 2574.124797568817, 2808.775297976157, 13046.739065218737, 14622.0, 15720.449784257611, 16433.753493697604, 16626.418075367303, 1690.847823695861, 1844.437446578793, 1849.2140840142886, 1861.4508719927653, 1876.3714155757837, 118153.0, 123201.0, 126890.0, 128827.35586322102, 128907.99999999907, 8315.04376996641, 9294.628214871329, 9757.90242097584, 9859.211263098934, 10106.797864042825, 14062.648433789238, 14088.647783805487, 14106.294685265695, 14113.0, 14116.85883141176, 8358.289545388589, 8537.530435825965, 8539.06070332263, 8575.699850505327, 8692.608832602547, 8535.402521823537, 8555.31557475413, 8595.742127736177, 8696.869546956801, 8702.260307873901, 5723.969685456575, 6722.394984451423, 6728.6635668216395, 6752.5948443093575, 6765.830854228684, 1518.883620538903, 1525.519461369665, 1619.3078233246285, 1682.8906121102088, 1864.7855496617792, 836.6277006732067, 1002.9197664186847, 1013.2299452416216, 1015.7866847961863, 1059.3855563772993, 21457.78542214558, 22788.4302892429, 22937.541249174923, 22951.655725164135, 23006.309810705705, 874.8381549413378, 913.8537833946536, 965.7247684409909, 1009.6869970309227, 1014.8274793285159, 9332.860007099898, 10657.57369705203, 11932.136287290989, 12180.390980450942, 12189.75620487603, 604341.0, 605817.0, 618082.0, 620409.0, 621739.0, 82605.58697206476, 90425.1914961697, 92264.0773592269, 112535.37040184723, 124069.37965310297, 967.1923943507251, 975.7804493988938, 1081.1620993729764, 1086.4513420722642, 1087.7661302819931, 17393.913030434767, 20246.59506809885, 20711.482212944797, 20863.79136208679, 20977.0, 37842.81078594589, 40523.666465051225, 54549.72725136349, 55555.0, 56555.0, 79538.80691789744, 79909.60045199736, 80320.0, 81085.0, 81104.0, 7814.53112813233, 7839.9216007839195, 7867.803304917423, 7989.320907722677, 8213.630386632496, 13099.083064185797, 13166.341682915625, 13313.001524885865, 13337.933310333387, 13348.265845378597, 9132.543372831198, 11524.481398336928, 11626.53493860236, 11648.825267621161, 11685.240459370114, 8821.867671984797, 10371.807242166946, 11225.887741122484, 11384.829227561428, 11677.416129193676, 9853.261005424765, 11645.184837061324, 11659.125565582784, 11887.465064071965, 11919.46362413676, 7016.614086225102, 7287.927120728725, 7294.598797066, 7465.738699145504, 8250.298724608476, 8200.835983280182, 10742.51658675377, 11247.606333778278, 11252.718682033015, 11254.549818007516], "value": 9675.142989403404, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1540.02978123782, 1572.536101849954, 1789.4810504953564, 1843.8340549350555, 1940.55367265529, 8591.48451092934, 9164.541772911345, 9164.991850896396, 9167.174954254117, 9167.266618670506, 7984.4810087344285, 8169.0, 8190.9590452047805, 8719.782005449866, 8914.77713057174, 2394.724606670229, 2465.0139944022444, 2467.716212635552, 2468.049800826686, 2471.283327834926, 2391.1707543729062, 2452.6566280720626, 2459.3359792855917, 2466.7656572625574, 2467.111839737693, 2423.612222044473, 2443.1815341860497, 2456.3981824452944, 2464.2360868130845, 2464.3962229253857, 2419.63705444183, 2430.659707640914, 2430.7812296893276, 2433.4403087289847, 2435.0, 2428.514297140579, 2429.878506074689, 2430.805535557151, 2434.914777982762, 2435.695538057743, 9013.549322533847, 9024.593893274752, 9120.407173533766, 9126.087391260888, 9126.315526335551, 5359.651622644555, 5389.83830485086, 5491.368492623359, 5495.120780675113, 5505.146702261156, 3556.270964452281, 3677.4667673187505, 3698.278835627054, 3716.6655001050035, 3783.0920579061103, 1071.8338657508098, 1098.6868742408376, 1116.3636727065496, 1132.3432409202642, 1136.204656740277, 11955.462004209832, 12051.21667091645, 12053.975412089983, 12079.939600301943, 12105.213161144586, 4731.739754313477, 4740.166466618139, 4825.734584597811, 4931.18635425158, 4954.083494553519, 10224.233182511342, 10229.488525573692, 10239.0, 10252.64115755945, 10252.846207306897, 5495.093309603951, 5496.917546236809, 5498.477644623755, 5499.615026948075, 5500.559955203574, 4504.617107545895, 4598.241290187082, 4932.605391568738, 5024.949750502448, 5036.093503169464, 3979.980100099539, 3982.522097348337, 4087.2847251730827, 4096.508418989681, 4123.773192474443, 1307.4704744578462, 1320.6434262749003, 1324.8211491448724, 1327.4292054416694, 1333.3266700316278, 790.8655528560158, 860.4278155026838, 880.067128843428, 895.2077411490748, 905.524599585223, 5783.537317014713, 5840.737166827503, 5936.050231962951, 5957.314908785458, 6112.847178820565, 434.2270758050675, 437.2108344438319, 456.4682145300754, 460.5187578979974, 461.5292401750263, 3876.6511014008875, 3914.530256369254, 3973.0, 4069.593040695907, 4082.1631565528996, 10554.155667546578, 11024.724381890517, 11060.557577696964, 11080.390578518098, 11106.500207490682, 11018.228723989243, 11028.944855275673, 11030.889691103146, 11041.889581104086, 11056.226064175431, 8307.418480706292, 8377.45546539473, 8386.87419688705, 8394.916050839414, 8396.160383961555, 5627.549796016391, 5723.0, 5890.764369425175, 5934.43622855828, 5963.28440587115, 10052.894181639867, 10256.0, 10321.948390258001, 10334.689959301233, 10350.998210518865, 10375.014373634493, 10421.0, 10469.26715129964, 10511.211659125749, 10531.842022369518, 7019.613921234177, 7224.638768061681, 7235.927640723526, 7662.616869156408, 7711.0, 8251.133630968661, 8362.121977192308, 8541.572921354033, 8662.91337086621, 8673.436226645248, 6620.470362370903, 7562.546247225184, 7681.347085497795, 7721.652525636472, 7767.572783496962, 7455.739049133471, 7460.142083660448, 7504.3246107850555, 7537.849243015218, 7689.615519223905, 6906.37842594169, 7033.824154396181, 7036.0, 7039.859202816017, 7040.260772618802, 4904.8773780655765, 5029.170186919117, 5034.395872495177, 5098.1842905135, 5170.560502357192, 5041.193409054534, 5081.364829396325, 5191.532762051434, 5236.423993360651, 5313.654612450178], "value": 4603.803540975746, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [300.5596800686993, 301.8701958157991, 307.74918441470203, 312.6154829559642, 314.5344889563448, 4397.912041759167, 4557.202489564325, 5330.600204984632, 5336.811907118083, 5351.304330437042, 4345.3264743964655, 4868.805247790084, 4894.339264199332, 5049.419316778572, 5197.480251974801, 3381.2392211752403, 3936.877989772908, 4006.298897692907, 4011.913804821491, 4035.2333056719276, 3968.0675041365257, 3974.841006359742, 4001.779457265533, 4020.3165461871554, 4053.4730485037053, 3395.473701576247, 3806.2681479926705, 3961.8019099045077, 3979.363301871701, 4028.5165780106427, 3333.250018745775, 3533.2580158166816, 3999.7000224982953, 4014.7992600369867, 4019.9598004019876, 2923.7866285491496, 3484.337975784593, 3487.0646791030968, 3494.143934736006, 3508.3162679186753, 5056.9633225188745, 5091.9236211456855, 5120.718360490171, 5135.0500157470615, 5222.608304377149, 4094.365373367103, 4308.82764689412, 4541.795619197144, 4616.460946479457, 4642.210824159901, 4581.61056310214, 4740.241561350202, 5402.8919421611345, 5418.756155972991, 5473.480019398151, 3811.752236104672, 3827.8660246891227, 3844.8654297099465, 3862.991244552845, 3880.2239552089413, 4629.606483448911, 5052.115879721071, 5334.306540149794, 5610.523105536035, 5678.4605462480995, 3894.5131858517684, 3909.179072394772, 3909.5504017038406, 3916.2559113768293, 3949.0, 3937.488126543531, 3972.4041393791163, 3984.2230765000545, 4036.8990775230855, 4049.493813273341, 3906.511686039245, 4082.2651922653613, 4584.899624090194, 4647.279671650899, 4666.996595731866, 4560.384348113029, 4764.356811830427, 5138.58891288696, 5435.119386725042, 5483.314585676791, 1324.8012798080172, 1345.4281930179634, 1358.8369395672385, 1359.232033900843, 1361.1900918953206, 853.9573021348908, 896.8161526886972, 900.5182227508252, 901.6880438961296, 903.6701603914524, 4243.872683819491, 4529.456465224194, 4954.405471343462, 5049.343585333883, 5054.620903432257, 4599.2292967207195, 4753.809847606126, 4837.371971021734, 5411.702356370437, 5551.946429463779, 4127.587241275908, 4336.73979561221, 4499.032707967803, 4689.6717229793585, 5082.669626474268, 2595.2733234694315, 2984.2986898078934, 2987.536931775578, 2993.383572870669, 3027.7880548361845, 5264.736763161826, 5794.159846822187, 5875.529957603381, 5886.0, 5889.793857214977, 4666.346711460398, 4756.453007904066, 5327.866803329948, 5731.882282954867, 5757.424257574209, 4838.532348502289, 5328.36059672834, 5739.0, 5791.797287094932, 5839.328477225174, 5030.76746197184, 5063.0, 5094.36980166358, 5587.3015873015875, 5592.552595792408, 5141.331626888481, 5325.840224793263, 5544.7782088716, 5597.63615365, 5691.089425691869, 5216.713080780593, 5870.471657550841, 5878.442877348367, 5905.527557795452, 5959.582829202088, 3602.0, 3796.804006737869, 3882.2429626222615, 3955.268275368951, 3958.18857134281, 4939.901201975868, 5651.463111004448, 5651.717414129195, 5680.32430433015, 5694.487496125369, 4814.257955913353, 5525.226468294522, 5542.556595472433, 5587.776488940397, 5599.8880022400135, 4805.192897948444, 5202.921956170585, 5384.919226211531, 5448.618596698193, 5480.506754392124, 4666.300054991779, 5118.411382691038, 5121.487851214849, 5377.596680249075, 5488.396276409671, 4653.697509661861, 5109.616778741683, 5385.54222891043, 5394.487523685244, 5515.806946756844, 4284.764337961317, 4306.806193721352, 5132.153194722829, 5224.164133738584, 5448.073827449422], "value": 3979.039729710913, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [592.0515831160819, 788.6096382290767, 796.6016991504251, 810.5663470043528, 811.9025716913972, 15309.770353444694, 16590.83409165911, 16624.58438539037, 16657.916710416444, 16729.99620022798, 14985.0, 15124.773128403083, 15129.092254464713, 15140.621484462894, 15156.31796569157, 665.597313625257, 685.5749435350065, 826.6858593734372, 848.4485084694936, 870.8911386076741, 845.4589062999654, 859.5014891362995, 860.2086080805642, 860.5826174305457, 862.5126803356084, 926.8934072581671, 928.9489078100702, 929.0987741890356, 929.1034152043277, 929.6513807322254, 703.0789665538161, 727.574368994139, 881.6253092435753, 920.2224120618067, 920.5949382271779, 669.2404121322286, 681.6148875885159, 682.3551743602297, 682.9248782633906, 683.9911131081665, 15169.772453413208, 15217.61955951099, 15226.771598425921, 15261.61845953849, 15267.38930442781, 14005.229712365815, 14105.0, 14110.50613228532, 14144.71710565783, 14378.928105359406, 12658.303793291365, 12757.23456592598, 12834.871651283462, 12941.870581294255, 13335.733285334336, 3763.303788799081, 4452.332150177467, 4566.977165114153, 4594.151720565626, 4634.39752832133, 120872.39563802125, 126092.0, 126251.0, 127888.12950899184, 128205.35897320454, 10896.891031089588, 11116.221864469571, 11167.436558881764, 11296.667483092508, 11602.825957610643, 22838.200662976717, 22839.20062797794, 22849.971751271234, 22879.084836606682, 22901.19845805389, 7697.807554811175, 7764.06831180262, 7847.607619619111, 7986.480878742863, 8176.059753128347, 4413.646908247332, 4471.776411179428, 4482.596566309048, 4551.180787458223, 4618.907621847544, 3408.1649995750704, 3553.466979953028, 3558.377283975294, 3560.9821950890077, 3561.0, 655.5902560899438, 669.926308106108, 670.5138774388529, 673.4275865514273, 679.8062552172706, 438.8222769778245, 481.9132556139929, 599.4045781764947, 622.950163986889, 629.4743888852859, 8706.39055266138, 8952.328575356874, 8963.686270980485, 8978.889960859193, 8986.86519702205, 555.4251349852883, 555.4945000050016, 559.3455656881458, 589.5990726306068, 625.6465097220147, 3711.406175011985, 3994.849845147378, 4070.3690927906223, 4189.685773566995, 4314.848980285675, 103227.0, 103462.0, 104159.4792026035, 162306.0, 163982.18008910117, 111168.44415778031, 112122.43938780254, 112297.43851280691, 140684.85548439523, 144419.0, 74801.62599186969, 76110.9999999989, 76219.0, 92469.0, 113035.34823258805, 8903.554822258991, 9921.851172232278, 10091.949540252546, 10100.797984040424, 10287.022732840369, 62730.372696272454, 63838.0, 63901.36098638954, 64531.677341612994, 65929.34070659232, 89449.0, 93682.53158734435, 97821.53267701133, 99105.0, 99742.0, 12911.954485533848, 13040.54358097462, 13145.60563183106, 13220.272884991418, 13303.370339619296, 14532.927335363256, 14832.0, 14863.479778207711, 15545.766813498032, 15625.140617265704, 10994.560217591526, 13369.53206637763, 13563.186208827501, 13722.108062975874, 14131.434742610592, 12586.370681466073, 12676.366181690693, 12760.234385936874, 13262.403191856583, 13295.0, 12872.678183045498, 13411.664708381979, 13439.865601343861, 13757.936309553646, 13854.792178117135, 10259.076683098558, 10267.463408350075, 10355.326903751233, 10684.679459616224, 10740.677779666623, 8179.953019535604, 9859.943417540586, 11498.94250528742, 11509.942450287695, 11544.422778861192], "value": 7755.582386302734, "unit": "score"}], "name": "dromaeo_css", "value": 7372.101540440221, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [113.0, 113.0, 117.0, 112.0, 112.0, 111.0, 112.0, 111.0, 112.0, 120.0], "value": 113.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [158.0, 138.0, 167.0, 164.0, 109.0, 132.0, 129.0, 108.0, 160.0, 109.0], "value": 137.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 184.0, 197.0, 232.0, 257.0, 208.0, 183.0, 185.0, 183.0, 181.0], "value": 199.5, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 80.0, 75.0, 154.0, 59.0, 58.0, 58.0, 65.0, 150.0, 71.0], "value": 84.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 130.0, 71.0, 80.0, 75.0, 113.0, 69.0, 70.0, 116.0], "value": 89.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 87.0, 87.0, 85.0, 88.0, 83.0, 87.0, 84.0, 86.0, 83.0], "value": 85.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 95.0, 95.0, 95.0, 94.0, 96.0, 95.0, 95.0, 106.0, 137.0], "value": 100.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 86.0, 86.0, 84.0, 86.0, 84.0, 85.0, 85.0, 84.0], "value": 85.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 52.0, 55.0, 53.0, 53.0, 55.0, 53.0, 54.0, 54.0, 52.0], "value": 53.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [63.0, 65.0, 75.0, 72.0, 74.0, 70.0, 71.0, 72.0, 68.0, 69.0], "value": 69.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 84.0, 85.0, 84.0, 88.0, 85.0, 86.0, 86.0, 90.0, 90.0], "value": 86.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 139.0, 141.0, 149.0, 126.0, 152.0, 160.0, 151.0, 187.0, 139.0], "value": 148.3, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 173.0, 173.0, 171.0, 164.0, 171.0, 240.0, 236.0, 180.0, 177.0], "value": 185.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [78.0, 84.0, 90.0, 87.0, 87.0, 68.0, 68.0, 80.0, 85.0, 81.0], "value": 80.8, "unit": "score"}], "name": "kraken", "value": 1519.3999999999999, "alertThreshold": 2.0}]} 06:40:14 INFO - Return code: 0 06:40:14 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 06:40:14 INFO - # TBPL SUCCESS # 06:40:14 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 06:40:14 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 06:40:14 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 06:40:14 INFO - 2016-09-12 06:40:14.696 screenresolution[1670:9952] starting screenresolution argv=screenresolution get 06:40:14 INFO - 2016-09-12 06:40:14.699 screenresolution[1670:9952] Display 0: 1600x1200x32@60 06:40:14 INFO - 2016-09-12 06:40:14.705 screenresolution[1671:9955] starting screenresolution argv=screenresolution list 06:40:14 INFO - Available Modes on Display 0 06:40:14 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 06:40:14 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 06:40:14 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 06:40:14 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 06:40:14 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 06:40:14 INFO - Intel Iris: 06:40:14 INFO - Chipset Model: Intel Iris 06:40:14 INFO - Type: GPU 06:40:14 INFO - Bus: Built-In 06:40:14 INFO - VRAM (Dynamic, Max): 1536 MB 06:40:14 INFO - Vendor: Intel (0x8086) 06:40:14 INFO - Device ID: 0x0a2e 06:40:14 INFO - Revision ID: 0x0009 06:40:14 INFO - Displays: 06:40:14 INFO - Display: 06:40:14 INFO - Resolution: 1600 x 1200 @ 60 Hz 06:40:14 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 06:40:14 INFO - Display Serial Number: 1600x1200 60 06:40:14 INFO - Main Display: Yes 06:40:14 INFO - Mirror: Off 06:40:14 INFO - Online: Yes 06:40:14 INFO - Rotation: Supported 06:40:14 INFO - Return code: 0 06:40:14 INFO - Running post-action listener: _resource_record_post_action 06:40:14 INFO - [mozharness: 2016-09-12 13:40:14.946041Z] Finished run-tests step (success) 06:40:14 INFO - Running post-run listener: _resource_record_post_run 06:40:15 INFO - Total resource usage - Wall time: 1213s; CPU: 53.0%; Read bytes: 36652032; Write bytes: 703342592; Read time: 386; Write time: 1883 06:40:15 INFO - TinderboxPrint: CPU usage
53.3% 06:40:15 INFO - TinderboxPrint: I/O read bytes / time
36,652,032 / 386 06:40:15 INFO - TinderboxPrint: I/O write bytes / time
703,342,592 / 1,883 06:40:15 INFO - TinderboxPrint: CPU idle
2,264.3 (46.7%) 06:40:15 INFO - TinderboxPrint: CPU system
967.0 (19.9%) 06:40:15 INFO - TinderboxPrint: CPU user
1,622.3 (33.4%) 06:40:15 INFO - TinderboxPrint: Swap in / out
442,023,936 / 0 06:40:15 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 401787392; Write bytes: 391317504; Read time: 29881; Write time: 685 06:40:15 INFO - run-tests - Wall time: 1180s; CPU: 53.0%; Read bytes: 29547520; Write bytes: 293326848; Read time: 298; Write time: 1157 06:40:15 INFO - Running post-run listener: _upload_blobber_files 06:40:15 INFO - Blob upload gear active. 06:40:15 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 06:40:15 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 06:40:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 06:40:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 06:40:15 INFO - (blobuploader) - INFO - Open directory for files ... 06:40:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 06:40:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:17 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 06:40:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:17 INFO - (blobuploader) - INFO - Done attempting. 06:40:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 06:40:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 06:40:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:18 INFO - (blobuploader) - INFO - Done attempting. 06:40:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 06:40:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 06:40:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 06:40:20 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 06:40:20 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 06:40:20 INFO - (blobuploader) - INFO - Done attempting. 06:40:20 INFO - (blobuploader) - INFO - Iteration through files over. 06:40:20 INFO - Return code: 0 06:40:20 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 06:40:20 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 06:40:20 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab1cf984df6ef31090086f840f691e312388cc3adf4cb13883c7eaa92bb515817d9bf325148bf3d44133617b5eeb761f912220261112f8d18ce306849849f0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57fe47331b8105f35f0436603d015617a37cdbdb6764f3d5c4b14a71c276f2e4b9cd6d9d51573bde050f6f6c549b94d1b3519fab2f07a20922db593631aaa280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ed5162002a337cfedec06eaac00a033fac2b7423929902fce17ab329cc898a01698919ef97918f35f4f21fcbe9c7cbe69568883633e606bd0702ca56de4cc5e"} 06:40:20 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 06:40:20 INFO - Writing to file /builds/slave/test/properties/blobber_files 06:40:20 INFO - Contents: 06:40:20 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab1cf984df6ef31090086f840f691e312388cc3adf4cb13883c7eaa92bb515817d9bf325148bf3d44133617b5eeb761f912220261112f8d18ce306849849f0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57fe47331b8105f35f0436603d015617a37cdbdb6764f3d5c4b14a71c276f2e4b9cd6d9d51573bde050f6f6c549b94d1b3519fab2f07a20922db593631aaa280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ed5162002a337cfedec06eaac00a033fac2b7423929902fce17ab329cc898a01698919ef97918f35f4f21fcbe9c7cbe69568883633e606bd0702ca56de4cc5e"} 06:40:20 INFO - Running post-run listener: copy_logs_to_upload_dir 06:40:20 INFO - Copying logs to upload dir... 06:40:20 INFO - mkdir: /builds/slave/test/build/upload/logs 06:40:20 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.327760 ========= master_lag: 0.16 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-09-12 06:40:20.682258) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 06:40:20.689005) ========= 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.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab1cf984df6ef31090086f840f691e312388cc3adf4cb13883c7eaa92bb515817d9bf325148bf3d44133617b5eeb761f912220261112f8d18ce306849849f0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57fe47331b8105f35f0436603d015617a37cdbdb6764f3d5c4b14a71c276f2e4b9cd6d9d51573bde050f6f6c549b94d1b3519fab2f07a20922db593631aaa280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ed5162002a337cfedec06eaac00a033fac2b7423929902fce17ab329cc898a01698919ef97918f35f4f21fcbe9c7cbe69568883633e606bd0702ca56de4cc5e"} build_url:https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011085 build_url: 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ab1cf984df6ef31090086f840f691e312388cc3adf4cb13883c7eaa92bb515817d9bf325148bf3d44133617b5eeb761f912220261112f8d18ce306849849f0a7", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/57fe47331b8105f35f0436603d015617a37cdbdb6764f3d5c4b14a71c276f2e4b9cd6d9d51573bde050f6f6c549b94d1b3519fab2f07a20922db593631aaa280", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/1ed5162002a337cfedec06eaac00a033fac2b7423929902fce17ab329cc898a01698919ef97918f35f4f21fcbe9c7cbe69568883633e606bd0702ca56de4cc5e"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 06:40:20.722637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:40:20.722963) ========= 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.LF4WMkKz7y/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.jRxURuznX8/Listeners TMPDIR=/var/folders/p1/rk75f7qj4pncvncgdvpj45_h00000w/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.005334 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 06:40:20.775374) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 06:40:20.775702) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 06:40:20.776020) ========= ========= Total master_lag: 0.29 =========