builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0039 starttime: 1473180103.09 results: success (0) buildid: 20160906065554 builduid: 3dbf7cce6d96438093004baa57f113e3 revision: 97633d930c8f10ca5b8c9a7986bc390900f5970a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.091207) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:43.091914) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-09-06 09:41:43.092510) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 2 secs) (at 2016-09-06 09:41:45.425827) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:45.426103) ========= 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.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-06 09:41:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 14.2M=0.001s 2016-09-06 09:41:46 (14.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.653194 ========= master_lag: 0.06 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:46.138699) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:46.139012) ========= 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.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.028404 ========= master_lag: 0.04 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:46.203289) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:41:46.203679) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 97633d930c8f10ca5b8c9a7986bc390900f5970a --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 97633d930c8f10ca5b8c9a7986bc390900f5970a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-06 09:41:46,284 truncating revision to first 12 chars 2016-09-06 09:41:46,284 Setting DEBUG logging. 2016-09-06 09:41:46,284 attempt 1/10 2016-09-06 09:41:46,285 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/97633d930c8f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 09:41:47,385 unpacking tar archive at: fx-team-97633d930c8f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.387799 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-06 09:41:47.613842) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:47.614141) ========= script_repo_revision: 97633d930c8f10ca5b8c9a7986bc390900f5970a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:47.614566) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:47.614838) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 09:41:47.633478) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 52 secs) (at 2016-09-06 09:41:47.633888) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --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-e10s', '--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.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:41:47 INFO - MultiFileLogger online at 20160906 09:41:47 in /builds/slave/test 09:41:47 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:41:47 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:41:47 INFO - {'append_to_log': False, 09:41:47 INFO - 'base_work_dir': '/builds/slave/test', 09:41:47 INFO - 'blob_upload_branch': 'Fx-Team', 09:41:47 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:41:47 INFO - 'branch': 'Fx-Team', 09:41:47 INFO - 'buildbot_json_path': 'buildprops.json', 09:41:47 INFO - 'config_files': ('talos/mac_config.py',), 09:41:47 INFO - 'default_actions': ('clobber', 09:41:47 INFO - 'read-buildbot-config', 09:41:47 INFO - 'download-and-extract', 09:41:47 INFO - 'populate-webroot', 09:41:47 INFO - 'create-virtualenv', 09:41:47 INFO - 'install', 09:41:47 INFO - 'run-tests'), 09:41:47 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:41:47 INFO - 'download_minidump_stackwalk': True, 09:41:47 INFO - 'download_symbols': 'ondemand', 09:41:47 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:41:47 INFO - 'tooltool.py': '/tools/tooltool.py', 09:41:47 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:41:47 INFO - '/tools/misc-python/virtualenv.py')}, 09:41:47 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:41:47 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:41:47 INFO - 'installer_path': 'installer.exe', 09:41:47 INFO - 'log_level': 'info', 09:41:47 INFO - 'log_name': 'talos', 09:41:47 INFO - 'log_to_console': True, 09:41:47 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:41:47 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:41:47 INFO - 'opt_config_files': (), 09:41:47 INFO - 'pip_index': False, 09:41:47 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:47 INFO - 'cmd': ('bash', 09:41:47 INFO - '-c', 09:41:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:47 INFO - 'enabled': True, 09:41:47 INFO - 'halt_on_failure': False, 09:41:47 INFO - 'name': 'check_screen_resolution'},), 09:41:47 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:41:47 INFO - 'cmd': ('bash', 09:41:47 INFO - '-c', 09:41:47 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:41:47 INFO - 'enabled': True, 09:41:47 INFO - 'halt_on_failure': False, 09:41:47 INFO - 'name': 'check_screen_resolution'},), 09:41:47 INFO - 'run_cmd_checks_enabled': True, 09:41:47 INFO - 'sps_profile': False, 09:41:47 INFO - 'sps_profile_interval': 0, 09:41:47 INFO - 'suite': 'dromaeojs-e10s', 09:41:47 INFO - 'system_bits': '32', 09:41:47 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:41:47 INFO - 'title': 't-yosemite-r7-0039', 09:41:47 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:41:47 INFO - 'use_talos_json': True, 09:41:47 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:41:47 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:41:47 INFO - 'work_dir': 'build'} 09:41:47 INFO - [mozharness: 2016-09-06 16:41:47.836245Z] Running clobber step. 09:41:47 INFO - Running pre-action listener: _resource_record_pre_action 09:41:47 INFO - Running main action method: clobber 09:41:47 INFO - rmtree: /builds/slave/test/build 09:41:47 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:41:50 INFO - Running post-action listener: _resource_record_post_action 09:41:50 INFO - [mozharness: 2016-09-06 16:41:50.045476Z] Finished clobber step (success) 09:41:50 INFO - [mozharness: 2016-09-06 16:41:50.045647Z] Running read-buildbot-config step. 09:41:50 INFO - Running pre-action listener: _resource_record_pre_action 09:41:50 INFO - Running main action method: read_buildbot_config 09:41:50 INFO - Using buildbot properties: 09:41:50 INFO - { 09:41:50 INFO - "project": "", 09:41:50 INFO - "product": "firefox", 09:41:50 INFO - "script_repo_revision": "production", 09:41:50 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:41:50 INFO - "repository": "", 09:41:50 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:41:50 INFO - "buildid": "20160906065554", 09:41:50 INFO - "pgo_build": "False", 09:41:50 INFO - "basedir": "/builds/slave/test", 09:41:50 INFO - "buildnumber": 3, 09:41:50 INFO - "slavename": "t-yosemite-r7-0039", 09:41:50 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:41:50 INFO - "platform": "yosemite_r7", 09:41:50 INFO - "scheduler": "tests-fx-team-macosx64-talos-7-3600", 09:41:50 INFO - "branch": "fx-team", 09:41:50 INFO - "repo_path": "integration/fx-team", 09:41:50 INFO - "revision": "97633d930c8f10ca5b8c9a7986bc390900f5970a", 09:41:50 INFO - "stage_platform": "macosx64", 09:41:50 INFO - "builduid": "3dbf7cce6d96438093004baa57f113e3", 09:41:50 INFO - "slavebuilddir": "test" 09:41:50 INFO - } 09:41:50 INFO - Found installer url https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:41:50 INFO - Running post-action listener: _resource_record_post_action 09:41:50 INFO - [mozharness: 2016-09-06 16:41:50.048563Z] Finished read-buildbot-config step (success) 09:41:50 INFO - [mozharness: 2016-09-06 16:41:50.048665Z] Running download-and-extract step. 09:41:50 INFO - Running pre-action listener: _resource_record_pre_action 09:41:50 INFO - Running main action method: download_and_extract 09:41:50 INFO - mkdir: /builds/slave/test/build/tests 09:41: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')]} 09:41:50 INFO - trying https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:41:50 INFO - Downloading https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:41:50 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:41:51 INFO - Downloaded 1199 bytes. 09:41:51 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:41:51 INFO - Using the following test package requirements: 09:41:51 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:41:51 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:41:51 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:41:51 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'jsshell-mac.zip'], 09:41:51 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:41:51 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:41:51 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:41:51 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:41:51 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:41:51 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:41:51 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:41:51 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:41:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:41:52 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:41:59 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 09:41:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:41:59 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:42:06 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:42:06 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:42:07 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')]} 09:42:07 INFO - trying https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:07 INFO - Downloading https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:42:07 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/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 09:42:10 INFO - Downloaded 111493226 bytes. 09:42:10 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:10 INFO - mkdir: /builds/slave/test/properties 09:42:10 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:42:10 INFO - Writing to file /builds/slave/test/properties/build_url 09:42:10 INFO - Contents: 09:42:10 INFO - build_url:https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:42:11 INFO - Running post-action listener: _resource_record_post_action 09:42:11 INFO - Running post-action listener: set_extra_try_arguments 09:42:11 INFO - [mozharness: 2016-09-06 16:42:11.522192Z] Finished download-and-extract step (success) 09:42:11 INFO - [mozharness: 2016-09-06 16:42:11.522330Z] Running populate-webroot step. 09:42:11 INFO - Running pre-action listener: _resource_record_pre_action 09:42:11 INFO - Running main action method: populate_webroot 09:42:11 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:42:11 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:42:11 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:42:11 INFO - '--tppagecycles', 09:42:11 INFO - '7'], 09:42:11 INFO - 'tests': ['tsvgm']}}, 09:42:11 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:42:11 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:42:11 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:42:11 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:42:11 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:11 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:42:11 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['damp', 'tps']}, 09:42:11 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'tests': ['damp', 'tps']}, 09:42:11 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['dromaeo_dom']}, 09:42:11 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:42:11 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['basic_compositor_video']}, 09:42:11 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:42:11 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'other-e10s': {'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['a11yr', 09:42:11 INFO - 'ts_paint', 09:42:11 INFO - 'tpaint', 09:42:11 INFO - 'sessionrestore', 09:42:11 INFO - 'sessionrestore_no_auto_restore', 09:42:11 INFO - 'tabpaint']}, 09:42:11 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['tsvgx', 09:42:11 INFO - 'tsvgr_opacity', 09:42:11 INFO - 'tart', 09:42:11 INFO - 'tscrollx', 09:42:11 INFO - 'cart']}, 09:42:11 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:42:11 INFO - 'tsvgr_opacity', 09:42:11 INFO - 'tart', 09:42:11 INFO - 'tscrollx', 09:42:11 INFO - 'cart']}, 09:42:11 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'talos_options': ['--disable-e10s'], 09:42:11 INFO - 'tests': ['tp5o']}, 09:42:11 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'tests': ['tp5o']}, 09:42:11 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'talos_options': ['--disable-e10s', 09:42:11 INFO - '--xperf_path', 09:42:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:11 INFO - 'tests': ['tp5n']}, 09:42:11 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:42:11 INFO - 'talos_options': ['--xperf_path', 09:42:11 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:42:11 INFO - 'tests': ['tp5n']}}, 09:42:11 INFO - 'talos.zip': {'path': '', 09:42:11 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:42:11 INFO - Running post-action listener: _resource_record_post_action 09:42:11 INFO - [mozharness: 2016-09-06 16:42:11.541202Z] Finished populate-webroot step (success) 09:42:11 INFO - [mozharness: 2016-09-06 16:42:11.541338Z] Running create-virtualenv step. 09:42:11 INFO - Running pre-action listener: _resource_record_pre_action 09:42:11 INFO - Running main action method: create_virtualenv 09:42:11 INFO - Creating virtualenv /builds/slave/test/build/venv 09:42:11 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 09:42:11 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:42:11 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:42:11 INFO - Using real prefix '/tools/python27' 09:42:11 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:42:12 INFO - Installing distribute.............................................................................................................................................................................................done. 09:42:15 INFO - Installing pip.................done. 09:42:15 INFO - Return code: 0 09:42:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:42:15 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')]} 09:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:15 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')]} 09:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:15 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:15 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 09:42:15 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 09:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:15 INFO - 'HOME': '/Users/cltbld', 09:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:15 INFO - 'LOGNAME': 'cltbld', 09:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:15 INFO - 'MOZ_NO_REMOTE': '1', 09:42:15 INFO - 'NO_EM_RESTART': '1', 09:42:15 INFO - 'PAGER': '/bin/cat', 09:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:15 INFO - 'PWD': '/builds/slave/test', 09:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:15 INFO - 'SHELL': '/bin/bash', 09:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:15 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:15 INFO - 'USER': 'cltbld', 09:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:15 INFO - 'XPC_FLAGS': '0x0', 09:42:15 INFO - 'XPC_SERVICE_NAME': '0', 09:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:15 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 09:42:15 INFO - Cleaning up... 09:42:15 INFO - Return code: 0 09:42:15 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:42:15 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')]} 09:42:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:15 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')]} 09:42:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:15 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:15 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 09:42:15 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 09:42:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:15 INFO - 'HOME': '/Users/cltbld', 09:42:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:15 INFO - 'LOGNAME': 'cltbld', 09:42:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:15 INFO - 'MOZ_NO_REMOTE': '1', 09:42:15 INFO - 'NO_EM_RESTART': '1', 09:42:15 INFO - 'PAGER': '/bin/cat', 09:42:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:15 INFO - 'PWD': '/builds/slave/test', 09:42:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:15 INFO - 'SHELL': '/bin/bash', 09:42:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:15 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:15 INFO - 'USER': 'cltbld', 09:42:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:15 INFO - 'XPC_FLAGS': '0x0', 09:42:15 INFO - 'XPC_SERVICE_NAME': '0', 09:42:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:15 INFO - Downloading/unpacking psutil>=3.1.1 09:42:15 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 09:42:15 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 09:42:15 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 09:42:15 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 09:42:15 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 09:42:15 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 09:42:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:42:18 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:18 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:18 INFO - Installing collected packages: psutil 09:42:18 INFO - Running setup.py install for psutil 09:42:18 INFO - building 'psutil._psutil_osx' extension 09:42:18 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 09:42:19 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 09:42:19 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 09:42:19 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 09:42:19 INFO - building 'psutil._psutil_posix' extension 09:42:19 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 09:42:19 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:42:19 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:42:19 INFO - ^ 09:42:19 INFO - 1 warning generated. 09:42:19 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 09:42:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:42:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:42:20 INFO - Successfully installed psutil 09:42:20 INFO - Cleaning up... 09:42:20 INFO - Return code: 0 09:42:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:42:20 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')]} 09:42:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:20 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')]} 09:42:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:20 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:20 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 09:42:20 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 09:42:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:20 INFO - 'HOME': '/Users/cltbld', 09:42:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:20 INFO - 'LOGNAME': 'cltbld', 09:42:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:20 INFO - 'MOZ_NO_REMOTE': '1', 09:42:20 INFO - 'NO_EM_RESTART': '1', 09:42:20 INFO - 'PAGER': '/bin/cat', 09:42:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:20 INFO - 'PWD': '/builds/slave/test', 09:42:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:20 INFO - 'SHELL': '/bin/bash', 09:42:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:20 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:20 INFO - 'USER': 'cltbld', 09:42:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:20 INFO - 'XPC_FLAGS': '0x0', 09:42:20 INFO - 'XPC_SERVICE_NAME': '0', 09:42:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:20 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:42:20 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 09:42:20 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 09:42:20 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 09:42:20 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 09:42:20 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 09:42:20 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 09:42:22 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:42:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:42:22 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:42:22 INFO - Installing collected packages: mozsystemmonitor 09:42:22 INFO - Running setup.py install for mozsystemmonitor 09:42:23 INFO - Successfully installed mozsystemmonitor 09:42:23 INFO - Cleaning up... 09:42:23 INFO - Return code: 0 09:42:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:23 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:23 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 09:42:23 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 09:42:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:23 INFO - 'HOME': '/Users/cltbld', 09:42:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:23 INFO - 'LOGNAME': 'cltbld', 09:42:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:23 INFO - 'MOZ_NO_REMOTE': '1', 09:42:23 INFO - 'NO_EM_RESTART': '1', 09:42:23 INFO - 'PAGER': '/bin/cat', 09:42:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:23 INFO - 'PWD': '/builds/slave/test', 09:42:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:23 INFO - 'SHELL': '/bin/bash', 09:42:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:23 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:23 INFO - 'USER': 'cltbld', 09:42:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:23 INFO - 'XPC_FLAGS': '0x0', 09:42:23 INFO - 'XPC_SERVICE_NAME': '0', 09:42:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:23 INFO - Downloading/unpacking jsonschema==2.5.1 09:42:23 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 09:42:23 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 09:42:23 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 09:42:23 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 09:42:23 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 09:42:23 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 09:42:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:42:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:25 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 09:42:26 INFO - Downloading functools32-3.2.3-2.tar.gz 09:42:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:42:26 INFO - warning: no files found matching '*.txt' 09:42:26 INFO - no previously-included directories found matching 'build' 09:42:26 INFO - no previously-included directories found matching 'dist' 09:42:26 INFO - no previously-included directories found matching '.git*' 09:42:26 INFO - Installing collected packages: jsonschema, functools32 09:42:26 INFO - Running setup.py install for functools32 09:42:26 INFO - warning: no files found matching '*.txt' 09:42:26 INFO - no previously-included directories found matching 'build' 09:42:26 INFO - no previously-included directories found matching 'dist' 09:42:26 INFO - no previously-included directories found matching '.git*' 09:42:26 INFO - Successfully installed jsonschema functools32 09:42:26 INFO - Cleaning up... 09:42:26 INFO - Return code: 0 09:42:26 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:42:26 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')]} 09:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 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')]} 09:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:26 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 09:42:26 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 09:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:26 INFO - 'HOME': '/Users/cltbld', 09:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:26 INFO - 'LOGNAME': 'cltbld', 09:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:26 INFO - 'MOZ_NO_REMOTE': '1', 09:42:26 INFO - 'NO_EM_RESTART': '1', 09:42:26 INFO - 'PAGER': '/bin/cat', 09:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:26 INFO - 'PWD': '/builds/slave/test', 09:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:26 INFO - 'SHELL': '/bin/bash', 09:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:26 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:26 INFO - 'USER': 'cltbld', 09:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:26 INFO - 'XPC_FLAGS': '0x0', 09:42:26 INFO - 'XPC_SERVICE_NAME': '0', 09:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:42:26 INFO - Cleaning up... 09:42:26 INFO - Return code: 0 09:42:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:42:26 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')]} 09:42:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 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')]} 09:42:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:26 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:26 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 09:42:26 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 09:42:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:26 INFO - 'HOME': '/Users/cltbld', 09:42:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:26 INFO - 'LOGNAME': 'cltbld', 09:42:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:26 INFO - 'MOZ_NO_REMOTE': '1', 09:42:26 INFO - 'NO_EM_RESTART': '1', 09:42:26 INFO - 'PAGER': '/bin/cat', 09:42:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:26 INFO - 'PWD': '/builds/slave/test', 09:42:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:26 INFO - 'SHELL': '/bin/bash', 09:42:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:26 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:26 INFO - 'USER': 'cltbld', 09:42:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:26 INFO - 'XPC_FLAGS': '0x0', 09:42:26 INFO - 'XPC_SERVICE_NAME': '0', 09:42:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:27 INFO - Downloading/unpacking blobuploader==1.2.4 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:27 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 09:42:28 INFO - Downloading blobuploader-1.2.4.tar.gz 09:42:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:42:28 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:28 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 09:42:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:42:30 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:42:30 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 09:42:30 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 09:42:30 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 09:42:30 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 09:42:30 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 09:42:30 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 09:42:30 INFO - Downloading docopt-0.6.1.tar.gz 09:42:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:42:30 INFO - Installing collected packages: blobuploader, requests, docopt 09:42:30 INFO - Running setup.py install for blobuploader 09:42:30 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:42:30 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:42:30 INFO - Running setup.py install for requests 09:42:31 INFO - Running setup.py install for docopt 09:42:31 INFO - Successfully installed blobuploader requests docopt 09:42:31 INFO - Cleaning up... 09:42:31 INFO - Return code: 0 09:42:31 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:31 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:31 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 09:42:31 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 09:42:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:31 INFO - 'HOME': '/Users/cltbld', 09:42:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:31 INFO - 'LOGNAME': 'cltbld', 09:42:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:31 INFO - 'MOZ_NO_REMOTE': '1', 09:42:31 INFO - 'NO_EM_RESTART': '1', 09:42:31 INFO - 'PAGER': '/bin/cat', 09:42:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:31 INFO - 'PWD': '/builds/slave/test', 09:42:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:31 INFO - 'SHELL': '/bin/bash', 09:42:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:31 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:31 INFO - 'USER': 'cltbld', 09:42:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:31 INFO - 'XPC_FLAGS': '0x0', 09:42:31 INFO - 'XPC_SERVICE_NAME': '0', 09:42:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-VSif0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-NVvm9p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:31 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-TlZWHY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-W5MgGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YbC1XR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-pPbSO_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-arwfvn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-8pgxgq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YRsG84-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-J9draT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-xrNwTM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:32 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ln6Uzm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-m9YX27-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-EX2KvX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ihucxZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-2gaTOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:33 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-a6i0VM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:33 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:42:33 INFO - Running setup.py install for manifestparser 09:42:33 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:42:33 INFO - Running setup.py install for mozcrash 09:42:33 INFO - Running setup.py install for mozdebug 09:42:33 INFO - Running setup.py install for mozdevice 09:42:34 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Running setup.py install for mozfile 09:42:34 INFO - Running setup.py install for mozhttpd 09:42:34 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Running setup.py install for mozinfo 09:42:34 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Running setup.py install for mozInstall 09:42:34 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:42:34 INFO - Running setup.py install for mozleak 09:42:34 INFO - Running setup.py install for mozlog 09:42:35 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Running setup.py install for moznetwork 09:42:35 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Running setup.py install for mozprocess 09:42:35 INFO - Running setup.py install for mozprofile 09:42:35 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Running setup.py install for mozrunner 09:42:35 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:42:35 INFO - Running setup.py install for mozscreenshot 09:42:35 INFO - Running setup.py install for moztest 09:42:35 INFO - Running setup.py install for mozversion 09:42:36 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:42:36 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:42:36 INFO - Cleaning up... 09:42:36 INFO - Return code: 0 09:42:36 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:42:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:36 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:36 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 09:42:36 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 09:42:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:36 INFO - 'HOME': '/Users/cltbld', 09:42:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:36 INFO - 'LOGNAME': 'cltbld', 09:42:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:36 INFO - 'MOZ_NO_REMOTE': '1', 09:42:36 INFO - 'NO_EM_RESTART': '1', 09:42:36 INFO - 'PAGER': '/bin/cat', 09:42:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:36 INFO - 'PWD': '/builds/slave/test', 09:42:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:36 INFO - 'SHELL': '/bin/bash', 09:42:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:36 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:36 INFO - 'USER': 'cltbld', 09:42:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:36 INFO - 'XPC_FLAGS': '0x0', 09:42:36 INFO - 'XPC_SERVICE_NAME': '0', 09:42:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-tk60nO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-HTGQBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-YBJe4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-BHS7VF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-DSNMOf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:42:36 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)) 09:42:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:42:36 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-hQvIgS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-CUdWbD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-Dy9nkF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-u0WFAz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-ZWmYl7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-O1RKIG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-rHrUS6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-gpgFyz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-f3XWJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-BtcY_4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:42:37 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)) 09:42:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:42:37 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-hwNnk8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:42:38 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)) 09:42:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:42:38 INFO - Running setup.py (path:/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/pip-QuVVR0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:42:38 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)) 09:42:38 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)) 09:42:38 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)) 09:42:38 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)) 09:42:38 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)) 09:42:38 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)) 09:42:38 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:42:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:42:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:42:40 INFO - Downloading blessings-1.6.tar.gz 09:42:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:42:40 INFO - Installing collected packages: blessings 09:42:40 INFO - Running setup.py install for blessings 09:42:40 INFO - Successfully installed blessings 09:42:40 INFO - Cleaning up... 09:42:41 INFO - Return code: 0 09:42:41 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:41 INFO - Reading from file tmpfile_stdout 09:42:41 INFO - Current package versions: 09:42:41 INFO - blessings == 1.6 09:42:41 INFO - blobuploader == 1.2.4 09:42:41 INFO - docopt == 0.6.1 09:42:41 INFO - functools32 == 3.2.3-2 09:42:41 INFO - jsonschema == 2.5.1 09:42:41 INFO - manifestparser == 1.1 09:42:41 INFO - mozInstall == 1.12 09:42:41 INFO - mozcrash == 0.17 09:42:41 INFO - mozdebug == 0.1 09:42:41 INFO - mozdevice == 0.48 09:42:41 INFO - mozfile == 1.2 09:42:41 INFO - mozhttpd == 0.7 09:42:41 INFO - mozinfo == 0.9 09:42:41 INFO - mozleak == 0.1 09:42:41 INFO - mozlog == 3.3 09:42:41 INFO - moznetwork == 0.27 09:42:41 INFO - mozprocess == 0.23 09:42:41 INFO - mozprofile == 0.28 09:42:41 INFO - mozrunner == 6.12 09:42:41 INFO - mozscreenshot == 0.1 09:42:41 INFO - mozsystemmonitor == 0.3 09:42:41 INFO - moztest == 0.7 09:42:41 INFO - mozversion == 1.4 09:42:41 INFO - psutil == 3.1.1 09:42:41 INFO - requests == 1.2.3 09:42:41 INFO - wsgiref == 0.1.2 09:42:41 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:41 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 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:41 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 09:42:41 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 09:42:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:41 INFO - 'HOME': '/Users/cltbld', 09:42:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:41 INFO - 'LOGNAME': 'cltbld', 09:42:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:41 INFO - 'MOZ_NO_REMOTE': '1', 09:42:41 INFO - 'NO_EM_RESTART': '1', 09:42:41 INFO - 'PAGER': '/bin/cat', 09:42:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:41 INFO - 'PWD': '/builds/slave/test', 09:42:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:41 INFO - 'SHELL': '/bin/bash', 09:42:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:41 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:41 INFO - 'USER': 'cltbld', 09:42:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:41 INFO - 'XPC_FLAGS': '0x0', 09:42:41 INFO - 'XPC_SERVICE_NAME': '0', 09:42:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 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)) 09:42:41 INFO - Cleaning up... 09:42:41 INFO - Return code: 0 09:42:41 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:42: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')]} 09:42:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:42:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42: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')]} 09:42:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:42:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:42:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a86a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101b1b618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa298db5370>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, '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.sh3i5z6aCL/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.H4gDsifGIX/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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 09:42:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:42:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:42:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:42:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:42:41 INFO - 'HOME': '/Users/cltbld', 09:42:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:42:41 INFO - 'LOGNAME': 'cltbld', 09:42:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:42:41 INFO - 'MOZ_NO_REMOTE': '1', 09:42:41 INFO - 'NO_EM_RESTART': '1', 09:42:41 INFO - 'PAGER': '/bin/cat', 09:42:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:42:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:42:41 INFO - 'PWD': '/builds/slave/test', 09:42:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:42:41 INFO - 'SHELL': '/bin/bash', 09:42:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:42:41 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:42:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:42:41 INFO - 'USER': 'cltbld', 09:42:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:42:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:42:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:42:41 INFO - 'XPC_FLAGS': '0x0', 09:42:41 INFO - 'XPC_SERVICE_NAME': '0', 09:42:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:42:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:42:41 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:42:41 INFO - Cleaning up... 09:42:41 INFO - Return code: 0 09:42:41 INFO - Running post-action listener: _resource_record_post_action 09:42:41 INFO - Running post-action listener: _start_resource_monitoring 09:42:41 INFO - Starting resource monitoring. 09:42:41 INFO - [mozharness: 2016-09-06 16:42:41.896618Z] Finished create-virtualenv step (success) 09:42:41 INFO - [mozharness: 2016-09-06 16:42:41.897599Z] Running install step. 09:42:41 INFO - Running pre-action listener: _resource_record_pre_action 09:42:41 INFO - Running main action method: install 09:42:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:42:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:42:42 INFO - Reading from file tmpfile_stdout 09:42:42 INFO - Detecting whether we're running mozinstall >=1.0... 09:42:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:42:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:42:42 INFO - Reading from file tmpfile_stdout 09:42:42 INFO - Output received: 09:42:42 INFO - Usage: mozinstall [options] installer 09:42:42 INFO - Options: 09:42:42 INFO - -h, --help show this help message and exit 09:42:42 INFO - -d DEST, --destination=DEST 09:42:42 INFO - Directory to install application into. [default: 09:42:42 INFO - "/builds/slave/test"] 09:42:42 INFO - --app=APP Application being installed. [default: firefox] 09:42:42 INFO - mkdir: /builds/slave/test/build/application 09:42:42 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'] 09:42:42 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 09:43:16 INFO - Reading from file tmpfile_stdout 09:43:16 INFO - Output received: 09:43:16 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:43:16 INFO - Running post-action listener: _resource_record_post_action 09:43:16 INFO - [mozharness: 2016-09-06 16:43:16.341933Z] Finished install step (success) 09:43:16 INFO - [mozharness: 2016-09-06 16:43:16.342079Z] Running run-tests step. 09:43:16 INFO - Running pre-action listener: _resource_record_pre_action 09:43:16 INFO - Running main action method: run_tests 09:43:16 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:43:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:43:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:43:16 INFO - 2016-09-06 09:43:16.356 screenresolution[1644:8228] starting screenresolution argv=screenresolution get 09:43:16 INFO - 2016-09-06 09:43:16.359 screenresolution[1644:8228] Display 0: 1600x1200x32@60 09:43:16 INFO - 2016-09-06 09:43:16.365 screenresolution[1645:8230] starting screenresolution argv=screenresolution list 09:43:16 INFO - Available Modes on Display 0 09:43:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:43:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:43:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:43:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:43:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:43:16 INFO - Intel Iris: 09:43:16 INFO - Chipset Model: Intel Iris 09:43:16 INFO - Type: GPU 09:43:16 INFO - Bus: Built-In 09:43:16 INFO - VRAM (Dynamic, Max): 1536 MB 09:43:16 INFO - Vendor: Intel (0x8086) 09:43:16 INFO - Device ID: 0x0a2e 09:43:16 INFO - Revision ID: 0x0009 09:43:16 INFO - Displays: 09:43:16 INFO - Display: 09:43:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:43:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:43:16 INFO - Display Serial Number: 1600x1200 60 09:43:16 INFO - Main Display: Yes 09:43:16 INFO - Mirror: Off 09:43:16 INFO - Online: Yes 09:43:16 INFO - Rotation: Supported 09:43:16 INFO - Return code: 0 09:43:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:43:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:43:16 INFO - Python 2.7.3 09:43:16 INFO - Return code: 0 09:43:16 INFO - grabbing minidump binary from tooltool 09:43:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:43:16 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 0x1019ead30>, '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 0x10174fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1019e2b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:43:16 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 09:43:16 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 09:43:16 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 09:43:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:43:16 INFO - Return code: 0 09:43:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:43:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:43:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:43:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:43:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:43:16 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-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:43:16 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-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0039 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:43:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.sh3i5z6aCL/Render', 09:43:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:43:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:43:16 INFO - 'HOME': '/Users/cltbld', 09:43:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:43:16 INFO - 'LOGNAME': 'cltbld', 09:43:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:43:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:43:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:43:16 INFO - 'MOZ_NO_REMOTE': '1', 09:43:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:43:16 INFO - 'NO_EM_RESTART': '1', 09:43:16 INFO - 'PAGER': '/bin/cat', 09:43:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:43:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:43:16 INFO - 'PWD': '/builds/slave/test', 09:43:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:43:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:43:16 INFO - 'SHELL': '/bin/bash', 09:43:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.H4gDsifGIX/Listeners', 09:43:16 INFO - 'TMPDIR': '/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/', 09:43:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:43:16 INFO - 'USER': 'cltbld', 09:43:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:43:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:43:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:43:16 INFO - 'XPC_FLAGS': '0x0', 09:43:16 INFO - 'XPC_SERVICE_NAME': '0', 09:43:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:43:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0039', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:43:16 INFO - mozversion application_buildid: 20160906065554 09:43:16 INFO - mozversion application_changeset: 97633d930c8f10ca5b8c9a7986bc390900f5970a 09:43:16 INFO - mozversion application_display_name: Nightly 09:43:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:43:16 INFO - mozversion application_name: Firefox 09:43:16 INFO - mozversion application_remotingname: firefox 09:43:16 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:43:16 INFO - mozversion application_vendor: Mozilla 09:43:16 INFO - mozversion application_version: 51.0a1 09:43:16 INFO - mozversion platform_buildid: 20160906065554 09:43:16 INFO - mozversion platform_changeset: 97633d930c8f10ca5b8c9a7986bc390900f5970a 09:43:16 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:43:16 INFO - mozversion platform_version: 51.0a1 09:43:16 INFO - using testdate: 1473180196 09:43:16 INFO - actual date: 1473180196 09:43:16 INFO - starting webserver on 'localhost:49273' 09:43:16 INFO - SUITE-START | Running 2 tests 09:43:16 INFO - TEST-START | dromaeo_css 09:43:16 INFO - operating with platform_type : mac_ 09:43:16 INFO - Initialising browser for dromaeo_css test... 09:43:16 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpuhAd04/profile http://localhost:49273/getInfo.html) 09:43:19 INFO - PROCESS | 1655 | __metrics Screen width/height:1600/1200 09:43:19 INFO - PROCESS | 1655 | colorDepth:24 09:43:19 INFO - PROCESS | 1655 | Browser inner width/height: 1024/689 09:43:19 INFO - PROCESS | 1655 | __metrics 09:43:19 ERROR - PROCESS | 1655 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 09:43:20 INFO - TEST-INFO | 1655: exit 0 09:43:20 INFO - Browser initialized. 09:43:20 INFO - Running cycle 1/1 for dromaeo_css test... 09:43:20 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmpuhAd04/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:43:32 INFO - PROCESS | 1659 | RSS: Main: 243994624 09:43:32 INFO - PROCESS | 1659 | 09:44:22 INFO - PROCESS | 1659 | 1473180262299 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] 09:46:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49273/tests/dromaeo/cssquery-ext.html) 09:46:35 INFO - PROCESS | 1659 | RSS: Main: 262225920 09:46:35 INFO - PROCESS | 1659 | 09:49:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-ext.html (next: http://localhost:49273/tests/dromaeo/cssquery-jquery.html) 09:49:38 INFO - PROCESS | 1659 | RSS: Main: 271155200 09:49:38 INFO - PROCESS | 1659 | 09:52:40 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49273/tests/dromaeo/cssquery-mootools.html) 09:52:41 INFO - PROCESS | 1659 | RSS: Main: 274583552 09:52:41 INFO - PROCESS | 1659 | 09:55:42 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49273/tests/dromaeo/cssquery-prototype.html) 09:55:43 INFO - PROCESS | 1659 | RSS: Main: 270766080 09:55:43 INFO - PROCESS | 1659 | 09:58:46 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49273/tests/dromaeo/cssquery-yui.html) 09:58:47 INFO - PROCESS | 1659 | RSS: Main: 275337216 09:58:47 INFO - PROCESS | 1659 | 10:01:49 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49273/tests/dromaeo/cssquery-yui.html (next: http://localhost:49273/tests/dromaeo/cssquery-dojo.html) 10:01:50 INFO - PROCESS | 1659 | RSS: Main: 278908928 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | __start_tp_report 10:01:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 10:01:50 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 10:01:50 INFO - PROCESS | 1659 | |i|pagename|runs| 10:01:50 INFO - PROCESS | 1659 | |0;dojo.html;3277.9836100819493;3342.3148254607804;3373.359061778262;3375.493675948608;3395.2021275000384;4549.839790853336;5312.814051508198;5500.28496295481;5579.721013949302;5588.3852776194635;5207.375114986197;5551.916721249185;5584.385717571074;5617.831465056045;5624.437556244374;5079.187330027178;5171.276021357013;5175.715335656538;5216.087184742674;5321.946780532203;5209.895802083956;5233;5234.293370394986;5252.52727254547;5274.129768588178;3823.8279277432584;4985.875353116165;5115.258287548322;5176.094183517907;5213.947860521384;5122.513361230677;5144.639875208737;5151.2530683050745;5174.948250517485;5227.2159176123505;3572.7141828653644;3917;4634.374359461463;4657.743824089674;5127.769250383704;4187.350960601112;5024.522670346311;5553.8333849984565;5558.166275058733;5654.462826031561;5287.709175995319;6141.877162456726;6157.261128664589;6157.692115394258;6169.105479705418;6060.575759696823;6195.7521699131985;6206.441420272152;6231.59494632852;6232.376762323776;5195.6622819516615;5506.366767821672;5617.410171931929;5652.463016013472;5656.8020119295625;6159.291681456601;6166.4758495527485;6173.043178307369;6175.042868355322;6178.196834411498;5731.140328950607;5831.737571809279;5850.678212698257;5857.531397488191;5878.412158784173;4838.782254798542;6029.155918171459;6047.637141771508;6070.119832624245;6115.6330620162935;5973.790917317873;6079.939200607937;6085.513158947274;6091.269047714302;6109.175261339662;4902.852914412573;6075.908861367083;6121.540884433685;6166;6169.907451388232;4761.666683332133;4861.416630004422;4876.317315575751;4881.389826271716;4901.852944411673;4321.524632290446;4392.407025051577;4407.823687052482;4409.933850992237;4432.689711720149;4439.911201775946;5300.549453296513;5407.864803379947;5460.235567020621;5528.806491772769;5898.1152827076285;5918.319393269829;5957.761689532457;6061.484773794277;6110.480609148272;4518.864434066983;5391.137418013178;5442.265294185234;5452.972735136299;5510.090835012258;5255.816046438356;5255.842324730264;5265.868353291198;5266.262723218752;5275.024120537712;6240.875182496325;6252.906206406817;6254.6872656366995;6258.061290806416;6272.843178920563;5865.208196893364;6022.879542409215;6080.7567697291615;6116.694165291807;6125.510168486406;6203.7828675996125;6248.750049997949;6251.749930002749;6254.21822272216;6255.093011513214;5943.465088142088;5998.580099393001;6036.336003039574;6060.424259695322;6076.817695469143;6103.943827031667;6105.421132926843;6110.682750901629;6114.692773002257;6124.625058545555;5906.438888305604;5919.852003699942;5921.111833225051;5924.200232968494;5960.254968128984;4461.598456138963;5729.942700573108;5866.911996319973;5874.295084589877;5883.911741323798;5673.801416950387;5676.779492408987;5695.45893140151;5748.223989761329;5755.856103597276;5861.413858614104;5890.2342695449315;5928;5928.34788173292;5950.9702451487465;5608.775648974159;5677.659340439587;5685.061964775766;5711.571632127524;5750.741216645345 10:01:50 INFO - PROCESS | 1659 | |1;ext.html;490.6589920005595;532.5154109760119;535.3602445078133;540.9729513524328;541.9648471419587;35484;40336;40369.39445908314;40430.99999999996;40531.18937621253;39080;40789.980250493754;41101.73940433275;41122.58877411225;41137.58862411375;9540.952295238514;9754;9771.169450596672;9778.168855647244;9786.510674466284;9608.2313414927;9700.75748106296;9702.514874256294;9704.07811257933;9716.31985760997;9670.4197748135;9696.660616878411;9701.514924253794;9712.951435242814;9716.951415242915;9651.855222171673;9679.274054445874;9692.176165025981;9699.90300096997;9701.854472182888;9518.619255229782;9574.521273936345;9579.856302155473;9646;9650.662226822098;39140.41289380661;39280.80359598184;39379.21241575152;39529.60470395288;39567;3194.632617249023;3231.2568109334984;3299.554560134375;3600.3519366514015;3852.8073596320073;29961.850190748908;30184.547231791323;30295.545566816516;30510.84744576263;31304.8434757827;3234.741220702338;3236.773425860191;3245.5618491503583;3249.4800831867024;3342.412354131768;465624;469805;471405.6429717864;471624;472912;58276;58725.41274587285;58768;58851.41148588545;58891.11663325054;33561;34194.658053419145;56596.71701641465;56774.43225567774;56884.43115568791;13570.457181712818;15968;16490.34038638465;16532.09073500945;16546.503604891874;12632.052596055333;12708.872911270953;12732.490700371882;12742.299173545358;12770;18886.86678799249;18890.52773680669;18907.52731181704;18944.71582926257;18989.050547472572;7861.213878612208;8006.9599652002535;8049.758507244792;8074.549678966674;8083.757487275391;820.3027426687268;962.8748262725801;975.5171190260867;985.0199051943363;995.183949161695;25363;26336.34159146037;30004.399912002074;30103;30229;924.4638109896379;1007.0835539659022;1020.8417695257247;1037.647199952005;1145.5990403358924;14679;15189;15195.392184312725;15478.148701821283;15547.689046219239;975264;985598;987053;987248;988723.0563847278;58005;58629;58661.41338586645;58739.41260587425;59004.704976474844;1331.7602831490424;1336.5054929676082;1370.595674276071;1373.1280636795707;1377.4008306386754;28555.42889142247;29970.500302942775;30631.387372252877;30853;31026.379472409975;44673.659790206344;47444.52555474539;49895.75052124716;50806.74596627141;50874.728131797005;625055;627840;632975;634028;634170;26474.735252648;26843.86578067097;26855;26903.461930761667;26963.05629302965;45356.77321613371;45701.54298456973;45775;47166.52833471621;47206.29190562213;38520;38643.613563864004;38854.028649283995;39180.804095979336;39894.40158397568;27957.860210698815;37589.248215036096;37773;37911.62088379081;38928.80535597304;28782;35590;37008;37033;37229;30957.535636965014;33711.74605702635;33967;34056.14859628529;34412;20533.794662053187;24967.375815604755;25003.249902502954;25131.869065891715;25282.87358563269 10:01:50 INFO - PROCESS | 1659 | |2;jquery.html;313.46241196348274;314.27559475409174;334.8995301409578;376.9114258149335;380.78675941472795;19237.807621923777;19745.50636234093;20639.484012899684;20681.586368272645;20939.581208375825;11568.074554035678;16489.34042638293;19581.314653987112;20398.286059987902;20445.182192712273;5395.7841686332595;5477.534409575191;5600.803971860985;5610.579206559504;5631.380548139701;5463.7814487420455;5471.015217260891;5498.505134537891;5500.724963751816;5557.638753481031;5466.262054622614;5508.586855985797;5520.723963801794;5526.751296191661;5555.194496797962;5332.840014799543;5425.484578964992;5484.122540393518;5548.278723765905;5548.306461692288;5360.142377219626;5369.087255166631;5372.167314066326;5372.54333381663;5376.704281264529;12691.682707932376;17380.826191738175;18622.720659190123;24778.38054048645;25487.107951221617;2154.956900861974;2251.34710933829;2257.1874125314876;2620.7903367730537;2644.6429731986127;10165.237607179492;10421.27051106423;12279.38603069843;14811.599409252347;15728.528144155694;1805.3049575913237;1890.9599720154;1892.8201820826998;1897.7438045863769;1898.1872361559474;123522.38238808748;126532;127187.36406317909;128071.99999999907;128137.9048304695;11381.317120972602;11395.715107122389;11510.482028308747;11642.6507204784;11787.057035437145;14313.928430357782;14386.928065359607;14394.640133996734;14429.567112986628;14431.855681443261;8356.791080222922;8513.829723405497;8517.148285171434;8519.659213631508;8671.913280867237;8432.536210508359;8437.493750374875;8440.898916291128;8539.06070332263;8566.271866891384;4057.4928133983253;4285.485741710952;4473;4535.387722657399;4580.312953057001;1901;1922.0197699173243;1947.221111555361;1948.814862588052;1953.8925359105385;830.6760363458257;978.5889926231;1006.3659894266565;1021.5862575656871;1037.901399367059;20184.798152018586;20967.47581310449;21066.683999740017;21126.47183820386;21255.468613284793;773.4779024158782;815.8735396013627;840.8864803251605;939.534930209537;1007.450939238117;8068.3948703847445;8900.777480563038;10759.946200269105;11185.384803835705;11291.53210082684;459133;554535;591145;601347;605556;103200;120587.19119213219;136709.31645341907;140234;142748;941.9293552983416;942.9009953954874;947.3084648206848;1001.6143784642819;1015.8374660054355;18472.72290915664;19141.330053448062;19166.04169791477;19283.421497355102;19364.32224872179;42021.78989105035;50850.96596136261;52663.736681316346;53319.7334013343;54505.454945451624;76049.61975190089;81067.59466202867;81387.59306203431;81887.5905620492;82228;7647.961760191163;7899.486533375312;7963.482373645695;8037.638306276349;8056.234657707509;12044.638660840188;12291.32397718134;12391.070669699628;12592;12932.612021639367;9632.181264592587;10888.891111088788;11304.508518087723;11400.543978240778;11680.824787628362;10406.375617462976;10894.727631809268;11224;11296.717582060513;11538.769224615293;10866.456677166268;11309.095272378025;11326.546938122383;11801.409929503663;11818.93168695492;8035.316998055231;8052.597370131587;8149.79625509367;8225.876611850706;8277.62750676233;8595.226429621363;10938.56245750161;10945.83581246266;11314.094872410024;11363 10:01:50 INFO - PROCESS | 1659 | |3;mootools.html;1522.2084516051652;1558.454540910681;1756.8053723468045;1861.6276744651072;1896.0993528074157;7822.647980840861;8268.669253229871;8512.91487085129;8785.077566855476;8811.030786613468;8629.266512346458;8685.565721713921;8716.738497845075;8719.564021798917;8749.606267717953;2345.1323010486144;2377.1561095811;2388.10446082719;2389.438481956739;2396.0415833666584;2217.7449593296737;2243.214874793817;2259.2318611672026;2262.1177740681146;2268.330842401487;2325.790678838904;2370.8340416170877;2385.510970251103;2388.319328991238;2389.486260454002;1809.8190180981928;2218.7115674962315;2224.799768020886;2227.6992605998144;2232.944176395595;1942.9125689344014;2356.4697942962866;2361.315218586608;2370.7392186859433;2375.064322577094;7178;8678.609462574137;8727.258183054448;8768.035516093225;8804.64781408743;5173.482651734834;5303.204519322094;5331.253624492574;5342.866428339284;5344.599155063347;3353.765236433451;3483.372992861284;3530.4174811156126;3603.891883243507;3660.3411385950517;1092.322759888874;1095.6001059613263;1098.549594666183;1099.4667586220676;1119.6249256499082;11328.716782080432;11356.999999999918;11441.771164576661;11454.54181832734;11460.885391145981;4545.318202269686;4551.7724113794175;4602.585767280893;4788.186008378584;4796.880077998078;9076.682316118904;9757.316987810786;9768.413895166314;9823.165030972448;9839.458829764288;4104.343305071175;4546.65900057497;5129.487051294916;5309.309789727311;5311.49540793624;4362.76004819738;4689.038747056845;4814.542618451242;4826.01066781309;4858.732769697701;3877.7091718121255;3954.189391174802;3985.362342025262;4006.0185254612225;4046.231216068996;1302.3683513495946;1309.594025851989;1309.7838856588555;1317.1701827848585;1321.6167311479562;664.8072059102902;704.5209257704707;749.6963729689485;787.7242964962218;844.160060739568;5392.460753924576;5744.482996530249;5887.61730487517;5933.762649493972;5953.821385358447;969.8254314223367;984.9953047891254;1000.5497526113132;1015.5024038221366;1052.2529004406804;3904.472896159039;3947.1908258806875;3965.5836137205756;4010.498687664042;4049.088954985105;10412.947935260276;10717.196210284259;10724.195685323632;10728.73178170552;10758;10174.949125254327;10554.313969591953;10643.680689579325;10692.732681683021;10721.30311529748;7819.609019549;8113.310368618733;8118.878216826753;8130.430869839054;8151.429399942066;5746.310442746897;5765.538756899522;5787.102999035156;5831.212786273799;5859.912101318569;9862.112409883144;9922.156616687374;9949.253805964727;9961.750956226153;9977.201823854219;8155.429119961545;9748.9025109748;9883.901160988586;10066.748331291776;10074.647387341407;6832.760853370109;7093.3970612498515;7240.963795181201;7266.7093316266755;7420.5176663516695;6986.441084713312;8129.959350203211;8338.24955753985;8345.791355215926;8406.781016752659;6900.758473453405;7157.534760240567;7290.198078211286;7322.0847394075745;7370.96314518424;7065.717371305091;7147.964260178665;7277.708891644275;7302.306280903305;7323.048003759477;6742;6763.086983257197;6816.182058153054;6833.624150671761;6844.657767111525;4400.713953593006;4731.408573928259;4896.338994235733;4949;4965.155923493087;4906.582940449959;4979.9004019918675;5014.6991180529285;5033.496650334937;5086.033653605803 10:01:50 INFO - PROCESS | 1659 | |4;prototype.html;299.5461875258983;304.52798162847586;309.80724211784633;314.12046270442755;316.83524567225044;4872.979791671872;5352.304200453946;5457.072297709389;5569.073802968907;5683.380236632561;5530.723463826803;5551.333839939203;5570.637908535942;5572.049782576097;5574.554035677146;3982.4424580558743;4156.85451009214;4182.247195504808;4252.022034931968;4255.5318914919335;3334.499825026241;3380.5605271314816;3807.324777098082;3809.447630093635;4383.079553293796;3512.3150985557827;4122.835086596532;4146.232946904817;4156.896077598054;4216.3464662977285;3322.5514555534937;3420.4698271767666;4160.063985603245;4195.643370313527;4205.747655140686;3213.453712868818;3654.963450365489;3663.7435379523445;3678.926421471582;3691.9446208306895;5237.921431178535;5377.865553361197;5382.6232163748555;5423.864403389907;5424.810131645373;3812.4281357796276;4838;4843.885906241584;4861.270809378586;4903.828366007173;4813.892804654914;5354.839354819362;5640.492355687967;5903.2325797646035;5931.199288096138;3457.764238807466;3482.6666133690487;3907.5701672815967;3911;3929.2337994091163;4580.931286030712;5096.261042148868;5891.057430811093;5943.3165186003735;6282.905756413657;3956.2680904032845;3962.900927476836;3984.187194826347;4006.1587066716315;4010.258102251093;3865.4781604483596;3989.6409323161056;3997.800109994547;4022.9942514371405;4032.6572241359227;4573.14178638351;4721.6694831362165;4730.739809310452;4780.593649539758;4788.760561971888;5025.572826309732;5144.768485418165;5893.6463812171405;5895.587308888336;6004.309504406961;1127.667338135252;1324.6291038509232;1338.237204793278;1358.280111540885;1363.8227030485973;778.6885245901684;873.785922449004;879.520661239626;916.4913473022536;925.564984457304;5254.395744489356;5290.470952904679;5297.629165958346;5308.654937429054;5329.7068661224;4941.344649542371;5873.182431419526;5926.525877929756;5946.970265148647;5968.22413086296;4776.9761151194025;4939.098447097893;5274.235235890775;5284.546749643879;5300.390455097636;2515.735847735961;2819.3929460207896;3145.732612727898;3146.2606287522412;3146.3235404388165;5695.408589091953;5820.708964551755;6191.594622957446;6325.588836725598;6344.968275158594;4441.933370999438;5419.864503387447;6134.4172303631085;6194.442500175006;6211.503079753609;5985.461308482258;6283.7800676976085;6344.270408902943;6359.5866268694235;6376.234851817719;5624.831255062355;5895.9115613266695;5979.611325263844;6040.402626870487;6052.909206361996;5214;5782.681952492653;5955.940440595538;5968.7612495501435;5989.401059893976;5798.607814428607;5829.970850145722;6073.180120683651;6260.499160067093;6328;3428.674275943781;3770.3967365221433;3893.8247778849454;3906.0664970839616;3995.380715989026;5260.526552610283;5701.686407247641;5848.853778655397;5947.762089516371;5950.553708471795;4847.466778654275;5601.103823388238;5793.8551536211935;5797.507211887037;5824.7381466378965;5760.94239057604;5785.739641716217;5797.275340582427;5801.738921748616;5820.854478637899;4566.794494247701;4955.578775803953;5442.319710036246;5613.326400831763;5710.857228569319;4967.3542439482635;5223.477652234898;5254.448282930237;5715.257016587817;5793.623414478046;5010.273510341053;5266.578673706018;5621.7189140542;5660.660360378391;5685.630434021776 10:01:50 INFO - PROCESS | 1659 | |5;yui.html;702.6311186627023;716.7670507085197;767.6507189228902;773.9651715672801;819.8975128108987;13133.474661013572;14907.701845963074;15920.601984950354;15938.282777275006;16004;11732;12055.276683399003;14392.352344144512;14529.418823247084;14579.19814410207;597.2057163971917;829.3738227638124;854.4019186569395;860.3418384935529;862.8533149364591;818.1450384348341;853.2917678327004;854.3165467625874;855.0978717453115;858.5707146426787;867.0938868882002;907.686848037426;910.995445022774;911.421247507834;911.886014248219;903.327021369081;903.5391950105428;903.7740564858785;903.8960519540271;904.5522466379184;589.1722130406786;652.0610321137547;675.4123912196371;681.0465348512123;681.9352161544646;14787.778183327258;15167.24163791806;15209;15247.856410769236;15251.008684435476;14587.489437869624;14605;14620.561383158521;14625;14704.705905881929;11476.598319058876;11501.88498115025;11583.420828958519;11586.594469193538;11713.297202167812;3702.666759991587;3788.1287303919976;3793.7534060285993;3857.996920800584;3924.2543916656027;120238;125706;126132.99999999908;126779.36610316981;127345.36327318396;10824.296420732628;11111.888881111085;11186.944065279784;11190;11317.71705707364;23032.309030729106;23530.52938941246;23564.7643523566;23569.882150589136;23583.882080589487;7884.605769711491;7890.092639346434;7912.525248485109;8137.226963438464;8209.261166494925;4256.531781504032;4301.741895486281;4310.245707001325;4371.300591905344;4526.23054080807;3533.3286675531567;3619.2218672985437;3631.7457777955287;3645.4167333226555;3652.141746689526;647.0585298390924;656.4584218020152;662.0896267632006;668.3083009085573;672.3478226120656;647.5402464250341;647.5790736021623;647.6049609738056;655.8229278094888;660.181375094879;8716.782080447912;9465.847199320358;9509.096635819586;9525.5713492893;9800.803983920425;483.1737728484261;567.9091345384719;595.6902410746475;628.5411649495894;659.4856012310405;4366.934495982563;4527.501974782771;4697.989917237752;4813.614910807127;4820.673329305372;147113;148158;148160;149219.50780492273;149434;126128;131367;133714.3314283442;134629.3268533651;135693.3215333917;103668.40828979335;113012.43493782479;113980;114096.85903140862;116989.41505292419;8040.517568945882;8941.642334306554;9026.871641044869;9124.817503650023;9970.55132519024;63936.68031659812;66197;67064.65273715397;67125.32874671191;67389;88266;96544.48377227335;103086.2068103529;104551;107342;12754.362281886055;13902.652433689238;13949.093308934887;14136.515665854937;14184.432622694976;14432.494862679756;15086.698266034398;15126.697466050837;15256.694866102838;16055;11277.887221128012;11468.197226194086;14128.152310861382;14178.716425671635;14272.64318392007;12447.439865205906;14200.431982720576;14442.350094245994;14636.487722929647;14935.551933442013;13693.726125477633;14021.579352619437;14277.286135692966;14313.427462901367;14667.92666036663;8962.955185224293;10348.844767328346;11405.315681059163;11535.942320288344;11958.103142264192;7781.610919454118;11284.943575282072;11397.316161030365;11404.48679809427;11685.76628467443 10:01:50 INFO - PROCESS | 1659 | __end_tp_report 10:01:50 INFO - PROCESS | 1659 | __start_cc_report 10:01:50 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,375 10:01:50 INFO - PROCESS | 1659 | __end_cc_report 10:01:50 INFO - PROCESS | 1659 | __startTimestamp1473181310206__endTimestamp 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | ------- Summary: start ------- 10:01:50 INFO - PROCESS | 1659 | Number of tests: 6 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5519.73 Median:5677.66 stddev:685.63 (12.1%) stddev-sans-first:664.80 10:01:50 INFO - PROCESS | 1659 | Values: 3278.0 3342.3 3373.4 3375.5 3395.2 4549.8 5312.8 5500.3 5579.7 5588.4 5207.4 5551.9 5584.4 5617.8 5624.4 5079.2 5171.3 5175.7 5216.1 5321.9 5209.9 5233.0 5234.3 5252.5 5274.1 3823.8 4985.9 5115.3 5176.1 5213.9 5122.5 5144.6 5151.3 5174.9 5227.2 3572.7 3917.0 4634.4 4657.7 5127.8 4187.4 5024.5 5553.8 5558.2 5654.5 5287.7 6141.9 6157.3 6157.7 6169.1 6060.6 6195.8 6206.4 6231.6 6232.4 5195.7 5506.4 5617.4 5652.5 5656.8 6159.3 6166.5 6173.0 6175.0 6178.2 5731.1 5831.7 5850.7 5857.5 5878.4 4838.8 6029.2 6047.6 6070.1 6115.6 5973.8 6079.9 6085.5 6091.3 6109.2 4902.9 6075.9 6121.5 6166.0 6169.9 4761.7 4861.4 4876.3 4881.4 4901.9 4321.5 4392.4 4407.8 4409.9 4432.7 4439.9 5300.5 5407.9 5460.2 5528.8 5898.1 5918.3 5957.8 6061.5 6110.5 4518.9 5391.1 5442.3 5453.0 5510.1 5255.8 5255.8 5265.9 5266.3 5275.0 6240.9 6252.9 6254.7 6258.1 6272.8 5865.2 6022.9 6080.8 6116.7 6125.5 6203.8 6248.8 6251.7 6254.2 6255.1 5943.5 5998.6 6036.3 6060.4 6076.8 6103.9 6105.4 6110.7 6114.7 6124.6 5906.4 5919.9 5921.1 5924.2 5960.3 4461.6 5729.9 5866.9 5874.3 5883.9 5673.8 5676.8 5695.5 5748.2 5755.9 5861.4 5890.2 5928.0 5928.3 5951.0 5608.8 5677.7 5685.1 5711.6 5750.7 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:79958.80 Median:39548.30 stddev:196905.34 (497.9%) stddev-sans-first:197367.31 10:01:50 INFO - PROCESS | 1659 | Values: 490.7 532.5 535.4 541.0 542.0 35484.0 40336.0 40369.4 40431.0 40531.2 39080.0 40790.0 41101.7 41122.6 41137.6 9541.0 9754.0 9771.2 9778.2 9786.5 9608.2 9700.8 9702.5 9704.1 9716.3 9670.4 9696.7 9701.5 9713.0 9717.0 9651.9 9679.3 9692.2 9699.9 9701.9 9518.6 9574.5 9579.9 9646.0 9650.7 39140.4 39280.8 39379.2 39529.6 39567.0 3194.6 3231.3 3299.6 3600.4 3852.8 29961.9 30184.5 30295.5 30510.8 31304.8 3234.7 3236.8 3245.6 3249.5 3342.4 465624.0 469805.0 471405.6 471624.0 472912.0 58276.0 58725.4 58768.0 58851.4 58891.1 33561.0 34194.7 56596.7 56774.4 56884.4 13570.5 15968.0 16490.3 16532.1 16546.5 12632.1 12708.9 12732.5 12742.3 12770.0 18886.9 18890.5 18907.5 18944.7 18989.1 7861.2 8007.0 8049.8 8074.5 8083.8 820.3 962.9 975.5 985.0 995.2 25363.0 26336.3 30004.4 30103.0 30229.0 924.5 1007.1 1020.8 1037.6 1145.6 14679.0 15189.0 15195.4 15478.1 15547.7 975264.0 985598.0 987053.0 987248.0 988723.1 58005.0 58629.0 58661.4 58739.4 59004.7 1331.8 1336.5 1370.6 1373.1 1377.4 28555.4 29970.5 30631.4 30853.0 31026.4 44673.7 47444.5 49895.8 50806.7 50874.7 625055.0 627840.0 632975.0 634028.0 634170.0 26474.7 26843.9 26855.0 26903.5 26963.1 45356.8 45701.5 45775.0 47166.5 47206.3 38520.0 38643.6 38854.0 39180.8 39894.4 27957.9 37589.2 37773.0 37911.6 38928.8 28782.0 35590.0 37008.0 37033.0 37229.0 30957.5 33711.7 33967.0 34056.1 34412.0 20533.8 24967.4 25003.2 25131.9 25282.9 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34058.25 Median:2203.15 stddev:94991.31 (4311.6%) stddev-sans-first:95223.99 10:01:50 INFO - PROCESS | 1659 | Values: 313.5 314.3 334.9 376.9 380.8 19237.8 19745.5 20639.5 20681.6 20939.6 11568.1 16489.3 19581.3 20398.3 20445.2 5395.8 5477.5 5600.8 5610.6 5631.4 5463.8 5471.0 5498.5 5500.7 5557.6 5466.3 5508.6 5520.7 5526.8 5555.2 5332.8 5425.5 5484.1 5548.3 5548.3 5360.1 5369.1 5372.2 5372.5 5376.7 12691.7 17380.8 18622.7 24778.4 25487.1 2155.0 2251.3 2257.2 2620.8 2644.6 10165.2 10421.3 12279.4 14811.6 15728.5 1805.3 1891.0 1892.8 1897.7 1898.2 123522.4 126532.0 127187.4 128072.0 128137.9 11381.3 11395.7 11510.5 11642.7 11787.1 14313.9 14386.9 14394.6 14429.6 14431.9 8356.8 8513.8 8517.1 8519.7 8671.9 8432.5 8437.5 8440.9 8539.1 8566.3 4057.5 4285.5 4473.0 4535.4 4580.3 1901.0 1922.0 1947.2 1948.8 1953.9 830.7 978.6 1006.4 1021.6 1037.9 20184.8 20967.5 21066.7 21126.5 21255.5 773.5 815.9 840.9 939.5 1007.5 8068.4 8900.8 10759.9 11185.4 11291.5 459133.0 554535.0 591145.0 601347.0 605556.0 103200.0 120587.2 136709.3 140234.0 142748.0 941.9 942.9 947.3 1001.6 1015.8 18472.7 19141.3 19166.0 19283.4 19364.3 42021.8 50851.0 52663.7 53319.7 54505.5 76049.6 81067.6 81387.6 81887.6 82228.0 7648.0 7899.5 7963.5 8037.6 8056.2 12044.6 12291.3 12391.1 12592.0 12932.6 9632.2 10888.9 11304.5 11400.5 11680.8 10406.4 10894.7 11224.0 11296.7 11538.8 10866.5 11309.1 11326.5 11801.4 11818.9 8035.3 8052.6 8149.8 8225.9 8277.6 8595.2 10938.6 10945.8 11314.1 11363.0 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5599.93 Median:4927.79 stddev:3128.08 (63.5%) stddev-sans-first:3121.84 10:01:50 INFO - PROCESS | 1659 | Values: 1522.2 1558.5 1756.8 1861.6 1896.1 7822.6 8268.7 8512.9 8785.1 8811.0 8629.3 8685.6 8716.7 8719.6 8749.6 2345.1 2377.2 2388.1 2389.4 2396.0 2217.7 2243.2 2259.2 2262.1 2268.3 2325.8 2370.8 2385.5 2388.3 2389.5 1809.8 2218.7 2224.8 2227.7 2232.9 1942.9 2356.5 2361.3 2370.7 2375.1 7178.0 8678.6 8727.3 8768.0 8804.6 5173.5 5303.2 5331.3 5342.9 5344.6 3353.8 3483.4 3530.4 3603.9 3660.3 1092.3 1095.6 1098.5 1099.5 1119.6 11328.7 11357.0 11441.8 11454.5 11460.9 4545.3 4551.8 4602.6 4788.2 4796.9 9076.7 9757.3 9768.4 9823.2 9839.5 4104.3 4546.7 5129.5 5309.3 5311.5 4362.8 4689.0 4814.5 4826.0 4858.7 3877.7 3954.2 3985.4 4006.0 4046.2 1302.4 1309.6 1309.8 1317.2 1321.6 664.8 704.5 749.7 787.7 844.2 5392.5 5744.5 5887.6 5933.8 5953.8 969.8 985.0 1000.5 1015.5 1052.3 3904.5 3947.2 3965.6 4010.5 4049.1 10412.9 10717.2 10724.2 10728.7 10758.0 10174.9 10554.3 10643.7 10692.7 10721.3 7819.6 8113.3 8118.9 8130.4 8151.4 5746.3 5765.5 5787.1 5831.2 5859.9 9862.1 9922.2 9949.3 9961.8 9977.2 8155.4 9748.9 9883.9 10066.7 10074.6 6832.8 7093.4 7241.0 7266.7 7420.5 6986.4 8130.0 8338.2 8345.8 8406.8 6900.8 7157.5 7290.2 7322.1 7371.0 7065.7 7148.0 7277.7 7302.3 7323.0 6742.0 6763.1 6816.2 6833.6 6844.7 4400.7 4731.4 4896.3 4949.0 4965.2 4906.6 4979.9 5014.7 5033.5 5086.0 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4661.80 Median:5263.55 stddev:1472.08 (28.0%) stddev-sans-first:1439.34 10:01:50 INFO - PROCESS | 1659 | Values: 299.5 304.5 309.8 314.1 316.8 4873.0 5352.3 5457.1 5569.1 5683.4 5530.7 5551.3 5570.6 5572.0 5574.6 3982.4 4156.9 4182.2 4252.0 4255.5 3334.5 3380.6 3807.3 3809.4 4383.1 3512.3 4122.8 4146.2 4156.9 4216.3 3322.6 3420.5 4160.1 4195.6 4205.7 3213.5 3655.0 3663.7 3678.9 3691.9 5237.9 5377.9 5382.6 5423.9 5424.8 3812.4 4838.0 4843.9 4861.3 4903.8 4813.9 5354.8 5640.5 5903.2 5931.2 3457.8 3482.7 3907.6 3911.0 3929.2 4580.9 5096.3 5891.1 5943.3 6282.9 3956.3 3962.9 3984.2 4006.2 4010.3 3865.5 3989.6 3997.8 4023.0 4032.7 4573.1 4721.7 4730.7 4780.6 4788.8 5025.6 5144.8 5893.6 5895.6 6004.3 1127.7 1324.6 1338.2 1358.3 1363.8 778.7 873.8 879.5 916.5 925.6 5254.4 5290.5 5297.6 5308.7 5329.7 4941.3 5873.2 5926.5 5947.0 5968.2 4777.0 4939.1 5274.2 5284.5 5300.4 2515.7 2819.4 3145.7 3146.3 3146.3 5695.4 5820.7 6191.6 6325.6 6345.0 4441.9 5419.9 6134.4 6194.4 6211.5 5985.5 6283.8 6344.3 6359.6 6376.2 5624.8 5895.9 5979.6 6040.4 6052.9 5214.0 5782.7 5955.9 5968.8 5989.4 5798.6 5830.0 6073.2 6260.5 6328.0 3428.7 3770.4 3893.8 3906.1 3995.4 5260.5 5701.7 5848.9 5947.8 5950.6 4847.5 5601.1 5793.9 5797.5 5824.7 5760.9 5785.7 5797.3 5801.7 5820.9 4566.8 4955.6 5442.3 5613.3 5710.9 4967.4 5223.5 5254.4 5715.3 5793.6 5010.3 5266.6 5621.7 5660.7 5685.6 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:25853.23 Median:13558.61 stddev:41435.07 (305.6%) stddev-sans-first:41508.28 10:01:50 INFO - PROCESS | 1659 | Values: 702.6 716.8 767.7 774.0 819.9 13133.5 14907.7 15920.6 15938.3 16004.0 11732.0 12055.3 14392.4 14529.4 14579.2 597.2 829.4 854.4 860.3 862.9 818.1 853.3 854.3 855.1 858.6 867.1 907.7 911.0 911.4 911.9 903.3 903.5 903.8 903.9 904.6 589.2 652.1 675.4 681.0 681.9 14787.8 15167.2 15209.0 15247.9 15251.0 14587.5 14605.0 14620.6 14625.0 14704.7 11476.6 11501.9 11583.4 11586.6 11713.3 3702.7 3788.1 3793.8 3858.0 3924.3 120238.0 125706.0 126133.0 126779.4 127345.4 10824.3 11111.9 11186.9 11190.0 11317.7 23032.3 23530.5 23564.8 23569.9 23583.9 7884.6 7890.1 7912.5 8137.2 8209.3 4256.5 4301.7 4310.2 4371.3 4526.2 3533.3 3619.2 3631.7 3645.4 3652.1 647.1 656.5 662.1 668.3 672.3 647.5 647.6 647.6 655.8 660.2 8716.8 9465.8 9509.1 9525.6 9800.8 483.2 567.9 595.7 628.5 659.5 4366.9 4527.5 4698.0 4813.6 4820.7 147113.0 148158.0 148160.0 149219.5 149434.0 126128.0 131367.0 133714.3 134629.3 135693.3 103668.4 113012.4 113980.0 114096.9 116989.4 8040.5 8941.6 9026.9 9124.8 9970.6 63936.7 66197.0 67064.7 67125.3 67389.0 88266.0 96544.5 103086.2 104551.0 107342.0 12754.4 13902.7 13949.1 14136.5 14184.4 14432.5 15086.7 15126.7 15256.7 16055.0 11277.9 11468.2 14128.2 14178.7 14272.6 12447.4 14200.4 14442.4 14636.5 14935.6 13693.7 14021.6 14277.3 14313.4 14667.9 8963.0 10348.8 11405.3 11535.9 11958.1 7781.6 11284.9 11397.3 11404.5 11685.8 10:01:50 INFO - PROCESS | 1659 | -------- Summary: end -------- 10:01:50 INFO - PROCESS | 1659 | 10:01:50 INFO - TEST-INFO | 1659: exit 0 10:01:50 INFO - TEST-OK | dromaeo_css | took 1113882ms 10:01:50 INFO - TEST-START | kraken 10:01:50 INFO - operating with platform_type : mac_ 10:01:50 INFO - Initialising browser for kraken test... 10:01:50 INFO - TEST-INFO | started process 1667 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmp8Ax93j/profile http://localhost:49273/getInfo.html) 10:01:53 INFO - PROCESS | 1667 | __metrics Screen width/height:1600/1200 10:01:53 INFO - PROCESS | 1667 | colorDepth:24 10:01:53 INFO - PROCESS | 1667 | Browser inner width/height: 1024/689 10:01:53 INFO - PROCESS | 1667 | __metrics 10:01:53 ERROR - PROCESS | 1667 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:01:54 INFO - TEST-INFO | 1667: exit 0 10:01:54 INFO - Browser initialized. 10:01:54 INFO - Running cycle 1/1 for kraken test... 10:01:54 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/T/tmp8Ax93j/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:02:06 INFO - PROCESS | 1669 | RSS: Main: 243892224 10:02:06 INFO - PROCESS | 1669 | 10:02:09 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection) 10:02:10 INFO - PROCESS | 1669 | RSS: Main: 256634880 10:02:10 INFO - PROCESS | 1669 | 10:02:12 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-dft) 10:02:13 INFO - PROCESS | 1669 | RSS: Main: 265400320 10:02:13 INFO - PROCESS | 1669 | 10:02:16 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-fft) 10:02:17 INFO - PROCESS | 1669 | RSS: Main: 263774208 10:02:17 INFO - PROCESS | 1669 | 10:02:19 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator) 10:02:19 INFO - PROCESS | 1669 | RSS: Main: 265146368 10:02:19 INFO - PROCESS | 1669 | 10:02:21 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:02:22 INFO - PROCESS | 1669 | RSS: Main: 266870784 10:02:22 INFO - PROCESS | 1669 | 10:02:25 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom) 10:02:25 INFO - PROCESS | 1669 | RSS: Main: 267923456 10:02:25 INFO - PROCESS | 1669 | 10:02:28 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate) 10:02:29 INFO - PROCESS | 1669 | RSS: Main: 267988992 10:02:29 INFO - PROCESS | 1669 | 10:02:32 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial) 10:02:32 INFO - PROCESS | 1669 | RSS: Main: 267997184 10:02:32 INFO - PROCESS | 1669 | 10:02:34 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:02:35 INFO - PROCESS | 1669 | RSS: Main: 267722752 10:02:35 INFO - PROCESS | 1669 | 10:02:37 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:02:37 INFO - PROCESS | 1669 | RSS: Main: 267411456 10:02:37 INFO - PROCESS | 1669 | 10:02:40 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:02:40 INFO - PROCESS | 1669 | RSS: Main: 267505664 10:02:40 INFO - PROCESS | 1669 | 10:02:43 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:02:44 INFO - PROCESS | 1669 | RSS: Main: 267558912 10:02:44 INFO - PROCESS | 1669 | 10:02:47 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:02:48 INFO - PROCESS | 1669 | RSS: Main: 267800576 10:02:48 INFO - PROCESS | 1669 | 10:02:49 INFO - PROCESS | 1669 | Cycle 1(1): loaded http://localhost:49273/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49273/tests/kraken/driver.html?testName=ai-astar) 10:02:50 INFO - PROCESS | 1669 | RSS: Main: 269787136 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | __start_tp_report 10:02:50 INFO - PROCESS | 1669 | _x_x_mozilla_page_load 10:02:50 INFO - PROCESS | 1669 | _x_x_mozilla_page_load_details 10:02:50 INFO - PROCESS | 1669 | |i|pagename|runs| 10:02:50 INFO - PROCESS | 1669 | |0;ai-astar;107;115;115;115;115;116;119;115;117;134 10:02:50 INFO - PROCESS | 1669 | |1;audio-beat-detection;142;110;135;163;140;167;131;115;157;110 10:02:50 INFO - PROCESS | 1669 | |2;audio-dft;192;201;210;232;197;196;181;197;197;195 10:02:50 INFO - PROCESS | 1669 | |3;audio-fft;71;85;76;144;74;82;83;62;130;60 10:02:50 INFO - PROCESS | 1669 | |4;audio-oscillator;69;72;114;71;71;93;109;69;73;112 10:02:50 INFO - PROCESS | 1669 | |5;imaging-gaussian-blur;132;86;89;86;89;85;89;86;89;86 10:02:50 INFO - PROCESS | 1669 | |6;imaging-darkroom;127;95;94;96;95;95;96;120;122;96 10:02:50 INFO - PROCESS | 1669 | |7;imaging-desaturate;91;92;91;88;93;92;90;98;148;113 10:02:50 INFO - PROCESS | 1669 | |8;json-parse-financial;48;60;60;59;59;58;59;56;58;57 10:02:50 INFO - PROCESS | 1669 | |9;json-stringify-tinderbox;73;85;94;124;120;113;70;68;89;74 10:02:50 INFO - PROCESS | 1669 | |10;stanford-crypto-aes;99;80;99;111;110;105;88;89;96;84 10:02:50 INFO - PROCESS | 1669 | |11;stanford-crypto-ccm;145;141;140;140;140;138;139;127;193;167 10:02:50 INFO - PROCESS | 1669 | |12;stanford-crypto-pbkdf2;170;171;170;170;175;172;171;218;243;187 10:02:50 INFO - PROCESS | 1669 | |13;stanford-crypto-sha256-iterative;70;69;69;62;74;75;91;90;88;86 10:02:50 INFO - PROCESS | 1669 | __end_tp_report 10:02:50 INFO - PROCESS | 1669 | __start_cc_report 10:02:50 INFO - PROCESS | 1669 | _x_x_mozilla_cycle_collect,959 10:02:50 INFO - PROCESS | 1669 | __end_cc_report 10:02:50 INFO - PROCESS | 1669 | __startTimestamp1473181370113__endTimestamp 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | ------- Summary: start ------- 10:02:50 INFO - PROCESS | 1669 | Number of tests: 14 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#0] ai-astar Cycles:10 Average:116.80 Median:115.50 stddev:6.78 (5.9%) stddev-sans-first:6.19 10:02:50 INFO - PROCESS | 1669 | Values: 107.0 115.0 115.0 115.0 115.0 116.0 119.0 115.0 117.0 134.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#1] audio-beat-detection Cycles:10 Average:137.00 Median:141.00 stddev:21.06 (14.9%) stddev-sans-first:22.26 10:02:50 INFO - PROCESS | 1669 | Values: 142.0 110.0 135.0 163.0 140.0 167.0 131.0 115.0 157.0 110.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#2] audio-dft Cycles:10 Average:199.80 Median:197.00 stddev:13.41 (6.8%) stddev-sans-first:13.92 10:02:50 INFO - PROCESS | 1669 | Values: 192.0 201.0 210.0 232.0 197.0 196.0 181.0 197.0 197.0 195.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#3] audio-fft Cycles:10 Average:86.70 Median:75.00 stddev:27.97 (37.3%) stddev-sans-first:29.09 10:02:50 INFO - PROCESS | 1669 | Values: 71.0 85.0 76.0 144.0 74.0 82.0 83.0 62.0 130.0 60.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:71.00 stddev:19.51 (27.5%) stddev-sans-first:19.78 10:02:50 INFO - PROCESS | 1669 | Values: 69.0 72.0 114.0 71.0 71.0 93.0 109.0 69.0 73.0 112.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#5] imaging-gaussian-blur Cycles:10 Average:91.70 Median:87.50 stddev:14.25 (16.3%) stddev-sans-first:1.72 10:02:50 INFO - PROCESS | 1669 | Values: 132.0 86.0 89.0 86.0 89.0 85.0 89.0 86.0 89.0 86.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:95.00 stddev:13.51 (14.2%) stddev-sans-first:11.37 10:02:50 INFO - PROCESS | 1669 | Values: 127.0 95.0 94.0 96.0 95.0 95.0 96.0 120.0 122.0 96.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#7] imaging-desaturate Cycles:10 Average:99.60 Median:91.50 stddev:18.43 (20.1%) stddev-sans-first:19.29 10:02:50 INFO - PROCESS | 1669 | Values: 91.0 92.0 91.0 88.0 93.0 92.0 90.0 98.0 148.0 113.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#8] json-parse-financial Cycles:10 Average:57.40 Median:59.00 stddev:3.53 (6.0%) stddev-sans-first:1.33 10:02:50 INFO - PROCESS | 1669 | Values: 48.0 60.0 60.0 59.0 59.0 58.0 59.0 56.0 58.0 57.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#9] json-stringify-tinderbox Cycles:10 Average:91.00 Median:73.50 stddev:21.20 (28.8%) stddev-sans-first:21.47 10:02:50 INFO - PROCESS | 1669 | Values: 73.0 85.0 94.0 124.0 120.0 113.0 70.0 68.0 89.0 74.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#10] stanford-crypto-aes Cycles:10 Average:96.10 Median:88.50 stddev:10.71 (12.1%) stddev-sans-first:11.31 10:02:50 INFO - PROCESS | 1669 | Values: 99.0 80.0 99.0 111.0 110.0 105.0 88.0 89.0 96.0 84.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#11] stanford-crypto-ccm Cycles:10 Average:147.00 Median:140.50 stddev:19.00 (13.5%) stddev-sans-first:20.14 10:02:50 INFO - PROCESS | 1669 | Values: 145.0 141.0 140.0 140.0 140.0 138.0 139.0 127.0 193.0 167.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:184.70 Median:173.50 stddev:25.37 (14.6%) stddev-sans-first:26.34 10:02:50 INFO - PROCESS | 1669 | Values: 170.0 171.0 170.0 170.0 175.0 172.0 171.0 218.0 243.0 187.0 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - PROCESS | 1669 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.40 Median:80.50 stddev:10.44 (13.0%) stddev-sans-first:10.72 10:02:50 INFO - PROCESS | 1669 | Values: 70.0 69.0 69.0 62.0 74.0 75.0 91.0 90.0 88.0 86.0 10:02:50 INFO - PROCESS | 1669 | -------- Summary: end -------- 10:02:50 INFO - PROCESS | 1669 | 10:02:50 INFO - TEST-INFO | 1669: exit 0 10:02:50 INFO - TEST-OK | kraken | took 60103ms 10:02:50 INFO - SUITE-END | took 1173s 10:02:51 INFO - Completed test suite (00:19:34) 10:02:51 INFO - javascript benchmark 10:02:51 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7313.398953076205, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3277.9836100819493, 3342.3148254607804, 3373.359061778262, 3375.493675948608, 3395.2021275000384, 4549.839790853336, 5312.814051508198, 5500.28496295481, 5579.721013949302, 5588.3852776194635, 5207.375114986197, 5551.916721249185, 5584.385717571074, 5617.831465056045, 5624.437556244374, 5079.187330027178, 5171.276021357013, 5175.715335656538, 5216.087184742674, 5321.946780532203, 5209.895802083956, 5233.0, 5234.293370394986, 5252.52727254547, 5274.129768588178, 3823.8279277432584, 4985.875353116165, 5115.258287548322, 5176.094183517907, 5213.947860521384, 5122.513361230677, 5144.639875208737, 5151.2530683050745, 5174.948250517485, 5227.2159176123505, 3572.7141828653644, 3917.0, 4634.374359461463, 4657.743824089674, 5127.769250383704, 4187.350960601112, 5024.522670346311, 5553.8333849984565, 5558.166275058733, 5654.462826031561, 5287.709175995319, 6141.877162456726, 6157.261128664589, 6157.692115394258, 6169.105479705418, 6060.575759696823, 6195.7521699131985, 6206.441420272152, 6231.59494632852, 6232.376762323776, 5195.6622819516615, 5506.366767821672, 5617.410171931929, 5652.463016013472, 5656.8020119295625, 6159.291681456601, 6166.4758495527485, 6173.043178307369, 6175.042868355322, 6178.196834411498, 5731.140328950607, 5831.737571809279, 5850.678212698257, 5857.531397488191, 5878.412158784173, 4838.782254798542, 6029.155918171459, 6047.637141771508, 6070.119832624245, 6115.6330620162935, 5973.790917317873, 6079.939200607937, 6085.513158947274, 6091.269047714302, 6109.175261339662, 4902.852914412573, 6075.908861367083, 6121.540884433685, 6166.0, 6169.907451388232, 4761.666683332133, 4861.416630004422, 4876.317315575751, 4881.389826271716, 4901.852944411673, 4321.524632290446, 4392.407025051577, 4407.823687052482, 4409.933850992237, 4432.689711720149, 4439.911201775946, 5300.549453296513, 5407.864803379947, 5460.235567020621, 5528.806491772769, 5898.1152827076285, 5918.319393269829, 5957.761689532457, 6061.484773794277, 6110.480609148272, 4518.864434066983, 5391.137418013178, 5442.265294185234, 5452.972735136299, 5510.090835012258, 5255.816046438356, 5255.842324730264, 5265.868353291198, 5266.262723218752, 5275.024120537712, 6240.875182496325, 6252.906206406817, 6254.6872656366995, 6258.061290806416, 6272.843178920563, 5865.208196893364, 6022.879542409215, 6080.7567697291615, 6116.694165291807, 6125.510168486406, 6203.7828675996125, 6248.750049997949, 6251.749930002749, 6254.21822272216, 6255.093011513214, 5943.465088142088, 5998.580099393001, 6036.336003039574, 6060.424259695322, 6076.817695469143, 6103.943827031667, 6105.421132926843, 6110.682750901629, 6114.692773002257, 6124.625058545555, 5906.438888305604, 5919.852003699942, 5921.111833225051, 5924.200232968494, 5960.254968128984, 4461.598456138963, 5729.942700573108, 5866.911996319973, 5874.295084589877, 5883.911741323798, 5673.801416950387, 5676.779492408987, 5695.45893140151, 5748.223989761329, 5755.856103597276, 5861.413858614104, 5890.2342695449315, 5928.0, 5928.34788173292, 5950.9702451487465, 5608.775648974159, 5677.659340439587, 5685.061964775766, 5711.571632127524, 5750.741216645345], "value": 5478.542484988276, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [490.6589920005595, 532.5154109760119, 535.3602445078133, 540.9729513524328, 541.9648471419587, 35484.0, 40336.0, 40369.39445908314, 40430.99999999996, 40531.18937621253, 39080.0, 40789.980250493754, 41101.73940433275, 41122.58877411225, 41137.58862411375, 9540.952295238514, 9754.0, 9771.169450596672, 9778.168855647244, 9786.510674466284, 9608.2313414927, 9700.75748106296, 9702.514874256294, 9704.07811257933, 9716.31985760997, 9670.4197748135, 9696.660616878411, 9701.514924253794, 9712.951435242814, 9716.951415242915, 9651.855222171673, 9679.274054445874, 9692.176165025981, 9699.90300096997, 9701.854472182888, 9518.619255229782, 9574.521273936345, 9579.856302155473, 9646.0, 9650.662226822098, 39140.41289380661, 39280.80359598184, 39379.21241575152, 39529.60470395288, 39567.0, 3194.632617249023, 3231.2568109334984, 3299.554560134375, 3600.3519366514015, 3852.8073596320073, 29961.850190748908, 30184.547231791323, 30295.545566816516, 30510.84744576263, 31304.8434757827, 3234.741220702338, 3236.773425860191, 3245.5618491503583, 3249.4800831867024, 3342.412354131768, 465624.0, 469805.0, 471405.6429717864, 471624.0, 472912.0, 58276.0, 58725.41274587285, 58768.0, 58851.41148588545, 58891.11663325054, 33561.0, 34194.658053419145, 56596.71701641465, 56774.43225567774, 56884.43115568791, 13570.457181712818, 15968.0, 16490.34038638465, 16532.09073500945, 16546.503604891874, 12632.052596055333, 12708.872911270953, 12732.490700371882, 12742.299173545358, 12770.0, 18886.86678799249, 18890.52773680669, 18907.52731181704, 18944.71582926257, 18989.050547472572, 7861.213878612208, 8006.9599652002535, 8049.758507244792, 8074.549678966674, 8083.757487275391, 820.3027426687268, 962.8748262725801, 975.5171190260867, 985.0199051943363, 995.183949161695, 25363.0, 26336.34159146037, 30004.399912002074, 30103.0, 30229.0, 924.4638109896379, 1007.0835539659022, 1020.8417695257247, 1037.647199952005, 1145.5990403358924, 14679.0, 15189.0, 15195.392184312725, 15478.148701821283, 15547.689046219239, 975264.0, 985598.0, 987053.0, 987248.0, 988723.0563847278, 58005.0, 58629.0, 58661.41338586645, 58739.41260587425, 59004.704976474844, 1331.7602831490424, 1336.5054929676082, 1370.595674276071, 1373.1280636795707, 1377.4008306386754, 28555.42889142247, 29970.500302942775, 30631.387372252877, 30853.0, 31026.379472409975, 44673.659790206344, 47444.52555474539, 49895.75052124716, 50806.74596627141, 50874.728131797005, 625055.0, 627840.0, 632975.0, 634028.0, 634170.0, 26474.735252648, 26843.86578067097, 26855.0, 26903.461930761667, 26963.05629302965, 45356.77321613371, 45701.54298456973, 45775.0, 47166.52833471621, 47206.29190562213, 38520.0, 38643.613563864004, 38854.028649283995, 39180.804095979336, 39894.40158397568, 27957.860210698815, 37589.248215036096, 37773.0, 37911.62088379081, 38928.80535597304, 28782.0, 35590.0, 37008.0, 37033.0, 37229.0, 30957.535636965014, 33711.74605702635, 33967.0, 34056.14859628529, 34412.0, 20533.794662053187, 24967.375815604755, 25003.249902502954, 25131.869065891715, 25282.87358563269], "value": 19915.571531077487, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.46241196348274, 314.27559475409174, 334.8995301409578, 376.9114258149335, 380.78675941472795, 19237.807621923777, 19745.50636234093, 20639.484012899684, 20681.586368272645, 20939.581208375825, 11568.074554035678, 16489.34042638293, 19581.314653987112, 20398.286059987902, 20445.182192712273, 5395.7841686332595, 5477.534409575191, 5600.803971860985, 5610.579206559504, 5631.380548139701, 5463.7814487420455, 5471.015217260891, 5498.505134537891, 5500.724963751816, 5557.638753481031, 5466.262054622614, 5508.586855985797, 5520.723963801794, 5526.751296191661, 5555.194496797962, 5332.840014799543, 5425.484578964992, 5484.122540393518, 5548.278723765905, 5548.306461692288, 5360.142377219626, 5369.087255166631, 5372.167314066326, 5372.54333381663, 5376.704281264529, 12691.682707932376, 17380.826191738175, 18622.720659190123, 24778.38054048645, 25487.107951221617, 2154.956900861974, 2251.34710933829, 2257.1874125314876, 2620.7903367730537, 2644.6429731986127, 10165.237607179492, 10421.27051106423, 12279.38603069843, 14811.599409252347, 15728.528144155694, 1805.3049575913237, 1890.9599720154, 1892.8201820826998, 1897.7438045863769, 1898.1872361559474, 123522.38238808748, 126532.0, 127187.36406317909, 128071.99999999907, 128137.9048304695, 11381.317120972602, 11395.715107122389, 11510.482028308747, 11642.6507204784, 11787.057035437145, 14313.928430357782, 14386.928065359607, 14394.640133996734, 14429.567112986628, 14431.855681443261, 8356.791080222922, 8513.829723405497, 8517.148285171434, 8519.659213631508, 8671.913280867237, 8432.536210508359, 8437.493750374875, 8440.898916291128, 8539.06070332263, 8566.271866891384, 4057.4928133983253, 4285.485741710952, 4473.0, 4535.387722657399, 4580.312953057001, 1901.0, 1922.0197699173243, 1947.221111555361, 1948.814862588052, 1953.8925359105385, 830.6760363458257, 978.5889926231, 1006.3659894266565, 1021.5862575656871, 1037.901399367059, 20184.798152018586, 20967.47581310449, 21066.683999740017, 21126.47183820386, 21255.468613284793, 773.4779024158782, 815.8735396013627, 840.8864803251605, 939.534930209537, 1007.450939238117, 8068.3948703847445, 8900.777480563038, 10759.946200269105, 11185.384803835705, 11291.53210082684, 459133.0, 554535.0, 591145.0, 601347.0, 605556.0, 103200.0, 120587.19119213219, 136709.31645341907, 140234.0, 142748.0, 941.9293552983416, 942.9009953954874, 947.3084648206848, 1001.6143784642819, 1015.8374660054355, 18472.72290915664, 19141.330053448062, 19166.04169791477, 19283.421497355102, 19364.32224872179, 42021.78989105035, 50850.96596136261, 52663.736681316346, 53319.7334013343, 54505.454945451624, 76049.61975190089, 81067.59466202867, 81387.59306203431, 81887.5905620492, 82228.0, 7647.961760191163, 7899.486533375312, 7963.482373645695, 8037.638306276349, 8056.234657707509, 12044.638660840188, 12291.32397718134, 12391.070669699628, 12592.0, 12932.612021639367, 9632.181264592587, 10888.891111088788, 11304.508518087723, 11400.543978240778, 11680.824787628362, 10406.375617462976, 10894.727631809268, 11224.0, 11296.717582060513, 11538.769224615293, 10866.456677166268, 11309.095272378025, 11326.546938122383, 11801.409929503663, 11818.93168695492, 8035.316998055231, 8052.597370131587, 8149.79625509367, 8225.876611850706, 8277.62750676233, 8595.226429621363, 10938.56245750161, 10945.83581246266, 11314.094872410024, 11363.0], "value": 9388.911295945556, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1522.2084516051652, 1558.454540910681, 1756.8053723468045, 1861.6276744651072, 1896.0993528074157, 7822.647980840861, 8268.669253229871, 8512.91487085129, 8785.077566855476, 8811.030786613468, 8629.266512346458, 8685.565721713921, 8716.738497845075, 8719.564021798917, 8749.606267717953, 2345.1323010486144, 2377.1561095811, 2388.10446082719, 2389.438481956739, 2396.0415833666584, 2217.7449593296737, 2243.214874793817, 2259.2318611672026, 2262.1177740681146, 2268.330842401487, 2325.790678838904, 2370.8340416170877, 2385.510970251103, 2388.319328991238, 2389.486260454002, 1809.8190180981928, 2218.7115674962315, 2224.799768020886, 2227.6992605998144, 2232.944176395595, 1942.9125689344014, 2356.4697942962866, 2361.315218586608, 2370.7392186859433, 2375.064322577094, 7178.0, 8678.609462574137, 8727.258183054448, 8768.035516093225, 8804.64781408743, 5173.482651734834, 5303.204519322094, 5331.253624492574, 5342.866428339284, 5344.599155063347, 3353.765236433451, 3483.372992861284, 3530.4174811156126, 3603.891883243507, 3660.3411385950517, 1092.322759888874, 1095.6001059613263, 1098.549594666183, 1099.4667586220676, 1119.6249256499082, 11328.716782080432, 11356.999999999918, 11441.771164576661, 11454.54181832734, 11460.885391145981, 4545.318202269686, 4551.7724113794175, 4602.585767280893, 4788.186008378584, 4796.880077998078, 9076.682316118904, 9757.316987810786, 9768.413895166314, 9823.165030972448, 9839.458829764288, 4104.343305071175, 4546.65900057497, 5129.487051294916, 5309.309789727311, 5311.49540793624, 4362.76004819738, 4689.038747056845, 4814.542618451242, 4826.01066781309, 4858.732769697701, 3877.7091718121255, 3954.189391174802, 3985.362342025262, 4006.0185254612225, 4046.231216068996, 1302.3683513495946, 1309.594025851989, 1309.7838856588555, 1317.1701827848585, 1321.6167311479562, 664.8072059102902, 704.5209257704707, 749.6963729689485, 787.7242964962218, 844.160060739568, 5392.460753924576, 5744.482996530249, 5887.61730487517, 5933.762649493972, 5953.821385358447, 969.8254314223367, 984.9953047891254, 1000.5497526113132, 1015.5024038221366, 1052.2529004406804, 3904.472896159039, 3947.1908258806875, 3965.5836137205756, 4010.498687664042, 4049.088954985105, 10412.947935260276, 10717.196210284259, 10724.195685323632, 10728.73178170552, 10758.0, 10174.949125254327, 10554.313969591953, 10643.680689579325, 10692.732681683021, 10721.30311529748, 7819.609019549, 8113.310368618733, 8118.878216826753, 8130.430869839054, 8151.429399942066, 5746.310442746897, 5765.538756899522, 5787.102999035156, 5831.212786273799, 5859.912101318569, 9862.112409883144, 9922.156616687374, 9949.253805964727, 9961.750956226153, 9977.201823854219, 8155.429119961545, 9748.9025109748, 9883.901160988586, 10066.748331291776, 10074.647387341407, 6832.760853370109, 7093.3970612498515, 7240.963795181201, 7266.7093316266755, 7420.5176663516695, 6986.441084713312, 8129.959350203211, 8338.24955753985, 8345.791355215926, 8406.781016752659, 6900.758473453405, 7157.534760240567, 7290.198078211286, 7322.0847394075745, 7370.96314518424, 7065.717371305091, 7147.964260178665, 7277.708891644275, 7302.306280903305, 7323.048003759477, 6742.0, 6763.086983257197, 6816.182058153054, 6833.624150671761, 6844.657767111525, 4400.713953593006, 4731.408573928259, 4896.338994235733, 4949.0, 4965.155923493087, 4906.582940449959, 4979.9004019918675, 5014.6991180529285, 5033.496650334937, 5086.033653605803], "value": 4510.422936806345, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [299.5461875258983, 304.52798162847586, 309.80724211784633, 314.12046270442755, 316.83524567225044, 4872.979791671872, 5352.304200453946, 5457.072297709389, 5569.073802968907, 5683.380236632561, 5530.723463826803, 5551.333839939203, 5570.637908535942, 5572.049782576097, 5574.554035677146, 3982.4424580558743, 4156.85451009214, 4182.247195504808, 4252.022034931968, 4255.5318914919335, 3334.499825026241, 3380.5605271314816, 3807.324777098082, 3809.447630093635, 4383.079553293796, 3512.3150985557827, 4122.835086596532, 4146.232946904817, 4156.896077598054, 4216.3464662977285, 3322.5514555534937, 3420.4698271767666, 4160.063985603245, 4195.643370313527, 4205.747655140686, 3213.453712868818, 3654.963450365489, 3663.7435379523445, 3678.926421471582, 3691.9446208306895, 5237.921431178535, 5377.865553361197, 5382.6232163748555, 5423.864403389907, 5424.810131645373, 3812.4281357796276, 4838.0, 4843.885906241584, 4861.270809378586, 4903.828366007173, 4813.892804654914, 5354.839354819362, 5640.492355687967, 5903.2325797646035, 5931.199288096138, 3457.764238807466, 3482.6666133690487, 3907.5701672815967, 3911.0, 3929.2337994091163, 4580.931286030712, 5096.261042148868, 5891.057430811093, 5943.3165186003735, 6282.905756413657, 3956.2680904032845, 3962.900927476836, 3984.187194826347, 4006.1587066716315, 4010.258102251093, 3865.4781604483596, 3989.6409323161056, 3997.800109994547, 4022.9942514371405, 4032.6572241359227, 4573.14178638351, 4721.6694831362165, 4730.739809310452, 4780.593649539758, 4788.760561971888, 5025.572826309732, 5144.768485418165, 5893.6463812171405, 5895.587308888336, 6004.309504406961, 1127.667338135252, 1324.6291038509232, 1338.237204793278, 1358.280111540885, 1363.8227030485973, 778.6885245901684, 873.785922449004, 879.520661239626, 916.4913473022536, 925.564984457304, 5254.395744489356, 5290.470952904679, 5297.629165958346, 5308.654937429054, 5329.7068661224, 4941.344649542371, 5873.182431419526, 5926.525877929756, 5946.970265148647, 5968.22413086296, 4776.9761151194025, 4939.098447097893, 5274.235235890775, 5284.546749643879, 5300.390455097636, 2515.735847735961, 2819.3929460207896, 3145.732612727898, 3146.2606287522412, 3146.3235404388165, 5695.408589091953, 5820.708964551755, 6191.594622957446, 6325.588836725598, 6344.968275158594, 4441.933370999438, 5419.864503387447, 6134.4172303631085, 6194.442500175006, 6211.503079753609, 5985.461308482258, 6283.7800676976085, 6344.270408902943, 6359.5866268694235, 6376.234851817719, 5624.831255062355, 5895.9115613266695, 5979.611325263844, 6040.402626870487, 6052.909206361996, 5214.0, 5782.681952492653, 5955.940440595538, 5968.7612495501435, 5989.401059893976, 5798.607814428607, 5829.970850145722, 6073.180120683651, 6260.499160067093, 6328.0, 3428.674275943781, 3770.3967365221433, 3893.8247778849454, 3906.0664970839616, 3995.380715989026, 5260.526552610283, 5701.686407247641, 5848.853778655397, 5947.762089516371, 5950.553708471795, 4847.466778654275, 5601.103823388238, 5793.8551536211935, 5797.507211887037, 5824.7381466378965, 5760.94239057604, 5785.739641716217, 5797.275340582427, 5801.738921748616, 5820.854478637899, 4566.794494247701, 4955.578775803953, 5442.319710036246, 5613.326400831763, 5710.857228569319, 4967.3542439482635, 5223.477652234898, 5254.448282930237, 5715.257016587817, 5793.623414478046, 5010.273510341053, 5266.578673706018, 5621.7189140542, 5660.660360378391, 5685.630434021776], "value": 4188.212539437539, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [702.6311186627023, 716.7670507085197, 767.6507189228902, 773.9651715672801, 819.8975128108987, 13133.474661013572, 14907.701845963074, 15920.601984950354, 15938.282777275006, 16004.0, 11732.0, 12055.276683399003, 14392.352344144512, 14529.418823247084, 14579.19814410207, 597.2057163971917, 829.3738227638124, 854.4019186569395, 860.3418384935529, 862.8533149364591, 818.1450384348341, 853.2917678327004, 854.3165467625874, 855.0978717453115, 858.5707146426787, 867.0938868882002, 907.686848037426, 910.995445022774, 911.421247507834, 911.886014248219, 903.327021369081, 903.5391950105428, 903.7740564858785, 903.8960519540271, 904.5522466379184, 589.1722130406786, 652.0610321137547, 675.4123912196371, 681.0465348512123, 681.9352161544646, 14787.778183327258, 15167.24163791806, 15209.0, 15247.856410769236, 15251.008684435476, 14587.489437869624, 14605.0, 14620.561383158521, 14625.0, 14704.705905881929, 11476.598319058876, 11501.88498115025, 11583.420828958519, 11586.594469193538, 11713.297202167812, 3702.666759991587, 3788.1287303919976, 3793.7534060285993, 3857.996920800584, 3924.2543916656027, 120238.0, 125706.0, 126132.99999999908, 126779.36610316981, 127345.36327318396, 10824.296420732628, 11111.888881111085, 11186.944065279784, 11190.0, 11317.71705707364, 23032.309030729106, 23530.52938941246, 23564.7643523566, 23569.882150589136, 23583.882080589487, 7884.605769711491, 7890.092639346434, 7912.525248485109, 8137.226963438464, 8209.261166494925, 4256.531781504032, 4301.741895486281, 4310.245707001325, 4371.300591905344, 4526.23054080807, 3533.3286675531567, 3619.2218672985437, 3631.7457777955287, 3645.4167333226555, 3652.141746689526, 647.0585298390924, 656.4584218020152, 662.0896267632006, 668.3083009085573, 672.3478226120656, 647.5402464250341, 647.5790736021623, 647.6049609738056, 655.8229278094888, 660.181375094879, 8716.782080447912, 9465.847199320358, 9509.096635819586, 9525.5713492893, 9800.803983920425, 483.1737728484261, 567.9091345384719, 595.6902410746475, 628.5411649495894, 659.4856012310405, 4366.934495982563, 4527.501974782771, 4697.989917237752, 4813.614910807127, 4820.673329305372, 147113.0, 148158.0, 148160.0, 149219.50780492273, 149434.0, 126128.0, 131367.0, 133714.3314283442, 134629.3268533651, 135693.3215333917, 103668.40828979335, 113012.43493782479, 113980.0, 114096.85903140862, 116989.41505292419, 8040.517568945882, 8941.642334306554, 9026.871641044869, 9124.817503650023, 9970.55132519024, 63936.68031659812, 66197.0, 67064.65273715397, 67125.32874671191, 67389.0, 88266.0, 96544.48377227335, 103086.2068103529, 104551.0, 107342.0, 12754.362281886055, 13902.652433689238, 13949.093308934887, 14136.515665854937, 14184.432622694976, 14432.494862679756, 15086.698266034398, 15126.697466050837, 15256.694866102838, 16055.0, 11277.887221128012, 11468.197226194086, 14128.152310861382, 14178.716425671635, 14272.64318392007, 12447.439865205906, 14200.431982720576, 14442.350094245994, 14636.487722929647, 14935.551933442013, 13693.726125477633, 14021.579352619437, 14277.286135692966, 14313.427462901367, 14667.92666036663, 8962.955185224293, 10348.844767328346, 11405.315681059163, 11535.942320288344, 11958.103142264192, 7781.610919454118, 11284.943575282072, 11397.316161030365, 11404.48679809427, 11685.76628467443], "value": 7905.887523927126, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1574.1000000000001, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 115.0, 115.0, 115.0, 115.0, 116.0, 119.0, 115.0, 117.0, 134.0], "value": 116.8, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 110.0, 135.0, 163.0, 140.0, 167.0, 131.0, 115.0, 157.0, 110.0], "value": 137.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [192.0, 201.0, 210.0, 232.0, 197.0, 196.0, 181.0, 197.0, 197.0, 195.0], "value": 199.8, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 85.0, 76.0, 144.0, 74.0, 82.0, 83.0, 62.0, 130.0, 60.0], "value": 86.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 72.0, 114.0, 71.0, 71.0, 93.0, 109.0, 69.0, 73.0, 112.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 86.0, 89.0, 86.0, 89.0, 85.0, 89.0, 86.0, 89.0, 86.0], "value": 91.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 95.0, 94.0, 96.0, 95.0, 95.0, 96.0, 120.0, 122.0, 96.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 92.0, 91.0, 88.0, 93.0, 92.0, 90.0, 98.0, 148.0, 113.0], "value": 99.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [48.0, 60.0, 60.0, 59.0, 59.0, 58.0, 59.0, 56.0, 58.0, 57.0], "value": 57.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 85.0, 94.0, 124.0, 120.0, 113.0, 70.0, 68.0, 89.0, 74.0], "value": 91.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 80.0, 99.0, 111.0, 110.0, 105.0, 88.0, 89.0, 96.0, 84.0], "value": 96.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 141.0, 140.0, 140.0, 140.0, 138.0, 139.0, 127.0, 193.0, 167.0], "value": 147.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 171.0, 170.0, 170.0, 175.0, 172.0, 171.0, 218.0, 243.0, 187.0], "value": 184.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 69.0, 69.0, 62.0, 74.0, 75.0, 91.0, 90.0, 88.0, 86.0], "value": 77.4, "unit": "score"}]}]} 10:02:51 INFO - Return code: 0 10:02:51 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:02:51 ERROR - # TBPL SUCCESS # 10:02:51 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:02:51 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:02:51 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:02:51 INFO - 2016-09-06 10:02:51.425 screenresolution[1674:11168] starting screenresolution argv=screenresolution get 10:02:51 INFO - 2016-09-06 10:02:51.428 screenresolution[1674:11168] Display 0: 1600x1200x32@60 10:02:51 INFO - 2016-09-06 10:02:51.433 screenresolution[1675:11171] starting screenresolution argv=screenresolution list 10:02:51 INFO - Available Modes on Display 0 10:02:51 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:02:51 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:02:51 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:02:51 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:02:51 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:02:51 INFO - Intel Iris: 10:02:51 INFO - Chipset Model: Intel Iris 10:02:51 INFO - Type: GPU 10:02:51 INFO - Bus: Built-In 10:02:51 INFO - VRAM (Dynamic, Max): 1536 MB 10:02:51 INFO - Vendor: Intel (0x8086) 10:02:51 INFO - Device ID: 0x0a2e 10:02:51 INFO - Revision ID: 0x0009 10:02:51 INFO - Displays: 10:02:51 INFO - Display: 10:02:51 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:02:51 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:02:51 INFO - Display Serial Number: 1600x1200 60 10:02:51 INFO - Main Display: Yes 10:02:51 INFO - Mirror: Off 10:02:51 INFO - Online: Yes 10:02:51 INFO - Rotation: Supported 10:02:51 INFO - Return code: 0 10:02:51 INFO - Running post-action listener: _resource_record_post_action 10:02:51 INFO - [mozharness: 2016-09-06 17:02:51.667912Z] Finished run-tests step (success) 10:02:51 INFO - Running post-run listener: _resource_record_post_run 10:02:51 INFO - Total resource usage - Wall time: 1209s; CPU: 54.0%; Read bytes: 33180672; Write bytes: 708254208; Read time: 313; Write time: 2259 10:02:51 INFO - TinderboxPrint: CPU usage
54.4% 10:02:51 INFO - TinderboxPrint: I/O read bytes / time
33,180,672 / 313 10:02:51 INFO - TinderboxPrint: I/O write bytes / time
708,254,208 / 2,259 10:02:51 INFO - TinderboxPrint: CPU idle
2,207.2 (45.6%) 10:02:51 INFO - TinderboxPrint: CPU system
975.6 (20.2%) 10:02:51 INFO - TinderboxPrint: CPU user
1,655.0 (34.2%) 10:02:51 INFO - TinderboxPrint: Swap in / out
435,331,072 / 0 10:02:51 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 398287360; Write bytes: 392836608; Read time: 29388; Write time: 742 10:02:51 INFO - run-tests - Wall time: 1175s; CPU: 54.0%; Read bytes: 31210496; Write bytes: 298722304; Read time: 291; Write time: 1479 10:02:52 INFO - Running post-run listener: _upload_blobber_files 10:02:52 INFO - Blob upload gear active. 10:02:52 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:02:52 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:02:52 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:02:52 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:02:52 INFO - (blobuploader) - INFO - Open directory for files ... 10:02:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:02:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:02:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:04:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:00 INFO - (blobuploader) - INFO - Done attempting. 10:04:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:04:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:01 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:04:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:01 INFO - (blobuploader) - INFO - Done attempting. 10:04:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:04:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:04:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:04:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:04:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:04:03 INFO - (blobuploader) - INFO - Done attempting. 10:04:03 INFO - (blobuploader) - INFO - Iteration through files over. 10:04:03 INFO - Return code: 0 10:04:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:04:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:04:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e662ef8de607a2596b6d8c0a14b045c58825694b1203e932cf479d643179e06d54aec36c8c59872610985701ef2ecf379a746de49988c9859153f5e9d83ebefa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ace3a0b1df4dc5793d74973b0a7af35670272e7552c1eaa1906a7810ba3e8050a34ce16b8b06cdf962f59f70fad5c0802ac296ba141629cb2b53d8abecd9af91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e753736141b7217cc8abe85feb837b923acc558deae368c8c09df2607cb93bd73a1a60f883cad68f461877330accbe12b9651a0e5bcb21948ba1563149fabcd"} 10:04:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:04:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:04:03 INFO - Contents: 10:04:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e662ef8de607a2596b6d8c0a14b045c58825694b1203e932cf479d643179e06d54aec36c8c59872610985701ef2ecf379a746de49988c9859153f5e9d83ebefa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ace3a0b1df4dc5793d74973b0a7af35670272e7552c1eaa1906a7810ba3e8050a34ce16b8b06cdf962f59f70fad5c0802ac296ba141629cb2b53d8abecd9af91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e753736141b7217cc8abe85feb837b923acc558deae368c8c09df2607cb93bd73a1a60f883cad68f461877330accbe12b9651a0e5bcb21948ba1563149fabcd"} 10:04:03 INFO - Running post-run listener: copy_logs_to_upload_dir 10:04:03 INFO - Copying logs to upload dir... 10:04:03 INFO - mkdir: /builds/slave/test/build/upload/logs 10:04:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1336.219943 ========= master_lag: 36.66 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 22 mins, 52 secs) (at 2016-09-06 10:04:40.516557) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:40.523723) ========= 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.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e662ef8de607a2596b6d8c0a14b045c58825694b1203e932cf479d643179e06d54aec36c8c59872610985701ef2ecf379a746de49988c9859153f5e9d83ebefa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ace3a0b1df4dc5793d74973b0a7af35670272e7552c1eaa1906a7810ba3e8050a34ce16b8b06cdf962f59f70fad5c0802ac296ba141629cb2b53d8abecd9af91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e753736141b7217cc8abe85feb837b923acc558deae368c8c09df2607cb93bd73a1a60f883cad68f461877330accbe12b9651a0e5bcb21948ba1563149fabcd"} build_url:https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011050 build_url: 'https://queue.taskcluster.net/v1/task/ONNkEKupTLCoxwx_vjTt0A/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/e662ef8de607a2596b6d8c0a14b045c58825694b1203e932cf479d643179e06d54aec36c8c59872610985701ef2ecf379a746de49988c9859153f5e9d83ebefa", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ace3a0b1df4dc5793d74973b0a7af35670272e7552c1eaa1906a7810ba3e8050a34ce16b8b06cdf962f59f70fad5c0802ac296ba141629cb2b53d8abecd9af91", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6e753736141b7217cc8abe85feb837b923acc558deae368c8c09df2607cb93bd73a1a60f883cad68f461877330accbe12b9651a0e5bcb21948ba1563149fabcd"}' ========= master_lag: 0.37 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 10:04:40.907929) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 10:04:40.908222) ========= 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.sh3i5z6aCL/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.H4gDsifGIX/Listeners TMPDIR=/var/folders/mj/1d_lzv6s5pd4r3qyy4sndkb000000w/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.005071 ========= master_lag: 3.63 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 3 secs) (at 2016-09-06 10:04:44.541362) ========= ========= Started reboot skipped (results: 3, elapsed: 6 secs) (at 2016-09-06 10:04:44.541776) ========= ========= Finished reboot skipped (results: 3, elapsed: 6 secs) (at 2016-09-06 10:04:51.511196) ========= ========= Total master_lag: 40.78 =========