builder: fx-team_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0195 starttime: 1473269769.02 results: success (0) buildid: 20160907082619 builduid: a33b423326734f90857c8fc26351969f revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.018913) ========= master: http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.019349) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.019622) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.045049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.045345) ========= 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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-07 10:36:09-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 89.8M=0s 2016-09-07 10:36:09 (89.8 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.128323 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.196701) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.197023) ========= 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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.028475 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:09.246547) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:36:09.246898) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 95acb9299fafdc69463c49860caf367e4fbcc8e3 --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 95acb9299fafdc69463c49860caf367e4fbcc8e3 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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-07 10:36:09,322 truncating revision to first 12 chars 2016-09-07 10:36:09,322 Setting DEBUG logging. 2016-09-07 10:36:09,322 attempt 1/10 2016-09-07 10:36:09,322 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/95acb9299faf?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 10:36:10,913 unpacking tar archive at: fx-team-95acb9299faf/testing/mozharness/ program finished with exit code 0 elapsedTime=1.860973 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:36:11.131781) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:11.132145) ========= script_repo_revision: 95acb9299fafdc69463c49860caf367e4fbcc8e3 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:11.132544) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:11.132848) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:36:11.151997) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-07 10:36:11.152377) ========= /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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:11 INFO - MultiFileLogger online at 20160907 10:36:11 in /builds/slave/test 10:36:11 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 10:36:11 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:36:11 INFO - {'append_to_log': False, 10:36:11 INFO - 'base_work_dir': '/builds/slave/test', 10:36:11 INFO - 'blob_upload_branch': 'Fx-Team', 10:36:11 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:36:11 INFO - 'branch': 'Fx-Team', 10:36:11 INFO - 'buildbot_json_path': 'buildprops.json', 10:36:11 INFO - 'config_files': ('talos/mac_config.py',), 10:36:11 INFO - 'default_actions': ('clobber', 10:36:11 INFO - 'read-buildbot-config', 10:36:11 INFO - 'download-and-extract', 10:36:11 INFO - 'populate-webroot', 10:36:11 INFO - 'create-virtualenv', 10:36:11 INFO - 'install', 10:36:11 INFO - 'run-tests'), 10:36:11 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:36:11 INFO - 'download_minidump_stackwalk': True, 10:36:11 INFO - 'download_symbols': 'ondemand', 10:36:11 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:36:11 INFO - 'tooltool.py': '/tools/tooltool.py', 10:36:11 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:36:11 INFO - '/tools/misc-python/virtualenv.py')}, 10:36:11 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:36:11 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:36:11 INFO - 'installer_path': 'installer.exe', 10:36:11 INFO - 'log_level': 'info', 10:36:11 INFO - 'log_name': 'talos', 10:36:11 INFO - 'log_to_console': True, 10:36:11 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:36:11 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:36:11 INFO - 'opt_config_files': (), 10:36:11 INFO - 'pip_index': False, 10:36:11 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:36:11 INFO - 'cmd': ('bash', 10:36:11 INFO - '-c', 10:36:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:36:11 INFO - 'enabled': True, 10:36:11 INFO - 'halt_on_failure': False, 10:36:11 INFO - 'name': 'check_screen_resolution'},), 10:36:11 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:36:11 INFO - 'cmd': ('bash', 10:36:11 INFO - '-c', 10:36:11 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:36:11 INFO - 'enabled': True, 10:36:11 INFO - 'halt_on_failure': False, 10:36:11 INFO - 'name': 'check_screen_resolution'},), 10:36:11 INFO - 'run_cmd_checks_enabled': True, 10:36:11 INFO - 'sps_profile': False, 10:36:11 INFO - 'sps_profile_interval': 0, 10:36:11 INFO - 'suite': 'dromaeojs', 10:36:11 INFO - 'system_bits': '32', 10:36:11 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:36:11 INFO - 'title': 't-yosemite-r7-0195', 10:36:11 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:36:11 INFO - 'use_talos_json': True, 10:36:11 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:36:11 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:36:11 INFO - 'work_dir': 'build'} 10:36:11 INFO - [mozharness: 2016-09-07 17:36:11.349395Z] Running clobber step. 10:36:11 INFO - Running pre-action listener: _resource_record_pre_action 10:36:11 INFO - Running main action method: clobber 10:36:11 INFO - rmtree: /builds/slave/test/build 10:36:11 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:36:13 INFO - Running post-action listener: _resource_record_post_action 10:36:13 INFO - [mozharness: 2016-09-07 17:36:13.543043Z] Finished clobber step (success) 10:36:13 INFO - [mozharness: 2016-09-07 17:36:13.543175Z] Running read-buildbot-config step. 10:36:13 INFO - Running pre-action listener: _resource_record_pre_action 10:36:13 INFO - Running main action method: read_buildbot_config 10:36:13 INFO - Using buildbot properties: 10:36:13 INFO - { 10:36:13 INFO - "project": "", 10:36:13 INFO - "product": "firefox", 10:36:13 INFO - "script_repo_revision": "production", 10:36:13 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs", 10:36:13 INFO - "repository": "", 10:36:13 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs", 10:36:13 INFO - "buildid": "20160907082619", 10:36:13 INFO - "pgo_build": "False", 10:36:13 INFO - "basedir": "/builds/slave/test", 10:36:13 INFO - "buildnumber": 145, 10:36:13 INFO - "slavename": "t-yosemite-r7-0195", 10:36:13 INFO - "master": "http://buildbot-master132.bb.releng.scl3.mozilla.com:8201/", 10:36:13 INFO - "platform": "yosemite_r7", 10:36:13 INFO - "scheduler": "tests-fx-team-macosx64-talos", 10:36:13 INFO - "branch": "fx-team", 10:36:13 INFO - "repo_path": "integration/fx-team", 10:36:13 INFO - "revision": "95acb9299fafdc69463c49860caf367e4fbcc8e3", 10:36:13 INFO - "stage_platform": "macosx64", 10:36:13 INFO - "builduid": "a33b423326734f90857c8fc26351969f", 10:36:13 INFO - "slavebuilddir": "test" 10:36:13 INFO - } 10:36:13 INFO - Found installer url https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:36:13 INFO - Running post-action listener: _resource_record_post_action 10:36:13 INFO - [mozharness: 2016-09-07 17:36:13.546704Z] Finished read-buildbot-config step (success) 10:36:13 INFO - [mozharness: 2016-09-07 17:36:13.546819Z] Running download-and-extract step. 10:36:13 INFO - Running pre-action listener: _resource_record_pre_action 10:36:13 INFO - Running main action method: download_and_extract 10:36:13 INFO - mkdir: /builds/slave/test/build/tests 10:36:13 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')]} 10:36:13 INFO - trying https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:36:13 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/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 10:36:13 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/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 10:36:14 INFO - Downloaded 1199 bytes. 10:36:14 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:36:14 INFO - Using the following test package requirements: 10:36:14 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:36:14 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:36:14 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:36:14 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'jsshell-mac.zip'], 10:36:14 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:36:14 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:36:14 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:36:14 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:36:14 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:36:14 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:36:14 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:36:14 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:36:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:36:15 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:36:22 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 10:36:22 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:36:22 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:36:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:36:30 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:36:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:31 INFO - trying https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:36:31 INFO - Downloading https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:36:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/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 10:36:34 INFO - Downloaded 111548825 bytes. 10:36:34 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:36:34 INFO - mkdir: /builds/slave/test/properties 10:36:34 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:36:34 INFO - Writing to file /builds/slave/test/properties/build_url 10:36:34 INFO - Contents: 10:36:34 INFO - build_url:https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:36:35 INFO - Running post-action listener: _resource_record_post_action 10:36:35 INFO - Running post-action listener: set_extra_try_arguments 10:36:35 INFO - [mozharness: 2016-09-07 17:36:35.004299Z] Finished download-and-extract step (success) 10:36:35 INFO - [mozharness: 2016-09-07 17:36:35.004394Z] Running populate-webroot step. 10:36:35 INFO - Running pre-action listener: _resource_record_pre_action 10:36:35 INFO - Running main action method: populate_webroot 10:36:35 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:36:35 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:36:35 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:36:35 INFO - '--tppagecycles', 10:36:35 INFO - '7'], 10:36:35 INFO - 'tests': ['tsvgm']}}, 10:36:35 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:36:35 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:36:35 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:36:35 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:36:35 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:36:35 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:36:35 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['damp', 'tps']}, 10:36:35 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'tests': ['damp', 'tps']}, 10:36:35 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['dromaeo_dom']}, 10:36:35 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:36:35 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['basic_compositor_video']}, 10:36:35 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:36:35 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'other-e10s': {'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['a11yr', 10:36:35 INFO - 'ts_paint', 10:36:35 INFO - 'tpaint', 10:36:35 INFO - 'sessionrestore', 10:36:35 INFO - 'sessionrestore_no_auto_restore', 10:36:35 INFO - 'tabpaint']}, 10:36:35 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['tsvgx', 10:36:35 INFO - 'tsvgr_opacity', 10:36:35 INFO - 'tart', 10:36:35 INFO - 'tscrollx', 10:36:35 INFO - 'cart']}, 10:36:35 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:36:35 INFO - 'tsvgr_opacity', 10:36:35 INFO - 'tart', 10:36:35 INFO - 'tscrollx', 10:36:35 INFO - 'cart']}, 10:36:35 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'talos_options': ['--disable-e10s'], 10:36:35 INFO - 'tests': ['tp5o']}, 10:36:35 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'tests': ['tp5o']}, 10:36:35 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'talos_options': ['--disable-e10s', 10:36:35 INFO - '--xperf_path', 10:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:36:35 INFO - 'tests': ['tp5n']}, 10:36:35 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:36:35 INFO - 'talos_options': ['--xperf_path', 10:36:35 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:36:35 INFO - 'tests': ['tp5n']}}, 10:36:35 INFO - 'talos.zip': {'path': '', 10:36:35 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:36:35 INFO - Running post-action listener: _resource_record_post_action 10:36:35 INFO - [mozharness: 2016-09-07 17:36:35.023118Z] Finished populate-webroot step (success) 10:36:35 INFO - [mozharness: 2016-09-07 17:36:35.023248Z] Running create-virtualenv step. 10:36:35 INFO - Running pre-action listener: _resource_record_pre_action 10:36:35 INFO - Running main action method: create_virtualenv 10:36:35 INFO - Creating virtualenv /builds/slave/test/build/venv 10:36:35 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 10:36:35 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:36:35 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:36:35 INFO - Using real prefix '/tools/python27' 10:36:35 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:36:35 INFO - Installing distribute.............................................................................................................................................................................................done. 10:36:38 INFO - Installing pip.................done. 10:36:38 INFO - Return code: 0 10:36:38 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36: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', 'pip>=1.5'] in /builds/slave/test/build 10:36: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 pip>=1.5 10:36:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:38 INFO - 'HOME': '/Users/cltbld', 10:36:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:38 INFO - 'LOGNAME': 'cltbld', 10:36:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:38 INFO - 'MOZ_NO_REMOTE': '1', 10:36:38 INFO - 'NO_EM_RESTART': '1', 10:36:38 INFO - 'PAGER': '/bin/cat', 10:36:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:38 INFO - 'PWD': '/builds/slave/test', 10:36:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:38 INFO - 'SHELL': '/bin/bash', 10:36:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:38 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:38 INFO - 'USER': 'cltbld', 10:36:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:38 INFO - 'XPC_FLAGS': '0x0', 10:36:38 INFO - 'XPC_SERVICE_NAME': '0', 10:36:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:39 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 10:36:39 INFO - Cleaning up... 10:36:39 INFO - Return code: 0 10:36:39 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:36:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 10:36:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 10:36:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:39 INFO - 'HOME': '/Users/cltbld', 10:36:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:39 INFO - 'LOGNAME': 'cltbld', 10:36:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:39 INFO - 'MOZ_NO_REMOTE': '1', 10:36:39 INFO - 'NO_EM_RESTART': '1', 10:36:39 INFO - 'PAGER': '/bin/cat', 10:36:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:39 INFO - 'PWD': '/builds/slave/test', 10:36:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:39 INFO - 'SHELL': '/bin/bash', 10:36:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:39 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:39 INFO - 'USER': 'cltbld', 10:36:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:39 INFO - 'XPC_FLAGS': '0x0', 10:36:39 INFO - 'XPC_SERVICE_NAME': '0', 10:36:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:39 INFO - Downloading/unpacking psutil>=3.1.1 10:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:36:42 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:36:42 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:36:42 INFO - Installing collected packages: psutil 10:36:42 INFO - Running setup.py install for psutil 10:36:42 INFO - building 'psutil._psutil_osx' extension 10:36:42 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 10:36:43 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 10:36:43 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 10:36:43 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 10:36:43 INFO - building 'psutil._psutil_posix' extension 10:36:43 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 10:36:43 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:36:43 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:36:43 INFO - ^ 10:36:43 INFO - 1 warning generated. 10:36:43 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 10:36:43 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:36:43 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:36:44 INFO - Successfully installed psutil 10:36:44 INFO - Cleaning up... 10:36:44 INFO - Return code: 0 10:36:44 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36: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', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:36: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 mozsystemmonitor==0.3 10:36:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:44 INFO - 'HOME': '/Users/cltbld', 10:36:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:44 INFO - 'LOGNAME': 'cltbld', 10:36:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:44 INFO - 'MOZ_NO_REMOTE': '1', 10:36:44 INFO - 'NO_EM_RESTART': '1', 10:36:44 INFO - 'PAGER': '/bin/cat', 10:36:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:44 INFO - 'PWD': '/builds/slave/test', 10:36:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:44 INFO - 'SHELL': '/bin/bash', 10:36:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:44 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:44 INFO - 'USER': 'cltbld', 10:36:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:44 INFO - 'XPC_FLAGS': '0x0', 10:36:44 INFO - 'XPC_SERVICE_NAME': '0', 10:36:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:44 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:36:44 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 10:36:44 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 10:36:44 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 10:36:44 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 10:36:44 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 10:36:44 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 10:36:46 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:36:46 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:36:46 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:36:46 INFO - Installing collected packages: mozsystemmonitor 10:36:46 INFO - Running setup.py install for mozsystemmonitor 10:36:47 INFO - Successfully installed mozsystemmonitor 10:36:47 INFO - Cleaning up... 10:36:47 INFO - Return code: 0 10:36:47 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:36:47 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')]} 10:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:47 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')]} 10:36:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:47 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:47 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 10:36:47 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 10:36:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:47 INFO - 'HOME': '/Users/cltbld', 10:36:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:47 INFO - 'LOGNAME': 'cltbld', 10:36:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:47 INFO - 'MOZ_NO_REMOTE': '1', 10:36:47 INFO - 'NO_EM_RESTART': '1', 10:36:47 INFO - 'PAGER': '/bin/cat', 10:36:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:47 INFO - 'PWD': '/builds/slave/test', 10:36:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:47 INFO - 'SHELL': '/bin/bash', 10:36:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:47 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:47 INFO - 'USER': 'cltbld', 10:36:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:47 INFO - 'XPC_FLAGS': '0x0', 10:36:47 INFO - 'XPC_SERVICE_NAME': '0', 10:36:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:47 INFO - Downloading/unpacking jsonschema==2.5.1 10:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:49 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:36:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:50 INFO - Downloading functools32-3.2.3-2.tar.gz 10:36:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:36:50 INFO - warning: no files found matching '*.txt' 10:36:50 INFO - no previously-included directories found matching 'build' 10:36:50 INFO - no previously-included directories found matching 'dist' 10:36:50 INFO - no previously-included directories found matching '.git*' 10:36:50 INFO - Installing collected packages: jsonschema, functools32 10:36:50 INFO - Running setup.py install for functools32 10:36:50 INFO - warning: no files found matching '*.txt' 10:36:50 INFO - no previously-included directories found matching 'build' 10:36:50 INFO - no previously-included directories found matching 'dist' 10:36:50 INFO - no previously-included directories found matching '.git*' 10:36:50 INFO - Successfully installed jsonschema functools32 10:36:50 INFO - Cleaning up... 10:36:50 INFO - Return code: 0 10:36:50 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:50 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:50 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 10:36:50 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 10:36:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:50 INFO - 'HOME': '/Users/cltbld', 10:36:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:50 INFO - 'LOGNAME': 'cltbld', 10:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:50 INFO - 'MOZ_NO_REMOTE': '1', 10:36:50 INFO - 'NO_EM_RESTART': '1', 10:36:50 INFO - 'PAGER': '/bin/cat', 10:36:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:50 INFO - 'PWD': '/builds/slave/test', 10:36:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:50 INFO - 'SHELL': '/bin/bash', 10:36:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:50 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:50 INFO - 'USER': 'cltbld', 10:36:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:50 INFO - 'XPC_FLAGS': '0x0', 10:36:50 INFO - 'XPC_SERVICE_NAME': '0', 10:36:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:36:50 INFO - Cleaning up... 10:36:50 INFO - Return code: 0 10:36:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36: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')]} 10:36:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:50 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:50 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 10:36:50 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 10:36:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:50 INFO - 'HOME': '/Users/cltbld', 10:36:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:50 INFO - 'LOGNAME': 'cltbld', 10:36:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:50 INFO - 'MOZ_NO_REMOTE': '1', 10:36:50 INFO - 'NO_EM_RESTART': '1', 10:36:50 INFO - 'PAGER': '/bin/cat', 10:36:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:50 INFO - 'PWD': '/builds/slave/test', 10:36:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:50 INFO - 'SHELL': '/bin/bash', 10:36:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:50 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:50 INFO - 'USER': 'cltbld', 10:36:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:50 INFO - 'XPC_FLAGS': '0x0', 10:36:50 INFO - 'XPC_SERVICE_NAME': '0', 10:36:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:51 INFO - Downloading/unpacking blobuploader==1.2.4 10:36:51 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 10:36:51 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 10:36:51 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 10:36:51 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 10:36:51 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 10:36:51 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 10:36:53 INFO - Downloading blobuploader-1.2.4.tar.gz 10:36:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:36:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:36:53 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 10:36:53 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 10:36:53 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 10:36:53 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 10:36:53 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 10:36:53 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 10:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:36:54 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:36:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:36:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:36:54 INFO - Downloading docopt-0.6.1.tar.gz 10:36:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:36:54 INFO - Installing collected packages: blobuploader, requests, docopt 10:36:54 INFO - Running setup.py install for blobuploader 10:36:55 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:36:55 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:36:55 INFO - Running setup.py install for requests 10:36:55 INFO - Running setup.py install for docopt 10:36:55 INFO - Successfully installed blobuploader requests docopt 10:36:55 INFO - Cleaning up... 10:36:55 INFO - Return code: 0 10:36:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:36: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')]} 10:36:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:36:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:56 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')]} 10:36:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:36:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:36:56 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:36:56 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 10:36:56 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 10:36:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:36:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:36:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:36:56 INFO - 'HOME': '/Users/cltbld', 10:36:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:36:56 INFO - 'LOGNAME': 'cltbld', 10:36:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:36:56 INFO - 'MOZ_NO_REMOTE': '1', 10:36:56 INFO - 'NO_EM_RESTART': '1', 10:36:56 INFO - 'PAGER': '/bin/cat', 10:36:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:36:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:36:56 INFO - 'PWD': '/builds/slave/test', 10:36:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:36:56 INFO - 'SHELL': '/bin/bash', 10:36:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:36:56 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:36:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:36:56 INFO - 'USER': 'cltbld', 10:36:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:36:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:36:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:36:56 INFO - 'XPC_FLAGS': '0x0', 10:36:56 INFO - 'XPC_SERVICE_NAME': '0', 10:36:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:36:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:36:56 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-Nt8OLG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:36:56 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-YUOBYC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:36:56 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-CoA3kq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:36:56 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-H_WrQk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:36:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:36:56 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-iQf8Bx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-eamJpq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-gXP6st-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-HfDmC7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-mcNrB8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-pCIQq2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-jU0njF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-6O1dBx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-4nRluQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:36:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:36:57 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-e3rWOX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:58 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-_yIhvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:36:58 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-TjX_Vh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:36:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:36:58 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1irw28-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:36:58 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:36:58 INFO - Running setup.py install for manifestparser 10:36:58 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:36:58 INFO - Running setup.py install for mozcrash 10:36:58 INFO - Running setup.py install for mozdebug 10:36:58 INFO - Running setup.py install for mozdevice 10:36:58 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:36:58 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:36:58 INFO - Running setup.py install for mozfile 10:36:59 INFO - Running setup.py install for mozhttpd 10:36:59 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Running setup.py install for mozinfo 10:36:59 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Running setup.py install for mozInstall 10:36:59 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Running setup.py install for mozleak 10:36:59 INFO - Running setup.py install for mozlog 10:36:59 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Running setup.py install for moznetwork 10:36:59 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:36:59 INFO - Running setup.py install for mozprocess 10:37:00 INFO - Running setup.py install for mozprofile 10:37:00 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:37:00 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:37:00 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:37:00 INFO - Running setup.py install for mozrunner 10:37:00 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:37:00 INFO - Running setup.py install for mozscreenshot 10:37:00 INFO - Running setup.py install for moztest 10:37:00 INFO - Running setup.py install for mozversion 10:37:00 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:37:00 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:37:00 INFO - Cleaning up... 10:37:00 INFO - Return code: 0 10:37:00 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:37: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')]} 10:37:00 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:00 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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')]} 10:37:00 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:00 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:00 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:37:00 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 10:37:00 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 10:37:00 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:37:00 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:00 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:00 INFO - 'HOME': '/Users/cltbld', 10:37:00 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:00 INFO - 'LOGNAME': 'cltbld', 10:37:00 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:00 INFO - 'MOZ_NO_REMOTE': '1', 10:37:00 INFO - 'NO_EM_RESTART': '1', 10:37:00 INFO - 'PAGER': '/bin/cat', 10:37:00 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:00 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:00 INFO - 'PWD': '/builds/slave/test', 10:37:00 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:00 INFO - 'SHELL': '/bin/bash', 10:37:00 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:37:00 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:37:00 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:00 INFO - 'USER': 'cltbld', 10:37:00 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:00 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:00 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:00 INFO - 'XPC_FLAGS': '0x0', 10:37:00 INFO - 'XPC_SERVICE_NAME': '0', 10:37:00 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-1Tz1Uj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-DS7vBu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-i9iEDj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-BSjV7a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-UCh9fE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-LOK6Rz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-4WtDEI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:37:01 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)) 10:37:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:37:01 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-cbrlJb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-ouPEBz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-iKpXEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-lz3TrR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-eJ4A6I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-z7j8ug-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QpjQvZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-LW4UAn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-e23ohH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:37:02 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)) 10:37:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:37:02 INFO - Running setup.py (path:/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/pip-QoLBXq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:37:02 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)) 10:37:02 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)) 10:37:02 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)) 10:37:02 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)) 10:37:02 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)) 10:37:02 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)) 10:37:02 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:37:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:37:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:37:05 INFO - Downloading blessings-1.6.tar.gz 10:37:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:37:05 INFO - Installing collected packages: blessings 10:37:05 INFO - Running setup.py install for blessings 10:37:06 INFO - Successfully installed blessings 10:37:06 INFO - Cleaning up... 10:37:06 INFO - Return code: 0 10:37:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:37:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:37:06 INFO - Reading from file tmpfile_stdout 10:37:06 INFO - Current package versions: 10:37:06 INFO - blessings == 1.6 10:37:06 INFO - blobuploader == 1.2.4 10:37:06 INFO - docopt == 0.6.1 10:37:06 INFO - functools32 == 3.2.3-2 10:37:06 INFO - jsonschema == 2.5.1 10:37:06 INFO - manifestparser == 1.1 10:37:06 INFO - mozInstall == 1.12 10:37:06 INFO - mozcrash == 0.17 10:37:06 INFO - mozdebug == 0.1 10:37:06 INFO - mozdevice == 0.48 10:37:06 INFO - mozfile == 1.2 10:37:06 INFO - mozhttpd == 0.7 10:37:06 INFO - mozinfo == 0.9 10:37:06 INFO - mozleak == 0.1 10:37:06 INFO - mozlog == 3.3 10:37:06 INFO - moznetwork == 0.27 10:37:06 INFO - mozprocess == 0.23 10:37:06 INFO - mozprofile == 0.28 10:37:06 INFO - mozrunner == 6.12 10:37:06 INFO - mozscreenshot == 0.1 10:37:06 INFO - mozsystemmonitor == 0.3 10:37:06 INFO - moztest == 0.7 10:37:06 INFO - mozversion == 1.4 10:37:06 INFO - psutil == 3.1.1 10:37:06 INFO - requests == 1.2.3 10:37:06 INFO - wsgiref == 0.1.2 10:37:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:06 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:37:06 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 10:37:06 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 10:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:06 INFO - 'HOME': '/Users/cltbld', 10:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:06 INFO - 'LOGNAME': 'cltbld', 10:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:06 INFO - 'MOZ_NO_REMOTE': '1', 10:37:06 INFO - 'NO_EM_RESTART': '1', 10:37:06 INFO - 'PAGER': '/bin/cat', 10:37:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:06 INFO - 'PWD': '/builds/slave/test', 10:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:06 INFO - 'SHELL': '/bin/bash', 10:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:37:06 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:06 INFO - 'USER': 'cltbld', 10:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:06 INFO - 'XPC_FLAGS': '0x0', 10:37:06 INFO - 'XPC_SERVICE_NAME': '0', 10:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 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)) 10:37:06 INFO - Cleaning up... 10:37:06 INFO - Return code: 0 10:37:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:06 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 0x1011e5a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10127a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe5d95bd310>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, '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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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 10:37:06 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 10:37:06 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 10:37:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:37:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:06 INFO - 'HOME': '/Users/cltbld', 10:37:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:06 INFO - 'LOGNAME': 'cltbld', 10:37:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:06 INFO - 'MOZ_NO_REMOTE': '1', 10:37:06 INFO - 'NO_EM_RESTART': '1', 10:37:06 INFO - 'PAGER': '/bin/cat', 10:37:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:06 INFO - 'PWD': '/builds/slave/test', 10:37:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:06 INFO - 'SHELL': '/bin/bash', 10:37:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:37:06 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:37:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:06 INFO - 'USER': 'cltbld', 10:37:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:06 INFO - 'XPC_FLAGS': '0x0', 10:37:06 INFO - 'XPC_SERVICE_NAME': '0', 10:37:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:06 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:37:06 INFO - Cleaning up... 10:37:06 INFO - Return code: 0 10:37:06 INFO - Running post-action listener: _resource_record_post_action 10:37:06 INFO - Running post-action listener: _start_resource_monitoring 10:37:06 INFO - Starting resource monitoring. 10:37:06 INFO - [mozharness: 2016-09-07 17:37:06.965323Z] Finished create-virtualenv step (success) 10:37:06 INFO - [mozharness: 2016-09-07 17:37:06.966393Z] Running install step. 10:37:06 INFO - Running pre-action listener: _resource_record_pre_action 10:37:06 INFO - Running main action method: install 10:37:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:37:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:37:07 INFO - Reading from file tmpfile_stdout 10:37:07 INFO - Detecting whether we're running mozinstall >=1.0... 10:37:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:37:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:37:07 INFO - Reading from file tmpfile_stdout 10:37:07 INFO - Output received: 10:37:07 INFO - Usage: mozinstall [options] installer 10:37:07 INFO - Options: 10:37:07 INFO - -h, --help show this help message and exit 10:37:07 INFO - -d DEST, --destination=DEST 10:37:07 INFO - Directory to install application into. [default: 10:37:07 INFO - "/builds/slave/test"] 10:37:07 INFO - --app=APP Application being installed. [default: firefox] 10:37:07 INFO - mkdir: /builds/slave/test/build/application 10:37:07 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'] 10:37:07 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 10:37:41 INFO - Reading from file tmpfile_stdout 10:37:41 INFO - Output received: 10:37:41 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:37:41 INFO - Running post-action listener: _resource_record_post_action 10:37:41 INFO - [mozharness: 2016-09-07 17:37:41.321038Z] Finished install step (success) 10:37:41 INFO - [mozharness: 2016-09-07 17:37:41.321182Z] Running run-tests step. 10:37:41 INFO - Running pre-action listener: _resource_record_pre_action 10:37:41 INFO - Running main action method: run_tests 10:37:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:37:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:37:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:37:41 INFO - 2016-09-07 10:37:41.335 screenresolution[1641:7663] starting screenresolution argv=screenresolution get 10:37:41 INFO - 2016-09-07 10:37:41.338 screenresolution[1641:7663] Display 0: 1600x1200x32@60 10:37:41 INFO - 2016-09-07 10:37:41.344 screenresolution[1642:7665] starting screenresolution argv=screenresolution list 10:37:41 INFO - Available Modes on Display 0 10:37:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:37:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:37:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:37:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:37:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:37:41 INFO - Intel Iris: 10:37:41 INFO - Chipset Model: Intel Iris 10:37:41 INFO - Type: GPU 10:37:41 INFO - Bus: Built-In 10:37:41 INFO - VRAM (Dynamic, Max): 1536 MB 10:37:41 INFO - Vendor: Intel (0x8086) 10:37:41 INFO - Device ID: 0x0a2e 10:37:41 INFO - Revision ID: 0x0009 10:37:41 INFO - Displays: 10:37:41 INFO - Display: 10:37:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:37:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:37:41 INFO - Display Serial Number: 1600x1200 60 10:37:41 INFO - Main Display: Yes 10:37:41 INFO - Mirror: Off 10:37:41 INFO - Online: Yes 10:37:41 INFO - Rotation: Supported 10:37:41 INFO - Return code: 0 10:37:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:37:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:37:41 INFO - Python 2.7.3 10:37:41 INFO - Return code: 0 10:37:41 INFO - grabbing minidump binary from tooltool 10:37: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')]} 10:37:41 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 0x101149d30>, '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 0x100eaedb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101141b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:37:41 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 10:37:41 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 10:37:41 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 10:37:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:37:41 INFO - Return code: 0 10:37:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:37:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:37:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:37:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:37:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:37:41 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/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 10:37:41 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/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0195 --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 10:37:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.3IVGa1NQrG/Render', 10:37:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:41 INFO - 'HOME': '/Users/cltbld', 10:37:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:41 INFO - 'LOGNAME': 'cltbld', 10:37:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:37:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:37:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:41 INFO - 'MOZ_NO_REMOTE': '1', 10:37:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:37:41 INFO - 'NO_EM_RESTART': '1', 10:37:41 INFO - 'PAGER': '/bin/cat', 10:37:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:41 INFO - 'PWD': '/builds/slave/test', 10:37:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:37:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:41 INFO - 'SHELL': '/bin/bash', 10:37:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.0hdsT9uN3i/Listeners', 10:37:41 INFO - 'TMPDIR': '/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/', 10:37:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:41 INFO - 'USER': 'cltbld', 10:37:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:41 INFO - 'XPC_FLAGS': '0x0', 10:37:41 INFO - 'XPC_SERVICE_NAME': '0', 10:37:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:41 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/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0195', '--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 10:37:41 INFO - mozversion application_buildid: 20160907082619 10:37:41 INFO - mozversion application_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:37:41 INFO - mozversion application_display_name: Nightly 10:37:41 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:37:41 INFO - mozversion application_name: Firefox 10:37:41 INFO - mozversion application_remotingname: firefox 10:37:41 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 10:37:41 INFO - mozversion application_vendor: Mozilla 10:37:41 INFO - mozversion application_version: 51.0a1 10:37:41 INFO - mozversion platform_buildid: 20160907082619 10:37:41 INFO - mozversion platform_changeset: 95acb9299fafdc69463c49860caf367e4fbcc8e3 10:37:41 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 10:37:41 INFO - mozversion platform_version: 51.0a1 10:37:41 INFO - using testdate: 1473269861 10:37:41 INFO - actual date: 1473269861 10:37:41 INFO - starting webserver on 'localhost:49279' 10:37:41 INFO - SUITE-START | Running 2 tests 10:37:41 INFO - TEST-START | dromaeo_css 10:37:41 INFO - operating with platform_type : mac_ 10:37:41 INFO - Initialising browser for dromaeo_css test... 10:37:41 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpR98bPB/profile http://localhost:49279/getInfo.html) 10:37:44 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 10:37:44 INFO - PROCESS | 1652 | colorDepth:24 10:37:44 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 10:37:44 INFO - PROCESS | 1652 | __metrics 10:37:44 INFO - PROCESS | 1652 | 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" 10:37:44 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:37:45 INFO - TEST-INFO | 1652: exit 0 10:37:45 INFO - Browser initialized. 10:37:45 INFO - Running cycle 1/1 for dromaeo_css test... 10:37:45 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpR98bPB/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:37:57 INFO - PROCESS | 1654 | RSS: Main: 232386560 10:37:57 INFO - PROCESS | 1654 | 10:38:49 INFO - PROCESS | 1654 | 1473269929295 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] 10:41:01 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49279/tests/dromaeo/cssquery-ext.html) 10:41:02 INFO - PROCESS | 1654 | RSS: Main: 371924992 10:41:02 INFO - PROCESS | 1654 | 10:44:05 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-ext.html (next: http://localhost:49279/tests/dromaeo/cssquery-jquery.html) 10:44:06 INFO - PROCESS | 1654 | RSS: Main: 371531776 10:44:06 INFO - PROCESS | 1654 | 10:47:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49279/tests/dromaeo/cssquery-mootools.html) 10:47:09 INFO - PROCESS | 1654 | RSS: Main: 361955328 10:47:09 INFO - PROCESS | 1654 | 10:50:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49279/tests/dromaeo/cssquery-prototype.html) 10:50:12 INFO - PROCESS | 1654 | RSS: Main: 351350784 10:50:12 INFO - PROCESS | 1654 | 10:53:15 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49279/tests/dromaeo/cssquery-yui.html) 10:53:16 INFO - PROCESS | 1654 | RSS: Main: 326135808 10:53:16 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49279/tests/dromaeo/cssquery-yui.html (next: http://localhost:49279/tests/dromaeo/cssquery-dojo.html) 10:56:19 INFO - PROCESS | 1654 | RSS: Main: 536367104 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | __start_tp_report 10:56:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 10:56:19 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 10:56:19 INFO - PROCESS | 1654 | |i|pagename|runs| 10:56:19 INFO - PROCESS | 1654 | |0;dojo.html;3346.9832650836743;3352.631210566837;3370.5955285365776;3409.676080772326;3433.6223015468295;5662.065759149736;5749.798757043493;5781.595288329813;5888.7055647217685;5889.146073819294;5792.536597072235;5824;5848.210491583635;5851.970740146293;5855.4144585541535;5213.061648903196;5271.4464981177;5275.630705850592;5302.946970530304;5321.148616221405;5345.33183352081;5351.7324133793345;5364.383095943977;5366.007288651593;5369.731513424333;5251.947480525203;5259.158534634459;5289.074411977888;5300.840974770763;5357.223202635615;5344.652597581184;5370;5379.031774280628;5382.865428364283;5406.243125962368;5126.61550383719;5196.948030519684;5231.869203269911;5271.630985830993;5275.472452754732;5738.856528586777;5777.277840269966;5786.508146807527;5825;5845.970770146165;5172.482751724835;5172.61205409592;6274.717637706314;6309.905351419732;6312.147860038882;5225.764840582183;6165.136880836686;6223.564350495467;6232.626042437469;6288.119663247149;5906.232189815297;5922.8815423691285;5953.642781433041;5960.880782384328;5980.0431930890845;6299.370062993664;6353.96823015882;6360.77737279193;6374.298827129012;6377.1390862233;6028.91847303289;6064.757409703562;6122.093225952662;6176.6911654418;6194.783182588588;6246.219222597176;6278.811635650938;6280.183576135073;6293.087502312139;6320.747170113144;6276.686165691697;6286.4342209201395;6290.968545157245;6291.6854157291955;6309.842253943688;6104.877902441926;6195.2255968004;6283.748650054038;6284.874302514016;6315.336889626523;5019.171836646912;5044;5061.670991385548;5082.898342033212;5127.846164615067;4503.662225333048;4503.68474206809;4504.144212599661;4514.977425112854;4556.316552517149;5567.721613919288;5749.1663708762835;5775.768969241183;5778.479936805624;5799.797007104816;5193.948060519422;6225.533085018642;6255.311915689271;6262.6868656566985;6263.3736626337;5693.146028095819;5736.196932429463;5739.655620662774;5756.107803290497;5779.797707080316;5446.591505637014;5488.7530061147345;5499.312585926759;5505.779768809283;5520.1167813149705;5183.326167598264;6284.654344011124;6294.716737746904;6326.304106548184;6353.428191462791;6265.8120256392385;6276.309605943251;6278.309385967448;6289.622622642656;6294.464970477377;6409.198850143732;6420.967895160495;6444.806655800334;6446.065320528591;6463.903041454476;6058.848528786815;6183.659898705614;6199.969000154971;6205.906911396242;6236.158118653923;6213.782517611862;6275.1842260507665;6277.591956522994;6289.96855015722;6290.716917738621;6203.751849925952;6217.813465596039;6218.502519798315;6224.564280500322;6234.2830574484515;5466.180072989084;5999.010163323177;6121.87979361315;6135.907961380493;6168.290646575521;6065.848353791191;6077.251678571656;6077.579333439728;6094.024956007018;6106.847328816815;6152.753889844356;6207.689615519296;6223.471004964629;6230.096635987847;6239.906401404073;5874.206982057368;5928.3775203603;5930.28836539618;5957.6127551709;6026.337102918587 10:56:19 INFO - PROCESS | 1654 | |1;ext.html;514.5214950096408;525.7923120367452;527.0171130840981;531.1581143886939;532.9706866122361;30114.54828177579;33329;39945.80027099864;41197.7940110299;43505;41873.37189942153;42328.57671423285;42364.36453453193;42394.57605423945;42414;8629.093945135764;8911.598978045971;9597;9921.75195620108;9943.801123977517;8929.598168082419;11859.46632401544;12039.398030098504;12079.577214797484;12387.504499819996;12102.878971210308;12129.757404851898;12145.696357591043;12168.574099906506;12188.024958003338;8764.737057888273;11941.880581194164;12031.699207519838;12094.516219351215;12118.757624847498;11675.883241167565;12008.459619317151;12026.218295810744;12031.93984030083;12033.81949270753;39223.60776392228;39638.801805991076;39651.00872478182;39667.20665586672;39700;3353.6814002669707;3403.23427228874;3518.0677120562814;3522.433067819978;3721.776693398405;30826.845865770527;31030.714824969196;31317.747290108367;31563.4763916316;32232.83883580567;3216.4692825683733;3217.6621454747383;3266.1508007917873;3271.689189526991;3373.4939759036342;426675;426767;429369;430225;433584;56992;56993.43006569964;57180;57181.714091429276;57256.84688377983;34119.31761364759;34643.4803477948;35018.824905875306;36151;36268.274634507165;16309.184540772914;16448.83551164473;16510.917445412695;16521.504354869372;16545.83454165467;11531.769364612828;11865.703357416134;12539.561115360919;12727.109102362745;12880.484780608858;19181.424557263304;19235.13441895118;19274.614507709768;19278.421647350602;19440.8055919439;7204.459665525106;7645.967794347803;7857;7949.364050875916;7988.400869934662;704.6230266807233;954.796955835652;1014.5485259059795;1033.0289527843802;1072.4100403023533;28618.427631447255;29492.705072948997;29562.40875182484;30125.397492050037;30260.94086706955;870.7735988642999;1090.0298734126634;1094.3433939636154;1103.7472468748028;1133;12484.438200281009;13988.386961951643;14305.856941430453;14677.853221467862;15628.062316261061;571333;620553;627841.8607907023;629225;634412;57619;58142.41857581455;58166.12750808741;60715.69642151761;60945;1300.2458574027041;1343.4559003603556;1407.3526177958254;1413.3145424469124;1455.4542046732474;29321.04607721046;30333;32311.838440807645;32418.83790581032;32877.83561082179;56195.71902140463;56892.43107568871;56899;56960;57006;510037;520361;523080;523698;525470;35277;36239.81880090583;40437.393439099025;42863.9284017902;43136.13727725491;46336.7683161582;46368.768156159;46770.76614616905;47255;47258.29112563246;38524.8442546724;38564;38674;39473.60526394699;40306.59693403028;31912.6808731919;31950;38517;39349.2130157401;39857.60142398655;36742.26515469729;37459;37670.434943475324;37696.43455348227;38201.85394438171;32903.01290961275;33818.32363352768;34239;34279.14302142367;34502.48246276358;22169.334919952427;22566.435839104153;25149.87425062936;25313.367165821;25399.873000634878 10:56:19 INFO - PROCESS | 1654 | |2;jquery.html;312.8076233116633;347.3348537550591;366.61322304968263;369.77443759306817;401.0515131713498;15275.388984440622;17567.297308107678;20339.389818305437;20360.796392036093;20661.243376371385;15908.761368579451;15927.920360398182;20087.598248035032;20412.489687757814;20562.177512899467;5746.166805813165;6011.068284415923;6011.368806275344;6014.2181516403025;6031.336552979169;4610.815567377301;6497;6586.27550969393;6633.635150066744;6661.566998145129;5758.251427314443;6053.606515576495;6557.213134423876;6566.901496477557;6568.113304703852;6015.428534289235;6061.181740465025;6064.514838812928;6070.119832624245;6071.301800292978;5131.897362052738;5406.053940560426;5453.836384908459;5459.908018396337;5818.272715910512;12550.74898502034;12771.552995645201;12797.360131993457;20390.388288351372;24043;2029.3911826452154;2076.9542001128875;2099.9160033598637;2456.0421435640114;2495.5508008558454;9485.47829869357;9521.523923803852;9749.951250243774;9974.950125249326;9977.551010204557;1746.5022468596517;1843.778746550409;1848.6949653307188;1882.8023057578894;1902.4673091534391;112250;115772;118030;119436;120575.3971230147;10930.781384372427;11194.712608050015;11241.21311508203;11272.985431311221;11440.599579014695;12295.262284262973;13669.589912302647;14194.361253743606;14204.786928196085;14216.857831421554;8112.594370281462;8317.8336433271;8320.37597180214;8394.916050839536;8432.409731318749;8186.672533098609;8231.34149268057;8239.217274358927;8409.2431681149;8464.40749147566;3855.8072096395067;4340.741185056361;4351.673624478113;4409.911801764011;4563.315502674625;1680.008794811043;1748.5453782016514;1813.9274429022691;1858.5353661584604;1859.8047205043547;706.9222385537587;755.7090520149782;783.8432313537202;803.0965164190286;897.6768363389175;18907.81092189088;19362.80637193638;19526.902365488364;20456.079476423598;20687.8965605171;919.8206349761732;952.4095061062186;958.849380743111;961.5817119553017;976.8996657186963;9981.500924953723;10712.303700259457;10732.570697172181;10755.462226888782;10772.676819695422;414730;477439;482928;484987;485855;99436.00563994267;117775;118748.40625796816;119738;124557.37721311336;922.9907700923173;939.6100618243489;954.9379290346105;991.5835349153373;993.2649839119144;13372;14149.43402263898;18612.534686632942;19143;19213.903930480257;53371.466285336646;54631;56033.71983140058;56547;57073.71463142658;80556.59721701354;80802.19197808181;81205.59397203212;83328.58335708283;84183;6724.495662825406;7832.099308579358;8024.83950321002;8162.795930101795;8314.625841837253;9128;11682.357470339213;12727.681807954876;12733.61799146027;12904.967602591958;10876.619318323821;11156.995870371706;11293.548258069584;11619.767604648028;11628.709282268012;10959.397233152253;10990;11011.724706882393;11409.54361825551;11488.19582629208;10495.422751748727;11126.944365278121;11286.774264514828;11474.426278685865;11554.884451155382;6966.198887127843;7267.818304542217;7386.96306518464;7401.185869554452;8085.676572937016;9457.337986341165;9694.612215511583;10966.561337546409;11075.280106793032;11292.096632269235 10:56:19 INFO - PROCESS | 1654 | |3;mootools.html;1497.7828214908834;1536.4468791235156;1737.3658614605665;1825.0600940515644;1897.155765684271;8454;8967.820643587133;8975.685850995216;8976.012638609747;8989.91010089899;7804.2585954334245;8049.154838741935;8318.292945099658;8651;8732.563371831415;2359.4219416243;2410.288964755401;2411.710594728635;2417.4802417480237;2420.4674971506242;2390.3665528634924;2393.6529203265604;2432.2946345559767;2434.6469761884605;2436.8294219404647;2399.664047033417;2415.7101147862195;2422.733499315074;2425.745296743843;2431.185552839801;2373.5727569037567;2392.222527678503;2393.8324317297797;2397.280815755275;2403.8197135214928;2110.2930518276394;2282.3723476044056;2397.8081753459855;2398.07674045492;2401.2436082633913;8628;8748.25639820616;8758.430702004413;8759.73720788371;8771.73684789451;5114.974425127888;5272.128394419964;5276.366835979668;5278.5513231375;5284.550813180885;3616.0959760059986;3629.872954446608;3696.2237930034726;3706.610805865399;3729.030452082478;1088.3034857691005;1093.3112139352156;1099.1371773158112;1112.883147269533;1121.243160866412;9595.856062159004;11541.76916461666;11620.302781833032;11629.476673549627;11659.533618655243;4570.451545814457;4668.299755036704;4841.05599408112;4873.610111191097;4884.0476107159475;8536.402451828437;9952.552135153936;9960.203183745429;9964.20286377088;9984.850227246598;4545;5356.571474282048;5386.973065134727;5396.78412863481;5398.568114550905;4425.336199570026;4576.908461830812;4791.856244312677;4897.069556784199;4928.088303663762;3897.5517815451585;3957.9010524737114;4030.5163380394542;4070.104576993057;4073.3889916512762;1282.6665067082627;1306.314185052836;1313.7306852095298;1324.0731487958276;1326.522451917309;746.3655892491339;858.2447446247262;902.4991129922824;905.2576886952636;925.7361651929168;5649.887002259932;5671.744771485293;5751.4536119068625;5793.420657934173;5989.101634754822;942.8067246214509;945.1021529546958;947.545178314413;952.1668540027476;955.6511873166384;2813.212300555848;3543.20277937462;3902.375619900802;4126.319157339006;4131.876043718693;10030.097291243823;10599.364038157735;10689.251752377259;10803.621873234399;10859.83710244347;9315.580798864068;10408.791824163474;10754.677359679066;10754.677359679223;10772.353658780341;7944.205579442125;8275.213854683796;8277.793055173668;8288.917110828936;8296.70961516344;4822.7829747660735;5852.561057920588;5913.526917846476;5996.5502587307;6002.939970600238;9867.309288349747;10084.798304034024;10126.746831329276;10141.391516509033;10160;9828.803423931624;10084.899151008689;10182.185425166117;10216.182705383402;10277.126444252022;6851.143607049119;7162.856742865009;7254.419646428168;7444.255574442513;7470.0662417197855;8141.8371632568205;8270.586470676322;8277.420580559303;8291.253787158943;8332.16678332162;7264.673089711081;7401.703931842666;7457.440691948234;7519.887201691869;7540.434467414856;7164.365379662714;7182;7225.096862892138;7339.816504587428;7375.262473752582;6791.151106111736;6851.48613853953;6870.45036397097;6873.621950792554;6876.724931002704;4901.877453063559;4933.21068629032;4962.925556116734;4978.228374601942;5041.571466425248;4877.219644856806;4882.6826256293225;4975.850724478271;5038.571721403575;5134.460881607527 10:56:19 INFO - PROCESS | 1654 | |4;prototype.html;289.86376403090554;291.9343147791748;302.0515581075423;308.1664098613251;312.50312003913785;4783.760811959397;5173.249878767581;5476;5561.555075593953;5592.972035139824;5295.841124766263;5301.973490132554;5429.56563474922;5475.780968761244;5489.819688766915;3336.1826352543662;4221.176870510251;4225.661947044229;4230.534641189467;4232.449781528412;4150.294449943517;4182.33082706767;4255.425517555137;4256.382824490447;4269.658427325822;3942.319222928422;4166.1877083468635;4168.874933751992;4184.727992680466;4203.7057405981595;3733.9813300933592;4166.291730405838;4187.497500299953;4197.580241975808;4222.936655950163;3435.886929538341;3456.602490713575;3568.357695614788;3570.589382221052;3590.6409359064146;4256.403848556791;5324.866878328034;5334.626576139672;5343.492368225051;5361.678299302054;4317.740935543846;4735.910740529663;4755.42934847817;4758.690685105492;4773.64197685175;5641.502268593457;5781.19063331134;5793.623414478087;5861.325947516048;5890.941090589082;3890.7665540067687;3894.9805250973845;3897.8440862365474;3909.9052265365744;3924.9215015699524;5796.072628379481;5829.387914268983;6116.571839971204;6128.479079278267;6131.816045518642;3309.8841540545964;3408.079818449005;3646.3983442732183;3961.1285517186175;3970.68234541236;3981.8407263709705;4009.7594144351433;4010.7192496524963;4016.9598304016586;4042.0905296308683;4735.715857048588;4737.24204127338;4746.288056791509;4754.738489383117;4764.4282686077195;4675.5324467553655;5706.543476521868;5808.796692115756;5852.795152169739;5871.735771890275;1166.8891455311732;1207.5569694215328;1297.5199176304827;1322.6032190342858;1363.7886127650231;738.553175328929;888.471359541072;897.8698088777221;912.6219408692922;921.5852866210232;4240.38514415408;5356;5359.464053594609;5377.892442151135;5416.539594134464;5838.766449341979;5849.268841394825;5874.265716785402;5903.970480147572;5917.852053698692;4208.85269015583;4977.477364876636;5277.997180535686;5296.788128474895;5320.334958130234;2326.895289711967;2342.4963632819026;2448.877556122187;2460.720425378793;2629.57926731722;4329.350597410351;5007.924881126786;5117.257997590403;5128.948565544055;5169.353830771153;4599.3265993265995;4821.276808478756;4952.62855285855;5096.63622009472;5112.775942465039;4406.838886808935;4711.905761884743;4901.362822833009;5171.560417364566;5184.844454666366;4902.377228182905;4944.8763780905765;4965.851024469272;4965.900681986268;5004.174311238605;4156.854510092132;4749.955009897817;4822.828253560715;4925.990172014656;5009.4489606144025;4705.964652798857;4816.301636262652;5187.81842635521;5208.713520756395;5311;2799.9160025199276;2959.926001849971;3361.2522037928024;3426.9780829310826;3473.5137080808204;4458.955410445984;4476.753778542212;4774.665773395829;4970;5679.403662615366;5834.790865024304;5866.413358664099;5880.529557635294;5890.263717035371;5895.877484105633;5847.44449277318;5868.295804503487;5871.706414679164;5878.382769809279;5905.379935106752;4525.804984582409;5635.88728225425;5692.943070569241;5700.714964251853;5788.53691704671;4353.760543170029;5679.943200567942;5829.542614346414;5839.591228613956;5843.590948633555;5059.5952323814745;5260.710660913686;5497.340319161726;5768.94231057684;5798.391168927371 10:56:19 INFO - PROCESS | 1654 | |5;yui.html;744.3896005275673;769.2230846844685;797.1629788721842;806.8848870759125;855.9957200213998;15111.773323400144;15916.522504324888;15939.681206375866;15942.601434964132;16025;11210.439478026106;13305.13516621419;13312.600621981332;13345.332733363317;13379.866201337984;669.4376723552215;843.3042739739709;868.7002983970551;872.995635021824;874.204473928725;681.9045333653293;839.9794249986265;846.4159729786459;846.5428668518992;850.1286181663783;896.0859922878692;896.923761480275;897.2866571075978;897.8742975983367;899.7750562359411;710.6553321639001;881.6473410635765;896.6771962093641;908.0737647599411;909.4134283387178;672.7914346552584;673.1316601583951;674.625582801545;674.6930146783202;675.9797206083825;14481;14513.637159071002;14532.346044428026;14536.418543258256;14544.127352358892;13567.321633918364;13637.045406821526;13643.590692279247;13799.586012419644;13943.860561394358;12569.245845249314;12633.93683031588;12645.557405490856;12723.554675586403;12757.043221758313;4413.470383553962;4463.330500424962;4477.395551600524;4601.171789077965;4668.229742092551;95966;97988.0201197986;98623;101076;108112.45943770309;10805.83791243132;10917;10986.230963832615;11096.667099987013;11269.887301126884;21127.57744845125;21339.35981920545;21384.251551195634;21398;21405.143794248368;7830.804229894298;8011.519308841488;8019.679212831421;8083.110857805636;8232.464889782144;4377.255866502702;4531.048479819275;4565.429321334833;4619.46876109245;4627.375304333939;3531.823408829548;3655.7570426055267;3662.926741465156;3665;3666.3400587894434;645.0904225042667;649.4025496543192;649.9447546958562;651.7034749189203;651.9445847102954;568.7753337431691;590.2916500199777;609.5215256024;636.8758092172075;681.7545683553917;8259.38054645904;8830.470171789713;8934.73195804127;9334.953325233466;10416;610.5329422991415;649.655682488282;670.718298314709;679.8062552172607;690.2441826200303;4409.382686423904;4494.235979883428;4657.930131048037;4665.720056796603;4713.057388522241;134817.32591337178;148069.2596537032;150940;151269;152108;127874.36062819626;127889;132850;133706;134395;111104.99999999838;111931;114948.85051149549;116474;117896.41051794686;9011.594478248626;9027.77430564215;9032.909670903207;9049.864252036094;9272.072792720674;43882.780586096866;58540;58555;58711.70644146752;59375.70312148584;88865.22269554703;89145;89702;89754;89983;13005.869941300465;13404.731905362034;13429.462821487421;13802.17186968785;13910.51313204033;14647;14683.926580367457;14853.628659283604;14889.627759306104;15035.398584056953;13582.388792504164;13908.860911390757;13979.576163271906;14117.647058823612;14597.197154156624;13468.5286014989;13497.39261733244;13682.93158534201;13950.720985580434;14139.434422623392;13371.732565348833;13477.865221348053;13519.662008449473;13633.318334083455;14027;11077.723056923642;11144;11456.968872801488;11645.184837061324;11689.649310520697;9182.524097646363;9280.675176368795;10913.890861091606;11216.102711782874;11328.660140195807 10:56:19 INFO - PROCESS | 1654 | __end_tp_report 10:56:19 INFO - PROCESS | 1654 | __start_cc_report 10:56:19 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,1278 10:56:19 INFO - PROCESS | 1654 | __end_cc_report 10:56:19 INFO - PROCESS | 1654 | __startTimestamp1473270979913__endTimestamp 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | ------- Summary: start ------- 10:56:19 INFO - PROCESS | 1654 | Number of tests: 6 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#0] dojo.html Cycles:165 Average:5779.21 Median:5957.61 stddev:631.81 (10.6%) stddev-sans-first:604.25 10:56:19 INFO - PROCESS | 1654 | Values: 3347.0 3352.6 3370.6 3409.7 3433.6 5662.1 5749.8 5781.6 5888.7 5889.1 5792.5 5824.0 5848.2 5852.0 5855.4 5213.1 5271.4 5275.6 5302.9 5321.1 5345.3 5351.7 5364.4 5366.0 5369.7 5251.9 5259.2 5289.1 5300.8 5357.2 5344.7 5370.0 5379.0 5382.9 5406.2 5126.6 5196.9 5231.9 5271.6 5275.5 5738.9 5777.3 5786.5 5825.0 5846.0 5172.5 5172.6 6274.7 6309.9 6312.1 5225.8 6165.1 6223.6 6232.6 6288.1 5906.2 5922.9 5953.6 5960.9 5980.0 6299.4 6354.0 6360.8 6374.3 6377.1 6028.9 6064.8 6122.1 6176.7 6194.8 6246.2 6278.8 6280.2 6293.1 6320.7 6276.7 6286.4 6291.0 6291.7 6309.8 6104.9 6195.2 6283.7 6284.9 6315.3 5019.2 5044.0 5061.7 5082.9 5127.8 4503.7 4503.7 4504.1 4515.0 4556.3 5567.7 5749.2 5775.8 5778.5 5799.8 5193.9 6225.5 6255.3 6262.7 6263.4 5693.1 5736.2 5739.7 5756.1 5779.8 5446.6 5488.8 5499.3 5505.8 5520.1 5183.3 6284.7 6294.7 6326.3 6353.4 6265.8 6276.3 6278.3 6289.6 6294.5 6409.2 6421.0 6444.8 6446.1 6463.9 6058.8 6183.7 6200.0 6205.9 6236.2 6213.8 6275.2 6277.6 6290.0 6290.7 6203.8 6217.8 6218.5 6224.6 6234.3 5466.2 5999.0 6121.9 6135.9 6168.3 6065.8 6077.3 6077.6 6094.0 6106.8 6152.8 6207.7 6223.5 6230.1 6239.9 5874.2 5928.4 5930.3 5957.6 6026.3 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#1] ext.html Cycles:180 Average:66144.85 Median:19399.72 stddev:140728.60 (725.4%) stddev-sans-first:141037.11 10:56:19 INFO - PROCESS | 1654 | Values: 514.5 525.8 527.0 531.2 533.0 30114.5 33329.0 39945.8 41197.8 43505.0 41873.4 42328.6 42364.4 42394.6 42414.0 8629.1 8911.6 9597.0 9921.8 9943.8 8929.6 11859.5 12039.4 12079.6 12387.5 12102.9 12129.8 12145.7 12168.6 12188.0 8764.7 11941.9 12031.7 12094.5 12118.8 11675.9 12008.5 12026.2 12031.9 12033.8 39223.6 39638.8 39651.0 39667.2 39700.0 3353.7 3403.2 3518.1 3522.4 3721.8 30826.8 31030.7 31317.7 31563.5 32232.8 3216.5 3217.7 3266.2 3271.7 3373.5 426675.0 426767.0 429369.0 430225.0 433584.0 56992.0 56993.4 57180.0 57181.7 57256.8 34119.3 34643.5 35018.8 36151.0 36268.3 16309.2 16448.8 16510.9 16521.5 16545.8 11531.8 11865.7 12539.6 12727.1 12880.5 19181.4 19235.1 19274.6 19278.4 19440.8 7204.5 7646.0 7857.0 7949.4 7988.4 704.6 954.8 1014.5 1033.0 1072.4 28618.4 29492.7 29562.4 30125.4 30260.9 870.8 1090.0 1094.3 1103.7 1133.0 12484.4 13988.4 14305.9 14677.9 15628.1 571333.0 620553.0 627841.9 629225.0 634412.0 57619.0 58142.4 58166.1 60715.7 60945.0 1300.2 1343.5 1407.4 1413.3 1455.5 29321.0 30333.0 32311.8 32418.8 32877.8 56195.7 56892.4 56899.0 56960.0 57006.0 510037.0 520361.0 523080.0 523698.0 525470.0 35277.0 36239.8 40437.4 42863.9 43136.1 46336.8 46368.8 46770.8 47255.0 47258.3 38524.8 38564.0 38674.0 39473.6 40306.6 31912.7 31950.0 38517.0 39349.2 39857.6 36742.3 37459.0 37670.4 37696.4 38201.9 32903.0 33818.3 34239.0 34279.1 34502.5 22169.3 22566.4 25149.9 25313.4 25399.9 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#2] jquery.html Cycles:180 Average:30748.64 Median:207565.53 stddev:79675.41 (38.4%) stddev-sans-first:79866.15 10:56:19 INFO - PROCESS | 1654 | Values: 312.8 347.3 366.6 369.8 401.1 15275.4 17567.3 20339.4 20360.8 20661.2 15908.8 15927.9 20087.6 20412.5 20562.2 5746.2 6011.1 6011.4 6014.2 6031.3 4610.8 6497.0 6586.3 6633.6 6661.6 5758.3 6053.6 6557.2 6566.9 6568.1 6015.4 6061.2 6064.5 6070.1 6071.3 5131.9 5406.1 5453.8 5459.9 5818.3 12550.7 12771.6 12797.4 20390.4 24043.0 2029.4 2077.0 2099.9 2456.0 2495.6 9485.5 9521.5 9750.0 9975.0 9977.6 1746.5 1843.8 1848.7 1882.8 1902.5 112250.0 115772.0 118030.0 119436.0 120575.4 10930.8 11194.7 11241.2 11273.0 11440.6 12295.3 13669.6 14194.4 14204.8 14216.9 8112.6 8317.8 8320.4 8394.9 8432.4 8186.7 8231.3 8239.2 8409.2 8464.4 3855.8 4340.7 4351.7 4409.9 4563.3 1680.0 1748.5 1813.9 1858.5 1859.8 706.9 755.7 783.8 803.1 897.7 18907.8 19362.8 19526.9 20456.1 20687.9 919.8 952.4 958.8 961.6 976.9 9981.5 10712.3 10732.6 10755.5 10772.7 414730.0 477439.0 482928.0 484987.0 485855.0 99436.0 117775.0 118748.4 119738.0 124557.4 923.0 939.6 954.9 991.6 993.3 13372.0 14149.4 18612.5 19143.0 19213.9 53371.5 54631.0 56033.7 56547.0 57073.7 80556.6 80802.2 81205.6 83328.6 84183.0 6724.5 7832.1 8024.8 8162.8 8314.6 9128.0 11682.4 12727.7 12733.6 12905.0 10876.6 11157.0 11293.5 11619.8 11628.7 10959.4 10990.0 11011.7 11409.5 11488.2 10495.4 11126.9 11286.8 11474.4 11554.9 6966.2 7267.8 7387.0 7401.2 8085.7 9457.3 9694.6 10966.6 11075.3 11292.1 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#3] mootools.html Cycles:180 Average:5664.22 Median:4930.65 stddev:3139.13 (63.7%) stddev-sans-first:3132.32 10:56:19 INFO - PROCESS | 1654 | Values: 1497.8 1536.4 1737.4 1825.1 1897.2 8454.0 8967.8 8975.7 8976.0 8989.9 7804.3 8049.2 8318.3 8651.0 8732.6 2359.4 2410.3 2411.7 2417.5 2420.5 2390.4 2393.7 2432.3 2434.6 2436.8 2399.7 2415.7 2422.7 2425.7 2431.2 2373.6 2392.2 2393.8 2397.3 2403.8 2110.3 2282.4 2397.8 2398.1 2401.2 8628.0 8748.3 8758.4 8759.7 8771.7 5115.0 5272.1 5276.4 5278.6 5284.6 3616.1 3629.9 3696.2 3706.6 3729.0 1088.3 1093.3 1099.1 1112.9 1121.2 9595.9 11541.8 11620.3 11629.5 11659.5 4570.5 4668.3 4841.1 4873.6 4884.0 8536.4 9952.6 9960.2 9964.2 9984.9 4545.0 5356.6 5387.0 5396.8 5398.6 4425.3 4576.9 4791.9 4897.1 4928.1 3897.6 3957.9 4030.5 4070.1 4073.4 1282.7 1306.3 1313.7 1324.1 1326.5 746.4 858.2 902.5 905.3 925.7 5649.9 5671.7 5751.5 5793.4 5989.1 942.8 945.1 947.5 952.2 955.7 2813.2 3543.2 3902.4 4126.3 4131.9 10030.1 10599.4 10689.3 10803.6 10859.8 9315.6 10408.8 10754.7 10754.7 10772.4 7944.2 8275.2 8277.8 8288.9 8296.7 4822.8 5852.6 5913.5 5996.6 6002.9 9867.3 10084.8 10126.7 10141.4 10160.0 9828.8 10084.9 10182.2 10216.2 10277.1 6851.1 7162.9 7254.4 7444.3 7470.1 8141.8 8270.6 8277.4 8291.3 8332.2 7264.7 7401.7 7457.4 7519.9 7540.4 7164.4 7182.0 7225.1 7339.8 7375.3 6791.2 6851.5 6870.5 6873.6 6876.7 4901.9 4933.2 4962.9 4978.2 5041.6 4877.2 4882.7 4975.9 5038.6 5134.5 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#4] prototype.html Cycles:180 Average:4470.18 Median:4901.87 stddev:1398.11 (28.5%) stddev-sans-first:1366.37 10:56:19 INFO - PROCESS | 1654 | Values: 289.9 291.9 302.1 308.2 312.5 4783.8 5173.2 5476.0 5561.6 5593.0 5295.8 5302.0 5429.6 5475.8 5489.8 3336.2 4221.2 4225.7 4230.5 4232.4 4150.3 4182.3 4255.4 4256.4 4269.7 3942.3 4166.2 4168.9 4184.7 4203.7 3734.0 4166.3 4187.5 4197.6 4222.9 3435.9 3456.6 3568.4 3570.6 3590.6 4256.4 5324.9 5334.6 5343.5 5361.7 4317.7 4735.9 4755.4 4758.7 4773.6 5641.5 5781.2 5793.6 5861.3 5890.9 3890.8 3895.0 3897.8 3909.9 3924.9 5796.1 5829.4 6116.6 6128.5 6131.8 3309.9 3408.1 3646.4 3961.1 3970.7 3981.8 4009.8 4010.7 4017.0 4042.1 4735.7 4737.2 4746.3 4754.7 4764.4 4675.5 5706.5 5808.8 5852.8 5871.7 1166.9 1207.6 1297.5 1322.6 1363.8 738.6 888.5 897.9 912.6 921.6 4240.4 5356.0 5359.5 5377.9 5416.5 5838.8 5849.3 5874.3 5904.0 5917.9 4208.9 4977.5 5278.0 5296.8 5320.3 2326.9 2342.5 2448.9 2460.7 2629.6 4329.4 5007.9 5117.3 5128.9 5169.4 4599.3 4821.3 4952.6 5096.6 5112.8 4406.8 4711.9 4901.4 5171.6 5184.8 4902.4 4944.9 4965.9 4965.9 5004.2 4156.9 4750.0 4822.8 4926.0 5009.4 4706.0 4816.3 5187.8 5208.7 5311.0 2799.9 2959.9 3361.3 3427.0 3473.5 4459.0 4476.8 4774.7 4970.0 5679.4 5834.8 5866.4 5880.5 5890.3 5895.9 5847.4 5868.3 5871.7 5878.4 5905.4 4525.8 5635.9 5692.9 5700.7 5788.5 4353.8 5679.9 5829.5 5839.6 5843.6 5059.6 5260.7 5497.3 5768.9 5798.4 10:56:19 INFO - PROCESS | 1654 | 10:56:19 INFO - PROCESS | 1654 | [#5] yui.html Cycles:180 Average:24520.19 Median:4021.80 stddev:39135.36 (973.1%) stddev-sans-first:39204.42 10:56:19 INFO - PROCESS | 1654 | Values: 744.4 769.2 797.2 806.9 856.0 15111.8 15916.5 15939.7 15942.6 16025.0 11210.4 13305.1 13312.6 13345.3 13379.9 669.4 843.3 868.7 873.0 874.2 681.9 840.0 846.4 846.5 850.1 896.1 896.9 897.3 897.9 899.8 710.7 881.6 896.7 908.1 909.4 672.8 673.1 674.6 674.7 676.0 14481.0 14513.6 14532.3 14536.4 14544.1 13567.3 13637.0 13643.6 13799.6 13943.9 12569.2 12633.9 12645.6 12723.6 12757.0 4413.5 4463.3 4477.4 4601.2 4668.2 95966.0 97988.0 98623.0 101076.0 108112.5 10805.8 10917.0 10986.2 11096.7 11269.9 21127.6 21339.4 21384.3 21398.0 21405.1 7830.8 8011.5 8019.7 8083.1 8232.5 4377.3 4531.0 4565.4 4619.5 4627.4 3531.8 3655.8 3662.9 3665.0 3666.3 645.1 649.4 649.9 651.7 651.9 568.8 590.3 609.5 636.9 681.8 8259.4 8830.5 8934.7 9335.0 10416.0 610.5 649.7 670.7 679.8 690.2 4409.4 4494.2 4657.9 4665.7 4713.1 134817.3 148069.3 150940.0 151269.0 152108.0 127874.4 127889.0 132850.0 133706.0 134395.0 111105.0 111931.0 114948.9 116474.0 117896.4 9011.6 9027.8 9032.9 9049.9 9272.1 43882.8 58540.0 58555.0 58711.7 59375.7 88865.2 89145.0 89702.0 89754.0 89983.0 13005.9 13404.7 13429.5 13802.2 13910.5 14647.0 14683.9 14853.6 14889.6 15035.4 13582.4 13908.9 13979.6 14117.6 14597.2 13468.5 13497.4 13682.9 13950.7 14139.4 13371.7 13477.9 13519.7 13633.3 14027.0 11077.7 11144.0 11457.0 11645.2 11689.6 9182.5 9280.7 10913.9 11216.1 11328.7 10:56:19 INFO - PROCESS | 1654 | -------- Summary: end -------- 10:56:19 INFO - PROCESS | 1654 | 10:56:20 INFO - TEST-INFO | 1654: exit 0 10:56:20 INFO - TEST-OK | dromaeo_css | took 1118649ms 10:56:20 INFO - TEST-START | kraken 10:56:20 INFO - operating with platform_type : mac_ 10:56:20 INFO - Initialising browser for kraken test... 10:56:20 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpDW5krZ/profile http://localhost:49279/getInfo.html) 10:56:22 INFO - PROCESS | 1660 | __metrics Screen width/height:1600/1200 10:56:22 INFO - PROCESS | 1660 | colorDepth:24 10:56:22 INFO - PROCESS | 1660 | Browser inner width/height: 1024/689 10:56:22 INFO - PROCESS | 1660 | __metrics 10:56:23 INFO - PROCESS | 1660 | 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" 10:56:23 INFO - PROCESS | 1660 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 10:56:23 INFO - TEST-INFO | 1660: exit 0 10:56:23 INFO - Browser initialized. 10:56:23 INFO - Running cycle 1/1 for kraken test... 10:56:23 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/T/tmpDW5krZ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:56:35 INFO - PROCESS | 1661 | RSS: Main: 232468480 10:56:35 INFO - PROCESS | 1661 | 10:56:38 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection) 10:56:39 INFO - PROCESS | 1661 | RSS: Main: 290918400 10:56:39 INFO - PROCESS | 1661 | 10:56:41 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-dft) 10:56:42 INFO - PROCESS | 1661 | RSS: Main: 287334400 10:56:42 INFO - PROCESS | 1661 | 10:56:45 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-fft) 10:56:46 INFO - PROCESS | 1661 | RSS: Main: 377708544 10:56:46 INFO - PROCESS | 1661 | 10:56:48 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator) 10:56:49 INFO - PROCESS | 1661 | RSS: Main: 279732224 10:56:49 INFO - PROCESS | 1661 | 10:56:51 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:56:52 INFO - PROCESS | 1661 | RSS: Main: 283627520 10:56:52 INFO - PROCESS | 1661 | 10:56:54 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom) 10:56:55 INFO - PROCESS | 1661 | RSS: Main: 320933888 10:56:55 INFO - PROCESS | 1661 | 10:56:58 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate) 10:56:58 INFO - PROCESS | 1661 | RSS: Main: 323600384 10:56:58 INFO - PROCESS | 1661 | 10:57:01 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial) 10:57:02 INFO - PROCESS | 1661 | RSS: Main: 321900544 10:57:02 INFO - PROCESS | 1661 | 10:57:03 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:57:04 INFO - PROCESS | 1661 | RSS: Main: 297689088 10:57:04 INFO - PROCESS | 1661 | 10:57:06 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:57:07 INFO - PROCESS | 1661 | RSS: Main: 303484928 10:57:07 INFO - PROCESS | 1661 | 10:57:09 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:57:10 INFO - PROCESS | 1661 | RSS: Main: 304173056 10:57:10 INFO - PROCESS | 1661 | 10:57:13 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:57:13 INFO - PROCESS | 1661 | RSS: Main: 315068416 10:57:13 INFO - PROCESS | 1661 | 10:57:16 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:57:17 INFO - PROCESS | 1661 | RSS: Main: 317001728 10:57:17 INFO - PROCESS | 1661 | 10:57:18 INFO - PROCESS | 1661 | Cycle 1(1): loaded http://localhost:49279/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49279/tests/kraken/driver.html?testName=ai-astar) 10:57:19 INFO - PROCESS | 1661 | RSS: Main: 360558592 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | __start_tp_report 10:57:19 INFO - PROCESS | 1661 | _x_x_mozilla_page_load 10:57:19 INFO - PROCESS | 1661 | _x_x_mozilla_page_load_details 10:57:19 INFO - PROCESS | 1661 | |i|pagename|runs| 10:57:19 INFO - PROCESS | 1661 | |0;ai-astar;112;113;116;113;112;112;114;114;114;121 10:57:19 INFO - PROCESS | 1661 | |1;audio-beat-detection;147;106;134;166;107;132;129;106;185;137 10:57:19 INFO - PROCESS | 1661 | |2;audio-dft;184;184;186;183;188;187;182;207;184;182 10:57:19 INFO - PROCESS | 1661 | |3;audio-fft;76;74;76;156;60;59;57;62;138;57 10:57:19 INFO - PROCESS | 1661 | |4;audio-oscillator;84;84;128;70;70;77;120;89;102;152 10:57:19 INFO - PROCESS | 1661 | |5;imaging-gaussian-blur;89;85;89;86;88;85;89;86;88;85 10:57:19 INFO - PROCESS | 1661 | |6;imaging-darkroom;96;97;97;96;97;96;97;96;105;95 10:57:19 INFO - PROCESS | 1661 | |7;imaging-desaturate;85;85;86;84;86;83;84;83;82;85 10:57:19 INFO - PROCESS | 1661 | |8;json-parse-financial;56;53;56;54;54;53;56;55;53;54 10:57:19 INFO - PROCESS | 1661 | |9;json-stringify-tinderbox;65;67;71;78;73;69;73;100;103;93 10:57:19 INFO - PROCESS | 1661 | |10;stanford-crypto-aes;95;102;106;87;85;85;85;87;89;90 10:57:19 INFO - PROCESS | 1661 | |11;stanford-crypto-ccm;138;141;137;123;173;156;147;163;244;158 10:57:19 INFO - PROCESS | 1661 | |12;stanford-crypto-pbkdf2;169;168;169;168;170;168;167;170;169;166 10:57:19 INFO - PROCESS | 1661 | |13;stanford-crypto-sha256-iterative;64;67;68;65;68;66;66;67;66;66 10:57:19 INFO - PROCESS | 1661 | __end_tp_report 10:57:19 INFO - PROCESS | 1661 | __start_cc_report 10:57:19 INFO - PROCESS | 1661 | _x_x_mozilla_cycle_collect,1973 10:57:19 INFO - PROCESS | 1661 | __end_cc_report 10:57:19 INFO - PROCESS | 1661 | __startTimestamp1473271039238__endTimestamp 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | ------- Summary: start ------- 10:57:19 INFO - PROCESS | 1661 | Number of tests: 14 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#0] ai-astar Cycles:10 Average:114.10 Median:114.00 stddev:2.73 (2.4%) stddev-sans-first:2.78 10:57:19 INFO - PROCESS | 1661 | Values: 112.0 113.0 116.0 113.0 112.0 112.0 114.0 114.0 114.0 121.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#1] audio-beat-detection Cycles:10 Average:134.90 Median:135.50 stddev:26.08 (19.2%) stddev-sans-first:27.29 10:57:19 INFO - PROCESS | 1661 | Values: 147.0 106.0 134.0 166.0 107.0 132.0 129.0 106.0 185.0 137.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#2] audio-dft Cycles:10 Average:186.70 Median:185.00 stddev:7.41 (4.0%) stddev-sans-first:7.79 10:57:19 INFO - PROCESS | 1661 | Values: 184.0 184.0 186.0 183.0 188.0 187.0 182.0 207.0 184.0 182.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#3] audio-fft Cycles:10 Average:81.50 Median:61.00 stddev:35.60 (58.4%) stddev-sans-first:37.71 10:57:19 INFO - PROCESS | 1661 | Values: 76.0 74.0 76.0 156.0 60.0 59.0 57.0 62.0 138.0 57.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#4] audio-oscillator Cycles:10 Average:97.60 Median:73.50 stddev:27.48 (37.4%) stddev-sans-first:28.70 10:57:19 INFO - PROCESS | 1661 | Values: 84.0 84.0 128.0 70.0 70.0 77.0 120.0 89.0 102.0 152.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#5] imaging-gaussian-blur Cycles:10 Average:87.00 Median:88.00 stddev:1.76 (2.0%) stddev-sans-first:1.72 10:57:19 INFO - PROCESS | 1661 | Values: 89.0 85.0 89.0 86.0 88.0 85.0 89.0 86.0 88.0 85.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#6] imaging-darkroom Cycles:10 Average:97.20 Median:96.50 stddev:2.82 (2.9%) stddev-sans-first:2.96 10:57:19 INFO - PROCESS | 1661 | Values: 96.0 97.0 97.0 96.0 97.0 96.0 97.0 96.0 105.0 95.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#7] imaging-desaturate Cycles:10 Average:84.30 Median:85.00 stddev:1.34 (1.6%) stddev-sans-first:1.39 10:57:19 INFO - PROCESS | 1661 | Values: 85.0 85.0 86.0 84.0 86.0 83.0 84.0 83.0 82.0 85.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#8] json-parse-financial Cycles:10 Average:54.40 Median:54.50 stddev:1.26 (2.3%) stddev-sans-first:1.20 10:57:19 INFO - PROCESS | 1661 | Values: 56.0 53.0 56.0 54.0 54.0 53.0 56.0 55.0 53.0 54.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#9] json-stringify-tinderbox Cycles:10 Average:79.20 Median:72.00 stddev:14.10 (19.6%) stddev-sans-first:13.99 10:57:19 INFO - PROCESS | 1661 | Values: 65.0 67.0 71.0 78.0 73.0 69.0 73.0 100.0 103.0 93.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#10] stanford-crypto-aes Cycles:10 Average:91.10 Median:87.00 stddev:7.50 (8.6%) stddev-sans-first:7.83 10:57:19 INFO - PROCESS | 1661 | Values: 95.0 102.0 106.0 87.0 85.0 85.0 85.0 87.0 89.0 90.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:157.00 stddev:33.54 (21.4%) stddev-sans-first:34.79 10:57:19 INFO - PROCESS | 1661 | Values: 138.0 141.0 137.0 123.0 173.0 156.0 147.0 163.0 244.0 158.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:168.40 Median:169.00 stddev:1.26 (0.7%) stddev-sans-first:1.32 10:57:19 INFO - PROCESS | 1661 | Values: 169.0 168.0 169.0 168.0 170.0 168.0 167.0 170.0 169.0 166.0 10:57:19 INFO - PROCESS | 1661 | 10:57:19 INFO - PROCESS | 1661 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.30 Median:66.50 stddev:1.25 (1.9%) stddev-sans-first:1.01 10:57:19 INFO - PROCESS | 1661 | Values: 64.0 67.0 68.0 65.0 68.0 66.0 66.0 67.0 66.0 66.0 10:57:19 INFO - PROCESS | 1661 | -------- Summary: end -------- 10:57:19 INFO - PROCESS | 1661 | 10:57:20 INFO - TEST-INFO | 1661: exit 0 10:57:20 INFO - TEST-OK | kraken | took 59483ms 10:57:20 INFO - SUITE-END | took 1178s 10:57:20 INFO - Completed test suite (00:19:38) 10:57:20 INFO - javascript benchmark 10:57:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3346.9832650836743, 3352.631210566837, 3370.5955285365776, 3409.676080772326, 3433.6223015468295, 5662.065759149736, 5749.798757043493, 5781.595288329813, 5888.7055647217685, 5889.146073819294, 5792.536597072235, 5824.0, 5848.210491583635, 5851.970740146293, 5855.4144585541535, 5213.061648903196, 5271.4464981177, 5275.630705850592, 5302.946970530304, 5321.148616221405, 5345.33183352081, 5351.7324133793345, 5364.383095943977, 5366.007288651593, 5369.731513424333, 5251.947480525203, 5259.158534634459, 5289.074411977888, 5300.840974770763, 5357.223202635615, 5344.652597581184, 5370.0, 5379.031774280628, 5382.865428364283, 5406.243125962368, 5126.61550383719, 5196.948030519684, 5231.869203269911, 5271.630985830993, 5275.472452754732, 5738.856528586777, 5777.277840269966, 5786.508146807527, 5825.0, 5845.970770146165, 5172.482751724835, 5172.61205409592, 6274.717637706314, 6309.905351419732, 6312.147860038882, 5225.764840582183, 6165.136880836686, 6223.564350495467, 6232.626042437469, 6288.119663247149, 5906.232189815297, 5922.8815423691285, 5953.642781433041, 5960.880782384328, 5980.0431930890845, 6299.370062993664, 6353.96823015882, 6360.77737279193, 6374.298827129012, 6377.1390862233, 6028.91847303289, 6064.757409703562, 6122.093225952662, 6176.6911654418, 6194.783182588588, 6246.219222597176, 6278.811635650938, 6280.183576135073, 6293.087502312139, 6320.747170113144, 6276.686165691697, 6286.4342209201395, 6290.968545157245, 6291.6854157291955, 6309.842253943688, 6104.877902441926, 6195.2255968004, 6283.748650054038, 6284.874302514016, 6315.336889626523, 5019.171836646912, 5044.0, 5061.670991385548, 5082.898342033212, 5127.846164615067, 4503.662225333048, 4503.68474206809, 4504.144212599661, 4514.977425112854, 4556.316552517149, 5567.721613919288, 5749.1663708762835, 5775.768969241183, 5778.479936805624, 5799.797007104816, 5193.948060519422, 6225.533085018642, 6255.311915689271, 6262.6868656566985, 6263.3736626337, 5693.146028095819, 5736.196932429463, 5739.655620662774, 5756.107803290497, 5779.797707080316, 5446.591505637014, 5488.7530061147345, 5499.312585926759, 5505.779768809283, 5520.1167813149705, 5183.326167598264, 6284.654344011124, 6294.716737746904, 6326.304106548184, 6353.428191462791, 6265.8120256392385, 6276.309605943251, 6278.309385967448, 6289.622622642656, 6294.464970477377, 6409.198850143732, 6420.967895160495, 6444.806655800334, 6446.065320528591, 6463.903041454476, 6058.848528786815, 6183.659898705614, 6199.969000154971, 6205.906911396242, 6236.158118653923, 6213.782517611862, 6275.1842260507665, 6277.591956522994, 6289.96855015722, 6290.716917738621, 6203.751849925952, 6217.813465596039, 6218.502519798315, 6224.564280500322, 6234.2830574484515, 5466.180072989084, 5999.010163323177, 6121.87979361315, 6135.907961380493, 6168.290646575521, 6065.848353791191, 6077.251678571656, 6077.579333439728, 6094.024956007018, 6106.847328816815, 6152.753889844356, 6207.689615519296, 6223.471004964629, 6230.096635987847, 6239.906401404073, 5874.206982057368, 5928.3775203603, 5930.28836539618, 5957.6127551709, 6026.337102918587], "value": 5740.814945176537, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [514.5214950096408, 525.7923120367452, 527.0171130840981, 531.1581143886939, 532.9706866122361, 30114.54828177579, 33329.0, 39945.80027099864, 41197.7940110299, 43505.0, 41873.37189942153, 42328.57671423285, 42364.36453453193, 42394.57605423945, 42414.0, 8629.093945135764, 8911.598978045971, 9597.0, 9921.75195620108, 9943.801123977517, 8929.598168082419, 11859.46632401544, 12039.398030098504, 12079.577214797484, 12387.504499819996, 12102.878971210308, 12129.757404851898, 12145.696357591043, 12168.574099906506, 12188.024958003338, 8764.737057888273, 11941.880581194164, 12031.699207519838, 12094.516219351215, 12118.757624847498, 11675.883241167565, 12008.459619317151, 12026.218295810744, 12031.93984030083, 12033.81949270753, 39223.60776392228, 39638.801805991076, 39651.00872478182, 39667.20665586672, 39700.0, 3353.6814002669707, 3403.23427228874, 3518.0677120562814, 3522.433067819978, 3721.776693398405, 30826.845865770527, 31030.714824969196, 31317.747290108367, 31563.4763916316, 32232.83883580567, 3216.4692825683733, 3217.6621454747383, 3266.1508007917873, 3271.689189526991, 3373.4939759036342, 426675.0, 426767.0, 429369.0, 430225.0, 433584.0, 56992.0, 56993.43006569964, 57180.0, 57181.714091429276, 57256.84688377983, 34119.31761364759, 34643.4803477948, 35018.824905875306, 36151.0, 36268.274634507165, 16309.184540772914, 16448.83551164473, 16510.917445412695, 16521.504354869372, 16545.83454165467, 11531.769364612828, 11865.703357416134, 12539.561115360919, 12727.109102362745, 12880.484780608858, 19181.424557263304, 19235.13441895118, 19274.614507709768, 19278.421647350602, 19440.8055919439, 7204.459665525106, 7645.967794347803, 7857.0, 7949.364050875916, 7988.400869934662, 704.6230266807233, 954.796955835652, 1014.5485259059795, 1033.0289527843802, 1072.4100403023533, 28618.427631447255, 29492.705072948997, 29562.40875182484, 30125.397492050037, 30260.94086706955, 870.7735988642999, 1090.0298734126634, 1094.3433939636154, 1103.7472468748028, 1133.0, 12484.438200281009, 13988.386961951643, 14305.856941430453, 14677.853221467862, 15628.062316261061, 571333.0, 620553.0, 627841.8607907023, 629225.0, 634412.0, 57619.0, 58142.41857581455, 58166.12750808741, 60715.69642151761, 60945.0, 1300.2458574027041, 1343.4559003603556, 1407.3526177958254, 1413.3145424469124, 1455.4542046732474, 29321.04607721046, 30333.0, 32311.838440807645, 32418.83790581032, 32877.83561082179, 56195.71902140463, 56892.43107568871, 56899.0, 56960.0, 57006.0, 510037.0, 520361.0, 523080.0, 523698.0, 525470.0, 35277.0, 36239.81880090583, 40437.393439099025, 42863.9284017902, 43136.13727725491, 46336.7683161582, 46368.768156159, 46770.76614616905, 47255.0, 47258.29112563246, 38524.8442546724, 38564.0, 38674.0, 39473.60526394699, 40306.59693403028, 31912.6808731919, 31950.0, 38517.0, 39349.2130157401, 39857.60142398655, 36742.26515469729, 37459.0, 37670.434943475324, 37696.43455348227, 38201.85394438171, 32903.01290961275, 33818.32363352768, 34239.0, 34279.14302142367, 34502.48246276358, 22169.334919952427, 22566.435839104153, 25149.87425062936, 25313.367165821, 25399.873000634878], "value": 20150.477165848883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.8076233116633, 347.3348537550591, 366.61322304968263, 369.77443759306817, 401.0515131713498, 15275.388984440622, 17567.297308107678, 20339.389818305437, 20360.796392036093, 20661.243376371385, 15908.761368579451, 15927.920360398182, 20087.598248035032, 20412.489687757814, 20562.177512899467, 5746.166805813165, 6011.068284415923, 6011.368806275344, 6014.2181516403025, 6031.336552979169, 4610.815567377301, 6497.0, 6586.27550969393, 6633.635150066744, 6661.566998145129, 5758.251427314443, 6053.606515576495, 6557.213134423876, 6566.901496477557, 6568.113304703852, 6015.428534289235, 6061.181740465025, 6064.514838812928, 6070.119832624245, 6071.301800292978, 5131.897362052738, 5406.053940560426, 5453.836384908459, 5459.908018396337, 5818.272715910512, 12550.74898502034, 12771.552995645201, 12797.360131993457, 20390.388288351372, 24043.0, 2029.3911826452154, 2076.9542001128875, 2099.9160033598637, 2456.0421435640114, 2495.5508008558454, 9485.47829869357, 9521.523923803852, 9749.951250243774, 9974.950125249326, 9977.551010204557, 1746.5022468596517, 1843.778746550409, 1848.6949653307188, 1882.8023057578894, 1902.4673091534391, 112250.0, 115772.0, 118030.0, 119436.0, 120575.3971230147, 10930.781384372427, 11194.712608050015, 11241.21311508203, 11272.985431311221, 11440.599579014695, 12295.262284262973, 13669.589912302647, 14194.361253743606, 14204.786928196085, 14216.857831421554, 8112.594370281462, 8317.8336433271, 8320.37597180214, 8394.916050839536, 8432.409731318749, 8186.672533098609, 8231.34149268057, 8239.217274358927, 8409.2431681149, 8464.40749147566, 3855.8072096395067, 4340.741185056361, 4351.673624478113, 4409.911801764011, 4563.315502674625, 1680.008794811043, 1748.5453782016514, 1813.9274429022691, 1858.5353661584604, 1859.8047205043547, 706.9222385537587, 755.7090520149782, 783.8432313537202, 803.0965164190286, 897.6768363389175, 18907.81092189088, 19362.80637193638, 19526.902365488364, 20456.079476423598, 20687.8965605171, 919.8206349761732, 952.4095061062186, 958.849380743111, 961.5817119553017, 976.8996657186963, 9981.500924953723, 10712.303700259457, 10732.570697172181, 10755.462226888782, 10772.676819695422, 414730.0, 477439.0, 482928.0, 484987.0, 485855.0, 99436.00563994267, 117775.0, 118748.40625796816, 119738.0, 124557.37721311336, 922.9907700923173, 939.6100618243489, 954.9379290346105, 991.5835349153373, 993.2649839119144, 13372.0, 14149.43402263898, 18612.534686632942, 19143.0, 19213.903930480257, 53371.466285336646, 54631.0, 56033.71983140058, 56547.0, 57073.71463142658, 80556.59721701354, 80802.19197808181, 81205.59397203212, 83328.58335708283, 84183.0, 6724.495662825406, 7832.099308579358, 8024.83950321002, 8162.795930101795, 8314.625841837253, 9128.0, 11682.357470339213, 12727.681807954876, 12733.61799146027, 12904.967602591958, 10876.619318323821, 11156.995870371706, 11293.548258069584, 11619.767604648028, 11628.709282268012, 10959.397233152253, 10990.0, 11011.724706882393, 11409.54361825551, 11488.19582629208, 10495.422751748727, 11126.944365278121, 11286.774264514828, 11474.426278685865, 11554.884451155382, 6966.198887127843, 7267.818304542217, 7386.96306518464, 7401.185869554452, 8085.676572937016, 9457.337986341165, 9694.612215511583, 10966.561337546409, 11075.280106793032, 11292.096632269235], "value": 9145.365413435278, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1497.7828214908834, 1536.4468791235156, 1737.3658614605665, 1825.0600940515644, 1897.155765684271, 8454.0, 8967.820643587133, 8975.685850995216, 8976.012638609747, 8989.91010089899, 7804.2585954334245, 8049.154838741935, 8318.292945099658, 8651.0, 8732.563371831415, 2359.4219416243, 2410.288964755401, 2411.710594728635, 2417.4802417480237, 2420.4674971506242, 2390.3665528634924, 2393.6529203265604, 2432.2946345559767, 2434.6469761884605, 2436.8294219404647, 2399.664047033417, 2415.7101147862195, 2422.733499315074, 2425.745296743843, 2431.185552839801, 2373.5727569037567, 2392.222527678503, 2393.8324317297797, 2397.280815755275, 2403.8197135214928, 2110.2930518276394, 2282.3723476044056, 2397.8081753459855, 2398.07674045492, 2401.2436082633913, 8628.0, 8748.25639820616, 8758.430702004413, 8759.73720788371, 8771.73684789451, 5114.974425127888, 5272.128394419964, 5276.366835979668, 5278.5513231375, 5284.550813180885, 3616.0959760059986, 3629.872954446608, 3696.2237930034726, 3706.610805865399, 3729.030452082478, 1088.3034857691005, 1093.3112139352156, 1099.1371773158112, 1112.883147269533, 1121.243160866412, 9595.856062159004, 11541.76916461666, 11620.302781833032, 11629.476673549627, 11659.533618655243, 4570.451545814457, 4668.299755036704, 4841.05599408112, 4873.610111191097, 4884.0476107159475, 8536.402451828437, 9952.552135153936, 9960.203183745429, 9964.20286377088, 9984.850227246598, 4545.0, 5356.571474282048, 5386.973065134727, 5396.78412863481, 5398.568114550905, 4425.336199570026, 4576.908461830812, 4791.856244312677, 4897.069556784199, 4928.088303663762, 3897.5517815451585, 3957.9010524737114, 4030.5163380394542, 4070.104576993057, 4073.3889916512762, 1282.6665067082627, 1306.314185052836, 1313.7306852095298, 1324.0731487958276, 1326.522451917309, 746.3655892491339, 858.2447446247262, 902.4991129922824, 905.2576886952636, 925.7361651929168, 5649.887002259932, 5671.744771485293, 5751.4536119068625, 5793.420657934173, 5989.101634754822, 942.8067246214509, 945.1021529546958, 947.545178314413, 952.1668540027476, 955.6511873166384, 2813.212300555848, 3543.20277937462, 3902.375619900802, 4126.319157339006, 4131.876043718693, 10030.097291243823, 10599.364038157735, 10689.251752377259, 10803.621873234399, 10859.83710244347, 9315.580798864068, 10408.791824163474, 10754.677359679066, 10754.677359679223, 10772.353658780341, 7944.205579442125, 8275.213854683796, 8277.793055173668, 8288.917110828936, 8296.70961516344, 4822.7829747660735, 5852.561057920588, 5913.526917846476, 5996.5502587307, 6002.939970600238, 9867.309288349747, 10084.798304034024, 10126.746831329276, 10141.391516509033, 10160.0, 9828.803423931624, 10084.899151008689, 10182.185425166117, 10216.182705383402, 10277.126444252022, 6851.143607049119, 7162.856742865009, 7254.419646428168, 7444.255574442513, 7470.0662417197855, 8141.8371632568205, 8270.586470676322, 8277.420580559303, 8291.253787158943, 8332.16678332162, 7264.673089711081, 7401.703931842666, 7457.440691948234, 7519.887201691869, 7540.434467414856, 7164.365379662714, 7182.0, 7225.096862892138, 7339.816504587428, 7375.262473752582, 6791.151106111736, 6851.48613853953, 6870.45036397097, 6873.621950792554, 6876.724931002704, 4901.877453063559, 4933.21068629032, 4962.925556116734, 4978.228374601942, 5041.571466425248, 4877.219644856806, 4882.6826256293225, 4975.850724478271, 5038.571721403575, 5134.460881607527], "value": 4579.954911116008, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.86376403090554, 291.9343147791748, 302.0515581075423, 308.1664098613251, 312.50312003913785, 4783.760811959397, 5173.249878767581, 5476.0, 5561.555075593953, 5592.972035139824, 5295.841124766263, 5301.973490132554, 5429.56563474922, 5475.780968761244, 5489.819688766915, 3336.1826352543662, 4221.176870510251, 4225.661947044229, 4230.534641189467, 4232.449781528412, 4150.294449943517, 4182.33082706767, 4255.425517555137, 4256.382824490447, 4269.658427325822, 3942.319222928422, 4166.1877083468635, 4168.874933751992, 4184.727992680466, 4203.7057405981595, 3733.9813300933592, 4166.291730405838, 4187.497500299953, 4197.580241975808, 4222.936655950163, 3435.886929538341, 3456.602490713575, 3568.357695614788, 3570.589382221052, 3590.6409359064146, 4256.403848556791, 5324.866878328034, 5334.626576139672, 5343.492368225051, 5361.678299302054, 4317.740935543846, 4735.910740529663, 4755.42934847817, 4758.690685105492, 4773.64197685175, 5641.502268593457, 5781.19063331134, 5793.623414478087, 5861.325947516048, 5890.941090589082, 3890.7665540067687, 3894.9805250973845, 3897.8440862365474, 3909.9052265365744, 3924.9215015699524, 5796.072628379481, 5829.387914268983, 6116.571839971204, 6128.479079278267, 6131.816045518642, 3309.8841540545964, 3408.079818449005, 3646.3983442732183, 3961.1285517186175, 3970.68234541236, 3981.8407263709705, 4009.7594144351433, 4010.7192496524963, 4016.9598304016586, 4042.0905296308683, 4735.715857048588, 4737.24204127338, 4746.288056791509, 4754.738489383117, 4764.4282686077195, 4675.5324467553655, 5706.543476521868, 5808.796692115756, 5852.795152169739, 5871.735771890275, 1166.8891455311732, 1207.5569694215328, 1297.5199176304827, 1322.6032190342858, 1363.7886127650231, 738.553175328929, 888.471359541072, 897.8698088777221, 912.6219408692922, 921.5852866210232, 4240.38514415408, 5356.0, 5359.464053594609, 5377.892442151135, 5416.539594134464, 5838.766449341979, 5849.268841394825, 5874.265716785402, 5903.970480147572, 5917.852053698692, 4208.85269015583, 4977.477364876636, 5277.997180535686, 5296.788128474895, 5320.334958130234, 2326.895289711967, 2342.4963632819026, 2448.877556122187, 2460.720425378793, 2629.57926731722, 4329.350597410351, 5007.924881126786, 5117.257997590403, 5128.948565544055, 5169.353830771153, 4599.3265993265995, 4821.276808478756, 4952.62855285855, 5096.63622009472, 5112.775942465039, 4406.838886808935, 4711.905761884743, 4901.362822833009, 5171.560417364566, 5184.844454666366, 4902.377228182905, 4944.8763780905765, 4965.851024469272, 4965.900681986268, 5004.174311238605, 4156.854510092132, 4749.955009897817, 4822.828253560715, 4925.990172014656, 5009.4489606144025, 4705.964652798857, 4816.301636262652, 5187.81842635521, 5208.713520756395, 5311.0, 2799.9160025199276, 2959.926001849971, 3361.2522037928024, 3426.9780829310826, 3473.5137080808204, 4458.955410445984, 4476.753778542212, 4774.665773395829, 4970.0, 5679.403662615366, 5834.790865024304, 5866.413358664099, 5880.529557635294, 5890.263717035371, 5895.877484105633, 5847.44449277318, 5868.295804503487, 5871.706414679164, 5878.382769809279, 5905.379935106752, 4525.804984582409, 5635.88728225425, 5692.943070569241, 5700.714964251853, 5788.53691704671, 4353.760543170029, 5679.943200567942, 5829.542614346414, 5839.591228613956, 5843.590948633555, 5059.5952323814745, 5260.710660913686, 5497.340319161726, 5768.94231057684, 5798.391168927371], "value": 4021.7619951881607, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [744.3896005275673, 769.2230846844685, 797.1629788721842, 806.8848870759125, 855.9957200213998, 15111.773323400144, 15916.522504324888, 15939.681206375866, 15942.601434964132, 16025.0, 11210.439478026106, 13305.13516621419, 13312.600621981332, 13345.332733363317, 13379.866201337984, 669.4376723552215, 843.3042739739709, 868.7002983970551, 872.995635021824, 874.204473928725, 681.9045333653293, 839.9794249986265, 846.4159729786459, 846.5428668518992, 850.1286181663783, 896.0859922878692, 896.923761480275, 897.2866571075978, 897.8742975983367, 899.7750562359411, 710.6553321639001, 881.6473410635765, 896.6771962093641, 908.0737647599411, 909.4134283387178, 672.7914346552584, 673.1316601583951, 674.625582801545, 674.6930146783202, 675.9797206083825, 14481.0, 14513.637159071002, 14532.346044428026, 14536.418543258256, 14544.127352358892, 13567.321633918364, 13637.045406821526, 13643.590692279247, 13799.586012419644, 13943.860561394358, 12569.245845249314, 12633.93683031588, 12645.557405490856, 12723.554675586403, 12757.043221758313, 4413.470383553962, 4463.330500424962, 4477.395551600524, 4601.171789077965, 4668.229742092551, 95966.0, 97988.0201197986, 98623.0, 101076.0, 108112.45943770309, 10805.83791243132, 10917.0, 10986.230963832615, 11096.667099987013, 11269.887301126884, 21127.57744845125, 21339.35981920545, 21384.251551195634, 21398.0, 21405.143794248368, 7830.804229894298, 8011.519308841488, 8019.679212831421, 8083.110857805636, 8232.464889782144, 4377.255866502702, 4531.048479819275, 4565.429321334833, 4619.46876109245, 4627.375304333939, 3531.823408829548, 3655.7570426055267, 3662.926741465156, 3665.0, 3666.3400587894434, 645.0904225042667, 649.4025496543192, 649.9447546958562, 651.7034749189203, 651.9445847102954, 568.7753337431691, 590.2916500199777, 609.5215256024, 636.8758092172075, 681.7545683553917, 8259.38054645904, 8830.470171789713, 8934.73195804127, 9334.953325233466, 10416.0, 610.5329422991415, 649.655682488282, 670.718298314709, 679.8062552172607, 690.2441826200303, 4409.382686423904, 4494.235979883428, 4657.930131048037, 4665.720056796603, 4713.057388522241, 134817.32591337178, 148069.2596537032, 150940.0, 151269.0, 152108.0, 127874.36062819626, 127889.0, 132850.0, 133706.0, 134395.0, 111104.99999999838, 111931.0, 114948.85051149549, 116474.0, 117896.41051794686, 9011.594478248626, 9027.77430564215, 9032.909670903207, 9049.864252036094, 9272.072792720674, 43882.780586096866, 58540.0, 58555.0, 58711.70644146752, 59375.70312148584, 88865.22269554703, 89145.0, 89702.0, 89754.0, 89983.0, 13005.869941300465, 13404.731905362034, 13429.462821487421, 13802.17186968785, 13910.51313204033, 14647.0, 14683.926580367457, 14853.628659283604, 14889.627759306104, 15035.398584056953, 13582.388792504164, 13908.860911390757, 13979.576163271906, 14117.647058823612, 14597.197154156624, 13468.5286014989, 13497.39261733244, 13682.93158534201, 13950.720985580434, 14139.434422623392, 13371.732565348833, 13477.865221348053, 13519.662008449473, 13633.318334083455, 14027.0, 11077.723056923642, 11144.0, 11456.968872801488, 11645.184837061324, 11689.649310520697, 9182.524097646363, 9280.675176368795, 10913.890861091606, 11216.102711782874, 11328.660140195807], "value": 7819.089623518031, "unit": "score"}], "name": "dromaeo_css", "value": 7308.417781767639, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 116.0, 113.0, 112.0, 112.0, 114.0, 114.0, 114.0, 121.0], "value": 114.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 106.0, 134.0, 166.0, 107.0, 132.0, 129.0, 106.0, 185.0, 137.0], "value": 134.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [184.0, 184.0, 186.0, 183.0, 188.0, 187.0, 182.0, 207.0, 184.0, 182.0], "value": 186.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 74.0, 76.0, 156.0, 60.0, 59.0, 57.0, 62.0, 138.0, 57.0], "value": 81.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 128.0, 70.0, 70.0, 77.0, 120.0, 89.0, 102.0, 152.0], "value": 97.6, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 85.0, 89.0, 86.0, 88.0, 85.0, 89.0, 86.0, 88.0, 85.0], "value": 87.0, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 97.0, 96.0, 97.0, 96.0, 97.0, 96.0, 105.0, 95.0], "value": 97.2, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 85.0, 86.0, 84.0, 86.0, 83.0, 84.0, 83.0, 82.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 53.0, 56.0, 54.0, 54.0, 53.0, 56.0, 55.0, 53.0, 54.0], "value": 54.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 67.0, 71.0, 78.0, 73.0, 69.0, 73.0, 100.0, 103.0, 93.0], "value": 79.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [95.0, 102.0, 106.0, 87.0, 85.0, 85.0, 85.0, 87.0, 89.0, 90.0], "value": 91.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 141.0, 137.0, 123.0, 173.0, 156.0, 147.0, 163.0, 244.0, 158.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 168.0, 169.0, 168.0, 170.0, 168.0, 167.0, 170.0, 169.0, 166.0], "value": 168.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 67.0, 68.0, 65.0, 68.0, 66.0, 66.0, 67.0, 66.0, 66.0], "value": 66.3, "unit": "score"}], "name": "kraken", "value": 1500.7, "alertThreshold": 2.0}]} 10:57:20 INFO - Return code: 0 10:57:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:57:20 INFO - # TBPL SUCCESS # 10:57:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:57:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:57:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:57:20 INFO - 2016-09-07 10:57:20.585 screenresolution[1665:9819] starting screenresolution argv=screenresolution get 10:57:20 INFO - 2016-09-07 10:57:20.588 screenresolution[1665:9819] Display 0: 1600x1200x32@60 10:57:20 INFO - 2016-09-07 10:57:20.594 screenresolution[1666:9822] starting screenresolution argv=screenresolution list 10:57:20 INFO - Available Modes on Display 0 10:57:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:57:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:57:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:57:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:57:20 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:57:20 INFO - Intel Iris: 10:57:20 INFO - Chipset Model: Intel Iris 10:57:20 INFO - Type: GPU 10:57:20 INFO - Bus: Built-In 10:57:20 INFO - VRAM (Dynamic, Max): 1536 MB 10:57:20 INFO - Vendor: Intel (0x8086) 10:57:20 INFO - Device ID: 0x0a2e 10:57:20 INFO - Revision ID: 0x0009 10:57:20 INFO - Displays: 10:57:20 INFO - Display: 10:57:20 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:57:20 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:57:20 INFO - Display Serial Number: 1600x1200 60 10:57:20 INFO - Main Display: Yes 10:57:20 INFO - Mirror: Off 10:57:20 INFO - Online: Yes 10:57:20 INFO - Rotation: Supported 10:57:20 INFO - Return code: 0 10:57:20 INFO - Running post-action listener: _resource_record_post_action 10:57:20 INFO - [mozharness: 2016-09-07 17:57:20.829349Z] Finished run-tests step (success) 10:57:20 INFO - Running post-run listener: _resource_record_post_run 10:57:20 INFO - Total resource usage - Wall time: 1213s; CPU: 54.0%; Read bytes: 37942272; Write bytes: 704239104; Read time: 379; Write time: 1879 10:57:20 INFO - TinderboxPrint: CPU usage
53.6% 10:57:20 INFO - TinderboxPrint: I/O read bytes / time
37,942,272 / 379 10:57:20 INFO - TinderboxPrint: I/O write bytes / time
704,239,104 / 1,879 10:57:20 INFO - TinderboxPrint: CPU idle
2,251.0 (46.4%) 10:57:20 INFO - TinderboxPrint: CPU system
969.8 (20.0%) 10:57:20 INFO - TinderboxPrint: CPU user
1,632.8 (33.6%) 10:57:20 INFO - TinderboxPrint: Swap in / out
442,626,048 / 0 10:57:20 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 405684224; Write bytes: 394229248; Read time: 29445; Write time: 715 10:57:20 INFO - run-tests - Wall time: 1180s; CPU: 54.0%; Read bytes: 30616576; Write bytes: 295202816; Read time: 285; Write time: 1130 10:57:21 INFO - Running post-run listener: _upload_blobber_files 10:57:21 INFO - Blob upload gear active. 10:57:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:57:21 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 10:57:21 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'] 10:57:21 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 10:57:21 INFO - (blobuploader) - INFO - Open directory for files ... 10:57:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 10:57:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:57:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:57:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 10:57:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:57:23 INFO - (blobuploader) - INFO - Done attempting. 10:57:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 10:57:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:57:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:57:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 10:57:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:57:24 INFO - (blobuploader) - INFO - Done attempting. 10:57:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:57:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:57:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:57:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:57:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:57:28 INFO - (blobuploader) - INFO - Done attempting. 10:57:28 INFO - (blobuploader) - INFO - Iteration through files over. 10:57:28 INFO - Return code: 0 10:57:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:57:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:57:28 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2d415409de6d1052acf00e14445de8adbf40a79ccf8d3b61e37017ad1467e871ac47d59ac62f250cc48710fb3f60ff537094a7981173bd45023808f4ee4bfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c51b70a1d723c76aaa09314ad43416a69ea6e5b4f7f9dc0f9dab1d5637ca17545e3e5065535032fc89efc8e3c341579f0daff1c3d7ba4b087428e90a011ba735", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d85decea1c205bfc15f9b9a4bc19114e09daa67ecd58092d26ccd09fa2bc9f51869ce1ef471fd5f3e00a37da57922ebf190fdbd5738b1e48224222ae06f5dc9"} 10:57:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:57:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:57:28 INFO - Contents: 10:57:28 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fb2d415409de6d1052acf00e14445de8adbf40a79ccf8d3b61e37017ad1467e871ac47d59ac62f250cc48710fb3f60ff537094a7981173bd45023808f4ee4bfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c51b70a1d723c76aaa09314ad43416a69ea6e5b4f7f9dc0f9dab1d5637ca17545e3e5065535032fc89efc8e3c341579f0daff1c3d7ba4b087428e90a011ba735", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d85decea1c205bfc15f9b9a4bc19114e09daa67ecd58092d26ccd09fa2bc9f51869ce1ef471fd5f3e00a37da57922ebf190fdbd5738b1e48224222ae06f5dc9"} 10:57:28 INFO - Running post-run listener: copy_logs_to_upload_dir 10:57:28 INFO - Copying logs to upload dir... 10:57:28 INFO - mkdir: /builds/slave/test/build/upload/logs 10:57:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.499231 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-07 10:57:28.693332) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:57:28.697661) ========= 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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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/fb2d415409de6d1052acf00e14445de8adbf40a79ccf8d3b61e37017ad1467e871ac47d59ac62f250cc48710fb3f60ff537094a7981173bd45023808f4ee4bfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c51b70a1d723c76aaa09314ad43416a69ea6e5b4f7f9dc0f9dab1d5637ca17545e3e5065535032fc89efc8e3c341579f0daff1c3d7ba4b087428e90a011ba735", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d85decea1c205bfc15f9b9a4bc19114e09daa67ecd58092d26ccd09fa2bc9f51869ce1ef471fd5f3e00a37da57922ebf190fdbd5738b1e48224222ae06f5dc9"} build_url:https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011107 build_url: 'https://queue.taskcluster.net/v1/task/c_ZPUck9R9aLHKn5CcJZHA/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/fb2d415409de6d1052acf00e14445de8adbf40a79ccf8d3b61e37017ad1467e871ac47d59ac62f250cc48710fb3f60ff537094a7981173bd45023808f4ee4bfc", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/c51b70a1d723c76aaa09314ad43416a69ea6e5b4f7f9dc0f9dab1d5637ca17545e3e5065535032fc89efc8e3c341579f0daff1c3d7ba4b087428e90a011ba735", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/7d85decea1c205bfc15f9b9a4bc19114e09daa67ecd58092d26ccd09fa2bc9f51869ce1ef471fd5f3e00a37da57922ebf190fdbd5738b1e48224222ae06f5dc9"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:57:28.731048) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:57:28.731408) ========= 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.3IVGa1NQrG/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.0hdsT9uN3i/Listeners TMPDIR=/var/folders/pk/x1kn_b9j39qdgrwqw737_qrh00000w/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.005047 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:57:28.780313) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:57:28.780633) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:57:28.781010) ========= ========= Total master_lag: 0.18 =========