builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0137 starttime: 1473696337.74 results: success (0) buildid: 20160912062116 builduid: 7aeaaab3b5b9448da74dfb8fb58a8c25 revision: b09dd5013cefe5d66700ca0d25ceaf821509069e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:37.737035) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:37.737461) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:37.738120) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:37.854604) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:37.854870) ========= 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.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-12 09:05:37-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 156M=0s 2016-09-12 09:05:38 (156 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.594488 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:38.479617) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:38.479949) ========= 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.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.020489 ========= master_lag: 0.06 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:38.555873) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:38.556256) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev b09dd5013cefe5d66700ca0d25ceaf821509069e --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 b09dd5013cefe5d66700ca0d25ceaf821509069e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-12 09:05:38,639 truncating revision to first 12 chars 2016-09-12 09:05:38,639 Setting DEBUG logging. 2016-09-12 09:05:38,639 attempt 1/10 2016-09-12 09:05:38,639 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/b09dd5013cef?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 09:05:39,318 unpacking tar archive at: fx-team-b09dd5013cef/testing/mozharness/ program finished with exit code 0 elapsedTime=0.934652 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:39.521708) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:39.521986) ========= script_repo_revision: b09dd5013cefe5d66700ca0d25ceaf821509069e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:39.522341) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:39.522902) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 09:05:39.541110) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-12 09:05:39.541405) ========= /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.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:05:39 INFO - MultiFileLogger online at 20160912 09:05:39 in /builds/slave/test 09:05:39 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:05:39 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:05:39 INFO - {'append_to_log': False, 09:05:39 INFO - 'base_work_dir': '/builds/slave/test', 09:05:39 INFO - 'blob_upload_branch': 'Fx-Team', 09:05:39 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:05:39 INFO - 'branch': 'Fx-Team', 09:05:39 INFO - 'buildbot_json_path': 'buildprops.json', 09:05:39 INFO - 'config_files': ('talos/mac_config.py',), 09:05:39 INFO - 'default_actions': ('clobber', 09:05:39 INFO - 'read-buildbot-config', 09:05:39 INFO - 'download-and-extract', 09:05:39 INFO - 'populate-webroot', 09:05:39 INFO - 'create-virtualenv', 09:05:39 INFO - 'install', 09:05:39 INFO - 'run-tests'), 09:05:39 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:05:39 INFO - 'download_minidump_stackwalk': True, 09:05:39 INFO - 'download_symbols': 'ondemand', 09:05:39 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:05:39 INFO - 'tooltool.py': '/tools/tooltool.py', 09:05:39 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:05:39 INFO - '/tools/misc-python/virtualenv.py')}, 09:05:39 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:05:39 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:05:39 INFO - 'installer_path': 'installer.exe', 09:05:39 INFO - 'log_level': 'info', 09:05:39 INFO - 'log_name': 'talos', 09:05:39 INFO - 'log_to_console': True, 09:05:39 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:05:39 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:05:39 INFO - 'opt_config_files': (), 09:05:39 INFO - 'pip_index': False, 09:05:39 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:05:39 INFO - 'cmd': ('bash', 09:05:39 INFO - '-c', 09:05:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:05:39 INFO - 'enabled': True, 09:05:39 INFO - 'halt_on_failure': False, 09:05:39 INFO - 'name': 'check_screen_resolution'},), 09:05:39 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:05:39 INFO - 'cmd': ('bash', 09:05:39 INFO - '-c', 09:05:39 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:05:39 INFO - 'enabled': True, 09:05:39 INFO - 'halt_on_failure': False, 09:05:39 INFO - 'name': 'check_screen_resolution'},), 09:05:39 INFO - 'run_cmd_checks_enabled': True, 09:05:39 INFO - 'sps_profile': False, 09:05:39 INFO - 'sps_profile_interval': 0, 09:05:39 INFO - 'suite': 'dromaeojs-e10s', 09:05:39 INFO - 'system_bits': '32', 09:05:39 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:05:39 INFO - 'title': 't-yosemite-r7-0137', 09:05:39 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:05:39 INFO - 'use_talos_json': True, 09:05:39 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:05:39 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:05:39 INFO - 'work_dir': 'build'} 09:05:39 INFO - [mozharness: 2016-09-12 16:05:39.734343Z] Running clobber step. 09:05:39 INFO - Running pre-action listener: _resource_record_pre_action 09:05:39 INFO - Running main action method: clobber 09:05:39 INFO - rmtree: /builds/slave/test/build 09:05:39 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:05:41 INFO - Running post-action listener: _resource_record_post_action 09:05:41 INFO - [mozharness: 2016-09-12 16:05:41.834687Z] Finished clobber step (success) 09:05:41 INFO - [mozharness: 2016-09-12 16:05:41.834805Z] Running read-buildbot-config step. 09:05:41 INFO - Running pre-action listener: _resource_record_pre_action 09:05:41 INFO - Running main action method: read_buildbot_config 09:05:41 INFO - Using buildbot properties: 09:05:41 INFO - { 09:05:41 INFO - "project": "", 09:05:41 INFO - "product": "firefox", 09:05:41 INFO - "script_repo_revision": "production", 09:05:41 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:05:41 INFO - "repository": "", 09:05:41 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:05:41 INFO - "buildid": "20160912062116", 09:05:41 INFO - "pgo_build": "False", 09:05:41 INFO - "basedir": "/builds/slave/test", 09:05:41 INFO - "buildnumber": 113, 09:05:41 INFO - "slavename": "t-yosemite-r7-0137", 09:05:41 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 09:05:41 INFO - "platform": "yosemite_r7", 09:05:41 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 09:05:41 INFO - "branch": "fx-team", 09:05:41 INFO - "repo_path": "integration/fx-team", 09:05:41 INFO - "revision": "b09dd5013cefe5d66700ca0d25ceaf821509069e", 09:05:41 INFO - "stage_platform": "macosx64", 09:05:41 INFO - "builduid": "7aeaaab3b5b9448da74dfb8fb58a8c25", 09:05:41 INFO - "slavebuilddir": "test" 09:05:41 INFO - } 09:05:41 INFO - Found installer url https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 09:05:41 INFO - Running post-action listener: _resource_record_post_action 09:05:41 INFO - [mozharness: 2016-09-12 16:05:41.838193Z] Finished read-buildbot-config step (success) 09:05:41 INFO - [mozharness: 2016-09-12 16:05:41.838311Z] Running download-and-extract step. 09:05:41 INFO - Running pre-action listener: _resource_record_pre_action 09:05:41 INFO - Running main action method: download_and_extract 09:05:41 INFO - mkdir: /builds/slave/test/build/tests 09:05: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:05:41 INFO - trying https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 09:05:41 INFO - Downloading https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/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:05:41 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/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:05:42 INFO - Downloaded 1199 bytes. 09:05:42 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 09:05:42 INFO - Using the following test package requirements: 09:05:42 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:05:42 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 09:05:42 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 09:05:42 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'jsshell-mac.zip'], 09:05:42 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 09:05:42 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 09:05:42 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 09:05:42 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 09:05:42 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 09:05:42 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 09:05:42 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 09:05:42 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:05:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:43 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:05:50 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:05:51 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 09:05:51 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:05:58 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 09:05:58 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 09:05:59 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:05:59 INFO - trying https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:05:59 INFO - Downloading https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 09:05:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/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:06:04 INFO - Downloaded 111633686 bytes. 09:06:04 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:06:04 INFO - mkdir: /builds/slave/test/properties 09:06:04 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:06:04 INFO - Writing to file /builds/slave/test/properties/build_url 09:06:04 INFO - Contents: 09:06:04 INFO - build_url:https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 09:06:04 INFO - Running post-action listener: _resource_record_post_action 09:06:04 INFO - Running post-action listener: set_extra_try_arguments 09:06:04 INFO - [mozharness: 2016-09-12 16:06:04.701357Z] Finished download-and-extract step (success) 09:06:04 INFO - [mozharness: 2016-09-12 16:06:04.701477Z] Running populate-webroot step. 09:06:04 INFO - Running pre-action listener: _resource_record_pre_action 09:06:04 INFO - Running main action method: populate_webroot 09:06:04 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:06:04 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:06:04 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:06:04 INFO - '--tppagecycles', 09:06:04 INFO - '7'], 09:06:04 INFO - 'tests': ['tsvgm']}}, 09:06:04 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:06:04 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:06:04 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:06:04 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:06:04 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:04 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:06:04 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['damp', 'tps']}, 09:06:04 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'tests': ['damp', 'tps']}, 09:06:04 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['dromaeo_dom']}, 09:06:04 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:06:04 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['basic_compositor_video']}, 09:06:04 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:06:04 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'other-e10s': {'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['a11yr', 09:06:04 INFO - 'ts_paint', 09:06:04 INFO - 'tpaint', 09:06:04 INFO - 'sessionrestore', 09:06:04 INFO - 'sessionrestore_no_auto_restore', 09:06:04 INFO - 'tabpaint']}, 09:06:04 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['tsvgx', 09:06:04 INFO - 'tsvgr_opacity', 09:06:04 INFO - 'tart', 09:06:04 INFO - 'tscrollx', 09:06:04 INFO - 'cart']}, 09:06:04 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:06:04 INFO - 'tsvgr_opacity', 09:06:04 INFO - 'tart', 09:06:04 INFO - 'tscrollx', 09:06:04 INFO - 'cart']}, 09:06:04 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'talos_options': ['--disable-e10s'], 09:06:04 INFO - 'tests': ['tp5o']}, 09:06:04 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'tests': ['tp5o']}, 09:06:04 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'talos_options': ['--disable-e10s', 09:06:04 INFO - '--xperf_path', 09:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:04 INFO - 'tests': ['tp5n']}, 09:06:04 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:06:04 INFO - 'talos_options': ['--xperf_path', 09:06:04 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:06:04 INFO - 'tests': ['tp5n']}}, 09:06:04 INFO - 'talos.zip': {'path': '', 09:06:04 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:06:04 INFO - Running post-action listener: _resource_record_post_action 09:06:04 INFO - [mozharness: 2016-09-12 16:06:04.720217Z] Finished populate-webroot step (success) 09:06:04 INFO - [mozharness: 2016-09-12 16:06:04.720309Z] Running create-virtualenv step. 09:06:04 INFO - Running pre-action listener: _resource_record_pre_action 09:06:04 INFO - Running main action method: create_virtualenv 09:06:04 INFO - Creating virtualenv /builds/slave/test/build/venv 09:06:04 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:06:04 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:06:04 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:06:04 INFO - Using real prefix '/tools/python27' 09:06:04 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:06:05 INFO - Installing distribute.............................................................................................................................................................................................done. 09:06:08 INFO - Installing pip.................done. 09:06:08 INFO - Return code: 0 09:06:08 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:08 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:06:08 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:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:08 INFO - 'HOME': '/Users/cltbld', 09:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:08 INFO - 'LOGNAME': 'cltbld', 09:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:08 INFO - 'MOZ_NO_REMOTE': '1', 09:06:08 INFO - 'NO_EM_RESTART': '1', 09:06:08 INFO - 'PAGER': '/bin/cat', 09:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:08 INFO - 'PWD': '/builds/slave/test', 09:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:08 INFO - 'SHELL': '/bin/bash', 09:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:08 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:08 INFO - 'USER': 'cltbld', 09:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:08 INFO - 'XPC_FLAGS': '0x0', 09:06:08 INFO - 'XPC_SERVICE_NAME': '0', 09:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:08 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:06:08 INFO - Cleaning up... 09:06:08 INFO - Return code: 0 09:06:08 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:08 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:08 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:06:08 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:06:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:08 INFO - 'HOME': '/Users/cltbld', 09:06:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:08 INFO - 'LOGNAME': 'cltbld', 09:06:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:08 INFO - 'MOZ_NO_REMOTE': '1', 09:06:08 INFO - 'NO_EM_RESTART': '1', 09:06:08 INFO - 'PAGER': '/bin/cat', 09:06:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:08 INFO - 'PWD': '/builds/slave/test', 09:06:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:08 INFO - 'SHELL': '/bin/bash', 09:06:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:08 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:08 INFO - 'USER': 'cltbld', 09:06:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:08 INFO - 'XPC_FLAGS': '0x0', 09:06:08 INFO - 'XPC_SERVICE_NAME': '0', 09:06:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:08 INFO - Downloading/unpacking psutil>=3.1.1 09:06:08 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:06:08 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:06:08 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:06:08 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:06:08 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:06:08 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:06:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:06:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:12 INFO - Installing collected packages: psutil 09:06:12 INFO - Running setup.py install for psutil 09:06:12 INFO - building 'psutil._psutil_osx' extension 09:06:12 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:06:12 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:06:12 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:06:12 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:06:12 INFO - building 'psutil._psutil_posix' extension 09:06:12 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:06:12 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:06:12 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:06:12 INFO - ^ 09:06:12 INFO - 1 warning generated. 09:06:12 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:06:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:06:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:06:13 INFO - Successfully installed psutil 09:06:13 INFO - Cleaning up... 09:06:13 INFO - Return code: 0 09:06:13 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:06:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:13 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:13 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:06:13 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:06:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:13 INFO - 'HOME': '/Users/cltbld', 09:06:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:13 INFO - 'LOGNAME': 'cltbld', 09:06:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:13 INFO - 'MOZ_NO_REMOTE': '1', 09:06:13 INFO - 'NO_EM_RESTART': '1', 09:06:13 INFO - 'PAGER': '/bin/cat', 09:06:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:13 INFO - 'PWD': '/builds/slave/test', 09:06:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:13 INFO - 'SHELL': '/bin/bash', 09:06:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:13 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:13 INFO - 'USER': 'cltbld', 09:06:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:13 INFO - 'XPC_FLAGS': '0x0', 09:06:13 INFO - 'XPC_SERVICE_NAME': '0', 09:06:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:13 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:06:13 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:06:13 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:06:13 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:06:13 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:06:13 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:06:13 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:06:15 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:06:15 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:06:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:06:15 INFO - Installing collected packages: mozsystemmonitor 09:06:15 INFO - Running setup.py install for mozsystemmonitor 09:06:16 INFO - Successfully installed mozsystemmonitor 09:06:16 INFO - Cleaning up... 09:06:16 INFO - Return code: 0 09:06:16 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:06: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:06:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06: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:06:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:16 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:16 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:06:16 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:06:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:16 INFO - 'HOME': '/Users/cltbld', 09:06:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:16 INFO - 'LOGNAME': 'cltbld', 09:06:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:16 INFO - 'MOZ_NO_REMOTE': '1', 09:06:16 INFO - 'NO_EM_RESTART': '1', 09:06:16 INFO - 'PAGER': '/bin/cat', 09:06:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:16 INFO - 'PWD': '/builds/slave/test', 09:06:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:16 INFO - 'SHELL': '/bin/bash', 09:06:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:16 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:16 INFO - 'USER': 'cltbld', 09:06:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:16 INFO - 'XPC_FLAGS': '0x0', 09:06:16 INFO - 'XPC_SERVICE_NAME': '0', 09:06:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:16 INFO - Downloading/unpacking jsonschema==2.5.1 09:06:16 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:06:16 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:06:16 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:06:16 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:06:16 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:06:16 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:06:18 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:06:18 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:18 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:06:19 INFO - Downloading functools32-3.2.3-2.tar.gz 09:06:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:06:19 INFO - warning: no files found matching '*.txt' 09:06:19 INFO - no previously-included directories found matching 'build' 09:06:19 INFO - no previously-included directories found matching 'dist' 09:06:19 INFO - no previously-included directories found matching '.git*' 09:06:19 INFO - Installing collected packages: jsonschema, functools32 09:06:19 INFO - Running setup.py install for functools32 09:06:19 INFO - warning: no files found matching '*.txt' 09:06:19 INFO - no previously-included directories found matching 'build' 09:06:19 INFO - no previously-included directories found matching 'dist' 09:06:19 INFO - no previously-included directories found matching '.git*' 09:06:19 INFO - Successfully installed jsonschema functools32 09:06:19 INFO - Cleaning up... 09:06:19 INFO - Return code: 0 09:06:19 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:06:19 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:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:19 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:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:19 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:19 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:06:19 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:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:19 INFO - 'HOME': '/Users/cltbld', 09:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:19 INFO - 'LOGNAME': 'cltbld', 09:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:19 INFO - 'MOZ_NO_REMOTE': '1', 09:06:19 INFO - 'NO_EM_RESTART': '1', 09:06:19 INFO - 'PAGER': '/bin/cat', 09:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:19 INFO - 'PWD': '/builds/slave/test', 09:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:19 INFO - 'SHELL': '/bin/bash', 09:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:19 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:19 INFO - 'USER': 'cltbld', 09:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:19 INFO - 'XPC_FLAGS': '0x0', 09:06:19 INFO - 'XPC_SERVICE_NAME': '0', 09:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:06:19 INFO - Cleaning up... 09:06:19 INFO - Return code: 0 09:06:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:06:19 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:06:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:19 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:06:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:19 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:19 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:06:19 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:06:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:19 INFO - 'HOME': '/Users/cltbld', 09:06:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:19 INFO - 'LOGNAME': 'cltbld', 09:06:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:19 INFO - 'MOZ_NO_REMOTE': '1', 09:06:19 INFO - 'NO_EM_RESTART': '1', 09:06:19 INFO - 'PAGER': '/bin/cat', 09:06:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:19 INFO - 'PWD': '/builds/slave/test', 09:06:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:19 INFO - 'SHELL': '/bin/bash', 09:06:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:19 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:19 INFO - 'USER': 'cltbld', 09:06:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:19 INFO - 'XPC_FLAGS': '0x0', 09:06:19 INFO - 'XPC_SERVICE_NAME': '0', 09:06:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:20 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:20 INFO - Downloading/unpacking blobuploader==1.2.4 09:06: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:06: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:06: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:06: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:06: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:06: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:06:22 INFO - Downloading blobuploader-1.2.4.tar.gz 09:06:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:06:22 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:06:22 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:06:22 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:06:22 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:06:22 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:06:22 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:06:22 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:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:06:23 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:06: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:06: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:06: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:06: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:06: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:06: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:06:23 INFO - Downloading docopt-0.6.1.tar.gz 09:06:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:06:23 INFO - Installing collected packages: blobuploader, requests, docopt 09:06:23 INFO - Running setup.py install for blobuploader 09:06:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:06:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:06:24 INFO - Running setup.py install for requests 09:06:24 INFO - Running setup.py install for docopt 09:06:24 INFO - Successfully installed blobuploader requests docopt 09:06:24 INFO - Cleaning up... 09:06:24 INFO - Return code: 0 09:06:24 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:24 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:06:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:24 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:06:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:24 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:24 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:06:24 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:06:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:24 INFO - 'HOME': '/Users/cltbld', 09:06:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:24 INFO - 'LOGNAME': 'cltbld', 09:06:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:24 INFO - 'MOZ_NO_REMOTE': '1', 09:06:24 INFO - 'NO_EM_RESTART': '1', 09:06:24 INFO - 'PAGER': '/bin/cat', 09:06:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:24 INFO - 'PWD': '/builds/slave/test', 09:06:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:24 INFO - 'SHELL': '/bin/bash', 09:06:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:24 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:24 INFO - 'USER': 'cltbld', 09:06:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:24 INFO - 'XPC_FLAGS': '0x0', 09:06:24 INFO - 'XPC_SERVICE_NAME': '0', 09:06:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:24 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-_ImJwj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-uF63k1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-GEti6w-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-9o_blC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Z3XSNa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-daYHDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-vVQGfa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-MUApHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-bYSsqy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:25 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-gw3RHc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-EMmWmI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-Lj8H4m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-HlRqP3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-m3OGng-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rT8_91-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-yOl_RB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:26 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:26 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-c9G8nU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:26 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:06:26 INFO - Running setup.py install for manifestparser 09:06:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:06:26 INFO - Running setup.py install for mozcrash 09:06:27 INFO - Running setup.py install for mozdebug 09:06:27 INFO - Running setup.py install for mozdevice 09:06:27 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozfile 09:06:27 INFO - Running setup.py install for mozhttpd 09:06:27 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozinfo 09:06:27 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozInstall 09:06:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:06:27 INFO - Running setup.py install for mozleak 09:06:28 INFO - Running setup.py install for mozlog 09:06:28 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Running setup.py install for moznetwork 09:06:28 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Running setup.py install for mozprocess 09:06:28 INFO - Running setup.py install for mozprofile 09:06:28 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Running setup.py install for mozrunner 09:06:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:06:28 INFO - Running setup.py install for mozscreenshot 09:06:29 INFO - Running setup.py install for moztest 09:06:29 INFO - Running setup.py install for mozversion 09:06:29 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:06:29 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:06:29 INFO - Cleaning up... 09:06:29 INFO - Return code: 0 09:06:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:29 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:06:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:29 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:06:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:29 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:29 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:06:29 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:06:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:29 INFO - 'HOME': '/Users/cltbld', 09:06:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:29 INFO - 'LOGNAME': 'cltbld', 09:06:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:29 INFO - 'MOZ_NO_REMOTE': '1', 09:06:29 INFO - 'NO_EM_RESTART': '1', 09:06:29 INFO - 'PAGER': '/bin/cat', 09:06:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:29 INFO - 'PWD': '/builds/slave/test', 09:06:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:29 INFO - 'SHELL': '/bin/bash', 09:06:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:29 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:29 INFO - 'USER': 'cltbld', 09:06:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:29 INFO - 'XPC_FLAGS': '0x0', 09:06:29 INFO - 'XPC_SERVICE_NAME': '0', 09:06:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:06:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-H3vCBe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:06:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-ik3isA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:06:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-utrf36-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:06:29 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:06:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:06:29 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-nPiW9K-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-LFhMTe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-KxzWqq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-dKBzwz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-rYpZ0o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-2X6nYT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-cLyfvw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-PFGtyC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-wErqVW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:06:30 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:06:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:06:30 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-onEg2C-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:06:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-J46GRY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-DrqvML-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:06:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-crYvvc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:06:31 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:06:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:06:31 INFO - Running setup.py (path:/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/pip-sI0i6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:06:31 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:06:31 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:06:31 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:06:31 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:06:31 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:06:31 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:06:31 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:31 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:31 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:06:31 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:06:31 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:06:33 INFO - Downloading blessings-1.6.tar.gz 09:06:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:06:34 INFO - Installing collected packages: blessings 09:06:34 INFO - Running setup.py install for blessings 09:06:34 INFO - Successfully installed blessings 09:06:34 INFO - Cleaning up... 09:06:34 INFO - Return code: 0 09:06:34 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:06:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:34 INFO - Reading from file tmpfile_stdout 09:06:34 INFO - Current package versions: 09:06:34 INFO - blessings == 1.6 09:06:34 INFO - blobuploader == 1.2.4 09:06:34 INFO - docopt == 0.6.1 09:06:34 INFO - functools32 == 3.2.3-2 09:06:34 INFO - jsonschema == 2.5.1 09:06:34 INFO - manifestparser == 1.1 09:06:34 INFO - mozInstall == 1.12 09:06:34 INFO - mozcrash == 0.17 09:06:34 INFO - mozdebug == 0.1 09:06:34 INFO - mozdevice == 0.48 09:06:34 INFO - mozfile == 1.2 09:06:34 INFO - mozhttpd == 0.7 09:06:34 INFO - mozinfo == 0.9 09:06:34 INFO - mozleak == 0.1 09:06:34 INFO - mozlog == 3.3 09:06:34 INFO - moznetwork == 0.27 09:06:34 INFO - mozprocess == 0.23 09:06:34 INFO - mozprofile == 0.28 09:06:34 INFO - mozrunner == 6.12 09:06:34 INFO - mozscreenshot == 0.1 09:06:34 INFO - mozsystemmonitor == 0.3 09:06:34 INFO - moztest == 0.7 09:06:34 INFO - mozversion == 1.4 09:06:34 INFO - psutil == 3.1.1 09:06:34 INFO - requests == 1.2.3 09:06:34 INFO - wsgiref == 0.1.2 09:06:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:06:34 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:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 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:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:34 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:06:34 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:06:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:34 INFO - 'HOME': '/Users/cltbld', 09:06:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:34 INFO - 'LOGNAME': 'cltbld', 09:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:34 INFO - 'MOZ_NO_REMOTE': '1', 09:06:34 INFO - 'NO_EM_RESTART': '1', 09:06:34 INFO - 'PAGER': '/bin/cat', 09:06:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:34 INFO - 'PWD': '/builds/slave/test', 09:06:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:34 INFO - 'SHELL': '/bin/bash', 09:06:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:34 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:34 INFO - 'USER': 'cltbld', 09:06:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:34 INFO - 'XPC_FLAGS': '0x0', 09:06:34 INFO - 'XPC_SERVICE_NAME': '0', 09:06:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 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:06:34 INFO - Cleaning up... 09:06:34 INFO - Return code: 0 09:06:34 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:06:34 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:06:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 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:06:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:06:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:06:34 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 0x10123b490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1011b1570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8bc3486810>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, '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.Cr7LlCImIO/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.PcabCR5jYp/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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:06:34 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:06:34 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:06:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:06:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:06:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:06:34 INFO - 'HOME': '/Users/cltbld', 09:06:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:06:34 INFO - 'LOGNAME': 'cltbld', 09:06:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:06:34 INFO - 'MOZ_NO_REMOTE': '1', 09:06:34 INFO - 'NO_EM_RESTART': '1', 09:06:34 INFO - 'PAGER': '/bin/cat', 09:06:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:06:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:06:34 INFO - 'PWD': '/builds/slave/test', 09:06:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:06:34 INFO - 'SHELL': '/bin/bash', 09:06:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:06:34 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:06:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:06:34 INFO - 'USER': 'cltbld', 09:06:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:06:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:06:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:06:34 INFO - 'XPC_FLAGS': '0x0', 09:06:34 INFO - 'XPC_SERVICE_NAME': '0', 09:06:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:06:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:06:35 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:06:35 INFO - Cleaning up... 09:06:35 INFO - Return code: 0 09:06:35 INFO - Running post-action listener: _resource_record_post_action 09:06:35 INFO - Running post-action listener: _start_resource_monitoring 09:06:35 INFO - Starting resource monitoring. 09:06:35 INFO - [mozharness: 2016-09-12 16:06:35.172620Z] Finished create-virtualenv step (success) 09:06:35 INFO - [mozharness: 2016-09-12 16:06:35.173690Z] Running install step. 09:06:35 INFO - Running pre-action listener: _resource_record_pre_action 09:06:35 INFO - Running main action method: install 09:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:06:35 INFO - Reading from file tmpfile_stdout 09:06:35 INFO - Detecting whether we're running mozinstall >=1.0... 09:06:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:06:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:06:35 INFO - Reading from file tmpfile_stdout 09:06:35 INFO - Output received: 09:06:35 INFO - Usage: mozinstall [options] installer 09:06:35 INFO - Options: 09:06:35 INFO - -h, --help show this help message and exit 09:06:35 INFO - -d DEST, --destination=DEST 09:06:35 INFO - Directory to install application into. [default: 09:06:35 INFO - "/builds/slave/test"] 09:06:35 INFO - --app=APP Application being installed. [default: firefox] 09:06:35 INFO - mkdir: /builds/slave/test/build/application 09:06:35 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:06:35 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:07:09 INFO - Reading from file tmpfile_stdout 09:07:09 INFO - Output received: 09:07:09 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:07:09 INFO - Running post-action listener: _resource_record_post_action 09:07:09 INFO - [mozharness: 2016-09-12 16:07:09.684060Z] Finished install step (success) 09:07:09 INFO - [mozharness: 2016-09-12 16:07:09.684330Z] Running run-tests step. 09:07:09 INFO - Running pre-action listener: _resource_record_pre_action 09:07:09 INFO - Running main action method: run_tests 09:07:09 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:07:09 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:07:09 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:07:09 INFO - 2016-09-12 09:07:09.698 screenresolution[2246:75644] starting screenresolution argv=screenresolution get 09:07:09 INFO - 2016-09-12 09:07:09.701 screenresolution[2246:75644] Display 0: 1600x1200x32@60 09:07:09 INFO - 2016-09-12 09:07:09.707 screenresolution[2247:75646] starting screenresolution argv=screenresolution list 09:07:09 INFO - Available Modes on Display 0 09:07:09 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:07:09 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:07:09 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:07:09 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:07:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:07:09 INFO - Intel Iris: 09:07:09 INFO - Chipset Model: Intel Iris 09:07:09 INFO - Type: GPU 09:07:09 INFO - Bus: Built-In 09:07:09 INFO - VRAM (Dynamic, Max): 1536 MB 09:07:09 INFO - Vendor: Intel (0x8086) 09:07:09 INFO - Device ID: 0x0a2e 09:07:09 INFO - Revision ID: 0x0009 09:07:09 INFO - Displays: 09:07:09 INFO - Display: 09:07:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:07:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:07:09 INFO - Display Serial Number: 1600x1200 60 09:07:09 INFO - Main Display: Yes 09:07:09 INFO - Mirror: Off 09:07:09 INFO - Online: Yes 09:07:09 INFO - Rotation: Supported 09:07:09 INFO - Return code: 0 09:07:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:07:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:07:09 INFO - Python 2.7.3 09:07:09 INFO - Return code: 0 09:07:09 INFO - grabbing minidump binary from tooltool 09:07:09 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:07:09 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 0x10104fd30>, '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 0x100e75db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x101047b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:07:09 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:07:09 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:07:09 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:07:10 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:07:10 INFO - Return code: 0 09:07:10 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:07:10 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:07:10 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:07:10 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:07:10 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:07:10 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/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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:07:10 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/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0137 --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:07:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Cr7LlCImIO/Render', 09:07:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:07:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:07:10 INFO - 'HOME': '/Users/cltbld', 09:07:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:07:10 INFO - 'LOGNAME': 'cltbld', 09:07:10 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:07:10 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:07:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:07:10 INFO - 'MOZ_NO_REMOTE': '1', 09:07:10 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:07:10 INFO - 'NO_EM_RESTART': '1', 09:07:10 INFO - 'PAGER': '/bin/cat', 09:07:10 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:07:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:07:10 INFO - 'PWD': '/builds/slave/test', 09:07:10 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:07:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:07:10 INFO - 'SHELL': '/bin/bash', 09:07:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.PcabCR5jYp/Listeners', 09:07:10 INFO - 'TMPDIR': '/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/', 09:07:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:07:10 INFO - 'USER': 'cltbld', 09:07:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:07:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:07:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:07:10 INFO - 'XPC_FLAGS': '0x0', 09:07:10 INFO - 'XPC_SERVICE_NAME': '0', 09:07:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:07:10 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/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0137', '--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:07:10 INFO - mozversion application_buildid: 20160912062116 09:07:10 INFO - mozversion application_changeset: b09dd5013cefe5d66700ca0d25ceaf821509069e 09:07:10 INFO - mozversion application_display_name: Nightly 09:07:10 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:07:10 INFO - mozversion application_name: Firefox 09:07:10 INFO - mozversion application_remotingname: firefox 09:07:10 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:07:10 INFO - mozversion application_vendor: Mozilla 09:07:10 INFO - mozversion application_version: 51.0a1 09:07:10 INFO - mozversion platform_buildid: 20160912062116 09:07:10 INFO - mozversion platform_changeset: b09dd5013cefe5d66700ca0d25ceaf821509069e 09:07:10 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:07:10 INFO - mozversion platform_version: 51.0a1 09:07:10 INFO - using testdate: 1473696430 09:07:10 INFO - actual date: 1473696430 09:07:10 INFO - starting webserver on 'localhost:54287' 09:07:10 INFO - SUITE-START | Running 2 tests 09:07:10 INFO - TEST-START | dromaeo_css 09:07:10 INFO - operating with platform_type : mac_ 09:07:10 INFO - Initialising browser for dromaeo_css test... 09:07:10 INFO - TEST-INFO | started process 2257 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpKmLEoG/profile http://localhost:54287/getInfo.html) 09:07:13 INFO - PROCESS | 2257 | __metrics Screen width/height:1600/1200 09:07:13 INFO - PROCESS | 2257 | colorDepth:24 09:07:13 INFO - PROCESS | 2257 | Browser inner width/height: 1024/689 09:07:13 INFO - PROCESS | 2257 | __metrics 09:07:13 ERROR - PROCESS | 2257 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 09:07:14 INFO - TEST-INFO | 2257: exit 0 09:07:14 INFO - Browser initialized. 09:07:14 INFO - Running cycle 1/1 for dromaeo_css test... 09:07:14 INFO - TEST-INFO | started process 2260 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpKmLEoG/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:07:26 INFO - PROCESS | 2260 | RSS: Main: 243957760 09:07:26 INFO - PROCESS | 2260 | 09:08:15 INFO - PROCESS | 2260 | 1473696495861 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:10:27 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-dojo.html (next: http://localhost:54287/tests/dromaeo/cssquery-ext.html) 09:10:29 INFO - PROCESS | 2260 | RSS: Main: 262520832 09:10:29 INFO - PROCESS | 2260 | 09:13:31 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-ext.html (next: http://localhost:54287/tests/dromaeo/cssquery-jquery.html) 09:13:32 INFO - PROCESS | 2260 | RSS: Main: 268320768 09:13:32 INFO - PROCESS | 2260 | 09:16:34 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-jquery.html (next: http://localhost:54287/tests/dromaeo/cssquery-mootools.html) 09:16:34 INFO - PROCESS | 2260 | RSS: Main: 273207296 09:16:34 INFO - PROCESS | 2260 | 09:19:36 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-mootools.html (next: http://localhost:54287/tests/dromaeo/cssquery-prototype.html) 09:19:37 INFO - PROCESS | 2260 | RSS: Main: 269533184 09:19:37 INFO - PROCESS | 2260 | 09:22:40 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-prototype.html (next: http://localhost:54287/tests/dromaeo/cssquery-yui.html) 09:22:40 INFO - PROCESS | 2260 | RSS: Main: 275836928 09:22:40 INFO - PROCESS | 2260 | 09:25:43 INFO - PROCESS | 2260 | Cycle 1(1): loaded http://localhost:54287/tests/dromaeo/cssquery-yui.html (next: http://localhost:54287/tests/dromaeo/cssquery-dojo.html) 09:25:44 INFO - PROCESS | 2260 | RSS: Main: 278585344 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | __start_tp_report 09:25:44 INFO - PROCESS | 2260 | _x_x_mozilla_page_load 09:25:44 INFO - PROCESS | 2260 | _x_x_mozilla_page_load_details 09:25:44 INFO - PROCESS | 2260 | |i|pagename|runs| 09:25:44 INFO - PROCESS | 2260 | |0;dojo.html;2617.887397855911;2844.31736383268;2940.514815055516;3436.82815859207;3462.1517728156596;4475.127350166719;4683.5550622690835;5166.354205724285;5708.686022268774;5858.326292476367;5804.129380592914;5811.564132690055;5819.214406055181;5936.050231962887;5974.372690867462;5255.658382205154;5306.257124002643;5381.300430943991;5425.538829199523;5428.809991650293;4821.421429428456;5338.893222135555;5437.673739575638;5454.127339625661;5494;5229.163333866582;5296.973515132399;5323.653962492464;5374.951884382547;5417.377001644822;5224.4775522447835;5327;5344.492273234076;5353.384360798519;5376.973115134439;5305.522502974713;5315.973420132875;5327.493888080626;5338.386085600167;5398.865028374322;4049.3925911113274;5637.323521177444;5753.050746626802;5820.796272130455;5869.914065897822;6238.5944913580925;6273.435390814803;6280.968595157041;6295.527835412362;6296;6274.152989346471;6301;6314.463270621956;6316.021016742412;6319.684015799192;5738.684372359518;5844.503217226498;5852.824415267549;5884.58807883444;5926.4073592640825;5805.216295800097;5883.676397798076;6371.808845734636;6398.776042838478;6402.775902843378;6187.225380433362;6197.4732147767045;6218.19163508741;6257.092721555349;6288.874222515524;6171.074338849208;6210.4100110489435;6266.624002559861;6314.9684251579365;6331.905021424683;5300.9734951325;6198.132261483396;6299;6300.621962682162;6309.747610095545;6165.630062196283;6278.4035516625845;6289.339619339995;6322.399372059739;6334.334894835976;4779.235322348408;4953.0589188054155;5005.224190250517;5078.339815823919;5123.257127716461;4418.624416924597;4471.859675782654;4530.161920044802;4602.677812553089;4617.79219935097;5741.1388291756575;5741.540676745851;5802.564807639444;5821.854453638693;5855.4730074292665;6227.657478838707;6253.437190652863;6276.968615156895;6282.4974002079725;6292.370762923671;5737.340205876294;5757.510611597977;5774.018416869142;5855.443732845373;5893.793717219963;5514.200440936042;5520.66875987442;5525.530329921921;5533.6956467393875;5544.390117087117;6256.874862502816;6432.839179020469;6492.35076492347;6507;6507.934920650733;6260.812175634738;6267.529935254783;6271;6293.811185664437;6294.590851594632;6406.679666016774;6438;6474.320196379313;6477.838054048687;6490.253620833664;6111.652512877656;6177.505799536116;6182.752689892354;6270.7805226816845;6318.08387783778;5212.791488340424;6189.164462797465;6254.749810007549;6295.905561416674;6308.148399965946;4625.375574297427;4803.783829727601;5133.307003554622;6164.921138800602;6202.596831205957;6098.512119030555;6115.541334399849;6183;6217.720202590985;6233.065040244;4446.888827779331;5552.3892371839875;6031.668258245663;6057.57596968208;6124.295705993868;5108.872278193075;6179.28938172098;6210.472109870531;6215.06773983906;6223.688815559294;5932.673702946379;6126.724297406538;6129.325774164749;6130.2030736004035;6132 09:25:44 INFO - PROCESS | 2260 | |1;ext.html;430.44687576464247;461.0847467776465;463.7101811367895;474.2956709286711;538.9786354857545;40235.396469052954;41794.37308440372;42265.366019509696;44277.11445771091;44520.3321950171;41585;41876;42477.57522424775;42575.78712106443;42601.721948341525;9723.319367644268;9912.851307230398;9941.652042178524;9945.154661853714;9958.153556947667;9813.65652202173;9943.552540135712;9947.502624868765;9951.800963980717;9975.850362244571;9943.10512053915;9973.401595904234;9979.351342162772;9982.750431239205;9988.101070903653;9862;9891.25815563832;9927;9929.55317010729;9958.502074896227;9769.706908792676;9841.114299712988;9841.704748857546;9851.458169800659;9865.506724663737;35028.299434011176;40292.992675183064;40952.79523602393;41267.79366103181;41483.377749333784;3328.700416962485;3366.764326497146;3422.469517224834;3559.430491121408;3777.942176190671;30318.242043948856;30852;30871.536926946115;30943.916962906416;31541.695199915852;3157.137541857869;3199.392115498048;3204.3431096625136;3217.3726123405945;3278.8852390166467;487387;487480;489441;492172;493229;58191.70904145537;58490;58633.4136658628;58712.41287587155;58811.82376352449;28089.297767555974;33511.83244083764;34762.65237347594;60762.696186518784;61121.77756444935;16522.58693532671;16696.58258543522;16726;16740.330386784393;16757.664846703243;12702.237865728086;12888.677783055311;12975.480980760853;12978.35108244584;12981.805272920914;18811.623767524572;18884.43346699601;18895.43313700591;18910.243590256236;18929.81070189308;7624.618769061635;7786.454948153575;7789;7828.84342313162;7874.448788584858;855.5679381912095;949.2975198353307;984.3749219245831;1020.6529779874878;1030.1038096855634;28943;29955.85022074919;30031.249218769706;30266;30680.846595766878;1016.1058268723476;1031.8452232165234;1047.8899715529913;1049.7847941172042;1143.616888342398;13270.66823329405;13527.86472135266;14862.777058344134;15099.54701358961;15666.451674191349;685239;706952;709637;709638;709993.4500327465;46162.538374615826;57052.71473642605;57071;57910.7104464475;57980.130298045566;1370.4997675848244;1380.9792853107213;1414.0525847682077;1421.6588018875395;1426.4864648726657;28155.155345339674;29015.56476652894;29152.979645713145;29829.70170298356;29885.850570747007;51585.484145158065;51735.74132129315;52066;52218.738906305225;52238.47761522488;649118;655044;655263;655430;659447;41887.58112418837;42263.7886810564;42881.14237715291;43060.77044221633;43116.13767724691;45824;46879.06241875075;47137.2929406066;47369.52630473789;47443.05113897772;38771;38796.41805372865;38834.41748373833;39590.208195835345;40219.59780402276;38285.6171438282;38350.42474362831;38919;39627.405588916765;39892.60107398889;27658.861705691343;33174.83412583018;34100.317993640485;35919.28161436704;36052.819735901154;31381.686183137877;34097.65902340945;34420.82789586036;34729.13177170591;34796.30407391889;22898.54202915899;24863.881125349042;24878.502429951663;25430.237092887244;25917.611235831853 09:25:44 INFO - PROCESS | 2260 | |2;jquery.html;306.995395069074;362.1561761096645;367.34245700196647;378.8314200180917;391.0438976701963;19175.52061198469;20021.299254526093;20183.596328073465;20952.371428857157;21133.68299475509;20155.59688806223;20591.794082059212;20623.69064464034;20644.967751612436;21068.841213733238;5812.738426770784;5855.209546711193;5939.524838012949;6046.576739628228;6107.603005804631;5957.31490878548;6053.909191362133;6059.878802423949;6061.757529698807;6071.119687645289;5718;5794.623349482291;5880.911786323209;5992.910106348409;6029.849253768647;5852.326982397015;5903.114532820068;5911.556633252523;5920.97039514804;5960.642361458305;5039.370078740158;5109.591232701412;5164.819231326923;5227.242049902743;5227.424983251839;25665.743342566708;25709;25801.61297580538;26099;26175;2167.5664867026658;2480.503899220163;2959.1270575180374;3027.515597504411;3135.4512960232096;14934.029288096239;15692.686146277125;15755.44855930037;15779.211039447982;16080.758788618181;1820.681380758362;1861.4229588827507;1882.7641678996674;1897.67739484288;1941.2720229913782;116317;118467;118586.81413185844;119093.40453297678;119441.40279298548;9930.404175749478;9999;10166.237532185116;11108.277961932448;11533;13688.863111368959;13728.039037267496;13728.176309421468;13742.65643358924;13746.312684365741;7518.323350898446;8351.331893448509;8657.567121643893;8661;8775.868361974575;7166.713331466787;8084.474509157004;8676.499508357916;8686.6525338987;9059.501727404924;5370.677759334374;6757.459403247728;6777.322267773183;6815.454763618898;6816.079829222991;1836.1461920207169;1852.4535262097713;1863.1988245054486;1868.6729822281318;1878.0609695152423;799.3085980626832;831.2061980808313;872.6989188729877;930.55798495714;1034.4724190662816;15532.912696111389;17979.820201798077;21359.359219223446;21635.02642381065;22276.66585001226;923.5751554285104;957.8419560772534;967.6564819489121;976.4678250353575;1047.104725459721;9594.904050959402;11468.426578671033;11758.882411175779;11801.52793888235;11870;569159;594976;598752;612655;614003;107090;109759;113981.43009284901;121699.39150304192;126481.89459427219;1007.29992655104;1035.3787727363522;1082.8213344798178;1087.4399684162663;1091.4542728635681;16554.83445165533;19666.01669916527;19736.40790776279;20020.399388018384;20045.298414555422;55775.442245577025;55846.44153558413;57421.13868291895;57486.425135748104;58200;66736;74809;78787;79245;80090.1990980106;7895;7906.367490600853;8170.060443049126;8172;8263.214994575506;12320.454567952534;12948.223106613634;13190.736185276433;13309;13397.464101435833;11129.888701112885;11167.274127181707;11321;11334.14993875446;11472.254303470248;9984.151347135576;10804.513796879002;11160.832587511031;11518.654440366467;11649.068074554185;11166.106711463226;11571.826422603835;11663.88336116628;11698.181127321006;11780.234284771463;6876.724931002704;6913.75801846933;7968.681252750057;8203.958980205061;8240.340772738287;9252.19582181495;10880.129589632652;11195.048420884315;11349.14881383883;11488.425578720864 09:25:44 INFO - PROCESS | 2260 | |3;mootools.html;1542.807149106362;1565.6320764620314;1786.0533917023974;1852.7776666799987;1902.9904850475737;7036.472264580158;8005.719799807008;8338.45800022998;9180.632774689013;9234.261259099272;7298.598577078258;8798.944126704804;8840.33697472689;8841;8962.058983806704;2364.3379853640963;2427.0898413095088;2428.611422172453;2429.7327293997732;2439.7926176275037;2386.284114765572;2412.601920683085;2434.7078350597953;2438.8536687798787;2440.462508619576;2413.215704895907;2420.3586049696846;2425.890834912433;2436.220409468962;2442.743511931248;2380.916667916632;2403.122860156048;2404.519096180762;2415.347856078849;2417.4319035026756;2022.1911235505916;2359.398353419884;2391.0196819304;2394.664746935413;2401.1235898896953;8806.339524535686;8856.778580535474;8888.97776755675;8917;8942.463452192824;4294;4597.080583883203;4943.134951383529;5337.546308563777;5442.156465747816;3052.938941221163;3386.5258863759095;3515.771474854126;3729.049092481427;3729.9254014919547;1064.2603390643505;1093.8523299354645;1096.2928910852456;1114.7380365614074;1121.9719507012308;11738.58914937973;11761.823572646417;11801.94099029508;11887.524499020028;11944.402779861059;4786.353842231254;4804.95195048052;4865.489123642037;4964.950350496449;4984.077945580079;10100.696979090491;10201.591936322613;10214.029667181605;10217.948910255402;10219.489025548693;4481.887952801141;5351.705656188869;5410.864728381822;5434.021876062267;5442.0204363215;4398.868033958986;4571.314302854598;4749.762511874462;4869.172240719086;4936.555709986119;4011.3581826907553;4078.469798926121;4129.91740165195;4157.625813676784;4191.098913733562;1120.0479592346603;1289.1500696500552;1298.6363818130937;1311.1280998136133;1316.394458549059;797.4417907464798;808.458332916947;882.029767256026;912.6082723846055;936.857034418009;5008.99820035987;5088.389393272831;5696.971515142398;5840.970795145997;5963.970180149072;857.9871301930476;928.3965422475445;954.6801821389912;963.1187463470985;978.5303054533721;3759.736818422739;3923.548791888912;4006.559278479365;4013;4038.676905847525;10595.31130476532;11058.949399807005;11082.445877706083;11120.221584489009;11125.276857004268;8091.959540202261;10730.248882578144;10772.515236814363;10952.890471095187;10958.616448424267;8105.351571874236;8332.70835520766;8335.833283334421;8341.416100873003;8342.249197572126;4520.638348932143;5434;5475.479829416199;5759.683217423082;5788.189653448605;9747.3664211826;9860.704178874645;9889.85165222508;9980.550875210487;10116.84824727615;9682.322237443312;9967.40195588267;10104.84842727345;10229.74425639365;10419.42693151884;6661.633610151488;7532.171461139161;7533.359664428366;7551.244875512624;7635.198304177981;8429.157084291766;8471.449355791854;8534.359923005675;8543.273821725223;8544.829103417773;7213.458990575833;7440.479166458296;7552.207018263003;7631.847363052819;7659.693612255447;6649.634270114997;7037.929620703728;7239.4932354734665;7416.629168541659;7515.962420187864;6858.794236172923;6864.553804002724;6922.307769223037;6928.16861976566;6930.237673855771;4544.454665440168;4594.448666160082;4949.802007919643;4956.53782134264;5054.9494505055945;4779.115863565252;4925.852224433273;5063.822766203165;5085.669431487089;5143.665661731976 09:25:44 INFO - PROCESS | 2260 | |4;prototype.html;246.6571465662728;253.87052603172376;272.1359683006454;301.1642691531001;308.54643673799535;4676.719396836188;5368.463153684639;5404.567634589233;5579.330480342354;5712.314522257329;5411.565935027211;5618.977168219438;5625.493705566499;5630.746616402262;5652.7846512999695;3703.4444833274956;4058.8285267381957;4267.253230684633;4280.229558679436;4321.330193819963;3891.708121890855;4125.257453658341;4281.143771245747;4295.9785201073955;4352.934705979413;3320.5683261176014;3525.3301872644242;4253.702240843127;4254.212970600449;4257.978710106445;3687.3635350724226;4245.830166793325;4256.2551553478015;4268.445102136702;4272.846331490511;3344.4648856182894;3608.953403512978;3630.473581330719;3631.564212294515;3634.582023067328;5175.689458632456;5456.48163424474;5460.481254280837;5521.923224971132;5544.944550554524;3974.960250397517;4851.587615052725;4867.8783030424165;4900.779464924087;4911.961535962571;5701.486866182022;5922.756221193555;5965.910511342333;5971.373005834412;6002.609830361056;3979.9801000994808;3993.8402463901407;3994.3409337459575;4009.719319647626;4019.658329042035;5527.53015993641;5568.610197286192;6295.968520157416;6334.3665633436285;6336.746530138789;3110.3204269694565;3152.8156561872675;3782.527184101987;3993.740406873544;4019.9196016079513;4051.6021972419467;4096.446979657708;4109.97945010273;4113.218488487178;4128.938065929014;4837.395325584302;4848.951510484921;4875.780589873464;4876.975615121973;4895.779689913962;4586.312053192048;5545.251391062236;5577.828655982208;5625.353084395347;5767.480926716616;1113.0261021606095;1302.1991475242658;1315.5001099582098;1353.1137105196112;1381.696026874086;754.7697952124655;899.4603238057244;915.9404638698463;932.3473568502076;940.210223412337;4614.469336026333;4840.507138823508;5228.503292187236;5385.78456861729;5504.614676972574;5149.922751158736;5615.578831587565;6108.511319094462;6132.724027418777;6138.877222455526;4038.353863381845;4351.608355248043;5046.091703493406;5119.744012799345;5441.591880608891;2356.929292121205;2422.49127683185;2559.8096884948586;2717.320669832542;2722.9591556126675;4416.466826538756;4811.051524132732;5296.9141326028075;5350.170723537858;5375.828261325836;4219.894502637459;4498.908007776399;4535.967995399539;5189.962007598496;5284.81503147388;4357.694961352739;4883.047805677929;5104.821331253388;5281.815136470282;5291.809342897895;5131.7157019508295;5169.741512924414;5171.431142574389;5175.456577059505;5201.609879258995;3702.5371828521434;4514.751688657156;4684.742339171378;4723.480417154179;5147.305113809737;4697;4937.679050861682;4997.550220480174;5282.3132992712235;5331.786728530816;3456.3605732939486;3507.965150280724;3517.120719820045;3535.497413599159;3542.220711443478;4247.110036033935;4395.7582332972;5158.690478571298;5204.516712736852;5261.684858662176;4177.937330940099;4611.538846115362;4811.676788882945;4866.878328041827;4965.82619608312;4317.546657600907;4360.454943132108;4506.887327816831;4606.493285738633;4792.808287668454;4095.959040409558;4492.192379762649;4897.730624815526;4908.797344650584;4913.410390753133;4718.929216061831;4799.328094066903;4982.327385803015;5012.022655582126;5019;4074.409210664378;4426.517116765919;4854.708717476821;4912.631552633638;4961.950380496293 09:25:44 INFO - PROCESS | 2260 | |5;yui.html;595.8182754259949;636.7612145445459;701.7789396340149;777.2538363171356;786.9055713314401;15162.848371516282;15867.444639437623;15946.840531594695;15959.521214363558;16006.19969001548;14350.497732579362;14480.927595362034;14491.420343186286;14498.42006319746;14510.927445362784;672.4586707700299;691.1844024051617;850.137110832507;850.5577099908044;869.6869127114234;869.42183448007;869.8173383589453;871.5642178910545;872.2760109109456;874.7200895713374;921.6681994482014;926.1433174313767;926.8517037274038;929.4376901974309;930.1814403325064;893.5710858787818;919.0855099176329;922.7416323429384;925.5511077127587;925.9166674999249;661.993380066198;664.7540410048313;688.3013741052836;689.1661090081008;689.9068625735512;14854.331555080047;14878.851211487963;15079.623009424742;15080.849191508163;15084;14661.627964795343;14729.631759205999;14733.705325893423;14790.556283311518;14839.703205935928;11885.34630595317;12009.63971080869;12237.632871013884;12273.202241854251;12452.31512266825;3719.8698045568544;3741.5884252732176;3896.8246428910484;3962.0689138052535;3965.405189221611;116469.41765291119;116738;117728.41135794352;121252.78747212591;122529;11214.495347709373;11356.716082097848;11386.601468948713;11415.258008229439;11832.88167118335;24120.396990075387;24132.155374561808;24178.252370882543;24206.878965605058;24263.878680606485;6498.252700939453;7692.19231980634;7808.882866757003;7827.804304892309;7923.366130709529;4348.347747837786;4391.780410979438;4399.5820397062225;4482.462104547475;4522.570355816258;3378.290558982592;3483.7387195960405;3589.3180295743723;3659.8170091495317;3711.721620878445;544.3794074754783;564.5596434780878;565.8839937812738;634.8571571396399;635.0981606119229;553.5433267554284;581.8429024163452;625.9655718935502;642.1042645509582;701.1516065560627;8008.39937004727;8806.911930880737;8911;9286.609134876948;9302.627894884128;596.5466245653334;605.9606125601822;652.3118110393581;653.2716021635937;677.1434135818193;3638.599754027055;4302.849400270976;4644.256918892961;4693.530646935279;4706.458757242893;135506;166801;169199.1540042292;172085;179176.66586673036;133943.3302833499;134441;135423.32288338692;141081.58918410685;148469.2143099967;124859;127278;131008;131706.34146829206;133831.27109111554;6938.410235129869;8856.64573417056;9000.729978100668;9003.639854406012;9022.5939832709;51485;52048.73975630249;55348.16977745257;56743.39556621962;68523;76309;82370;86099;89782.55108724415;93301.5334923321;12475.313857737912;13015.284159371326;13163.934180329037;13352.666183345105;13558.864411356155;14628.853711462749;14732.116073035651;14739;14756;14796.186209758567;13257.602271931857;13431.86568134306;13447.596572102853;13558.86441135576;13587.864121359054;13331.66670833237;13387.866121338662;13598.640135986718;13642.181469111885;13657.453701851815;12137.939310303393;12976.935115324362;13244.173019917149;13355.866441335462;13381.464741410624;11146.386948717898;11221.66335009951;11230.606928757454;11427.485763140512;11666.533338666359;10059.29584929048;10603.522841471999;10701.304415212986;11167.281007684844;11447.084233261152 09:25:44 INFO - PROCESS | 2260 | __end_tp_report 09:25:44 INFO - PROCESS | 2260 | __start_cc_report 09:25:44 INFO - PROCESS | 2260 | _x_x_mozilla_cycle_collect,383 09:25:44 INFO - PROCESS | 2260 | __end_cc_report 09:25:44 INFO - PROCESS | 2260 | __startTimestamp1473697544028__endTimestamp 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | ------- Summary: start ------- 09:25:44 INFO - PROCESS | 2260 | Number of tests: 6 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#0] dojo.html Cycles:165 Average:5735.36 Median:5926.41 stddev:722.59 (12.2%) stddev-sans-first:682.17 09:25:44 INFO - PROCESS | 2260 | Values: 2617.9 2844.3 2940.5 3436.8 3462.2 4475.1 4683.6 5166.4 5708.7 5858.3 5804.1 5811.6 5819.2 5936.1 5974.4 5255.7 5306.3 5381.3 5425.5 5428.8 4821.4 5338.9 5437.7 5454.1 5494.0 5229.2 5297.0 5323.7 5375.0 5417.4 5224.5 5327.0 5344.5 5353.4 5377.0 5305.5 5316.0 5327.5 5338.4 5398.9 4049.4 5637.3 5753.1 5820.8 5869.9 6238.6 6273.4 6281.0 6295.5 6296.0 6274.2 6301.0 6314.5 6316.0 6319.7 5738.7 5844.5 5852.8 5884.6 5926.4 5805.2 5883.7 6371.8 6398.8 6402.8 6187.2 6197.5 6218.2 6257.1 6288.9 6171.1 6210.4 6266.6 6315.0 6331.9 5301.0 6198.1 6299.0 6300.6 6309.7 6165.6 6278.4 6289.3 6322.4 6334.3 4779.2 4953.1 5005.2 5078.3 5123.3 4418.6 4471.9 4530.2 4602.7 4617.8 5741.1 5741.5 5802.6 5821.9 5855.5 6227.7 6253.4 6277.0 6282.5 6292.4 5737.3 5757.5 5774.0 5855.4 5893.8 5514.2 5520.7 5525.5 5533.7 5544.4 6256.9 6432.8 6492.4 6507.0 6507.9 6260.8 6267.5 6271.0 6293.8 6294.6 6406.7 6438.0 6474.3 6477.8 6490.3 6111.7 6177.5 6182.8 6270.8 6318.1 5212.8 6189.2 6254.7 6295.9 6308.1 4625.4 4803.8 5133.3 6164.9 6202.6 6098.5 6115.5 6183.0 6217.7 6233.1 4446.9 5552.4 6031.7 6057.6 6124.3 5108.9 6179.3 6210.5 6215.1 6223.7 5932.7 6126.7 6129.3 6130.2 6132.0 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#1] ext.html Cycles:180 Average:74001.11 Median:41534.19 stddev:167019.19 (402.1%) stddev-sans-first:167396.37 09:25:44 INFO - PROCESS | 2260 | Values: 430.4 461.1 463.7 474.3 539.0 40235.4 41794.4 42265.4 44277.1 44520.3 41585.0 41876.0 42477.6 42575.8 42601.7 9723.3 9912.9 9941.7 9945.2 9958.2 9813.7 9943.6 9947.5 9951.8 9975.9 9943.1 9973.4 9979.4 9982.8 9988.1 9862.0 9891.3 9927.0 9929.6 9958.5 9769.7 9841.1 9841.7 9851.5 9865.5 35028.3 40293.0 40952.8 41267.8 41483.4 3328.7 3366.8 3422.5 3559.4 3777.9 30318.2 30852.0 30871.5 30943.9 31541.7 3157.1 3199.4 3204.3 3217.4 3278.9 487387.0 487480.0 489441.0 492172.0 493229.0 58191.7 58490.0 58633.4 58712.4 58811.8 28089.3 33511.8 34762.7 60762.7 61121.8 16522.6 16696.6 16726.0 16740.3 16757.7 12702.2 12888.7 12975.5 12978.4 12981.8 18811.6 18884.4 18895.4 18910.2 18929.8 7624.6 7786.5 7789.0 7828.8 7874.4 855.6 949.3 984.4 1020.7 1030.1 28943.0 29955.9 30031.2 30266.0 30680.8 1016.1 1031.8 1047.9 1049.8 1143.6 13270.7 13527.9 14862.8 15099.5 15666.5 685239.0 706952.0 709637.0 709638.0 709993.5 46162.5 57052.7 57071.0 57910.7 57980.1 1370.5 1381.0 1414.1 1421.7 1426.5 28155.2 29015.6 29153.0 29829.7 29885.9 51585.5 51735.7 52066.0 52218.7 52238.5 649118.0 655044.0 655263.0 655430.0 659447.0 41887.6 42263.8 42881.1 43060.8 43116.1 45824.0 46879.1 47137.3 47369.5 47443.1 38771.0 38796.4 38834.4 39590.2 40219.6 38285.6 38350.4 38919.0 39627.4 39892.6 27658.9 33174.8 34100.3 35919.3 36052.8 31381.7 34097.7 34420.8 34729.1 34796.3 22898.5 24863.9 24878.5 25430.2 25917.6 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#2] jquery.html Cycles:180 Average:34927.42 Median:25755.31 stddev:99505.23 (386.3%) stddev-sans-first:99750.41 09:25:44 INFO - PROCESS | 2260 | Values: 307.0 362.2 367.3 378.8 391.0 19175.5 20021.3 20183.6 20952.4 21133.7 20155.6 20591.8 20623.7 20645.0 21068.8 5812.7 5855.2 5939.5 6046.6 6107.6 5957.3 6053.9 6059.9 6061.8 6071.1 5718.0 5794.6 5880.9 5992.9 6029.8 5852.3 5903.1 5911.6 5921.0 5960.6 5039.4 5109.6 5164.8 5227.2 5227.4 25665.7 25709.0 25801.6 26099.0 26175.0 2167.6 2480.5 2959.1 3027.5 3135.5 14934.0 15692.7 15755.4 15779.2 16080.8 1820.7 1861.4 1882.8 1897.7 1941.3 116317.0 118467.0 118586.8 119093.4 119441.4 9930.4 9999.0 10166.2 11108.3 11533.0 13688.9 13728.0 13728.2 13742.7 13746.3 7518.3 8351.3 8657.6 8661.0 8775.9 7166.7 8084.5 8676.5 8686.7 9059.5 5370.7 6757.5 6777.3 6815.5 6816.1 1836.1 1852.5 1863.2 1868.7 1878.1 799.3 831.2 872.7 930.6 1034.5 15532.9 17979.8 21359.4 21635.0 22276.7 923.6 957.8 967.7 976.5 1047.1 9594.9 11468.4 11758.9 11801.5 11870.0 569159.0 594976.0 598752.0 612655.0 614003.0 107090.0 109759.0 113981.4 121699.4 126481.9 1007.3 1035.4 1082.8 1087.4 1091.5 16554.8 19666.0 19736.4 20020.4 20045.3 55775.4 55846.4 57421.1 57486.4 58200.0 66736.0 74809.0 78787.0 79245.0 80090.2 7895.0 7906.4 8170.1 8172.0 8263.2 12320.5 12948.2 13190.7 13309.0 13397.5 11129.9 11167.3 11321.0 11334.1 11472.3 9984.2 10804.5 11160.8 11518.7 11649.1 11166.1 11571.8 11663.9 11698.2 11780.2 6876.7 6913.8 7968.7 8204.0 8240.3 9252.2 10880.1 11195.0 11349.1 11488.4 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#3] mootools.html Cycles:180 Average:5687.62 Median:4867.33 stddev:3209.02 (65.9%) stddev-sans-first:3202.91 09:25:44 INFO - PROCESS | 2260 | Values: 1542.8 1565.6 1786.1 1852.8 1903.0 7036.5 8005.7 8338.5 9180.6 9234.3 7298.6 8798.9 8840.3 8841.0 8962.1 2364.3 2427.1 2428.6 2429.7 2439.8 2386.3 2412.6 2434.7 2438.9 2440.5 2413.2 2420.4 2425.9 2436.2 2442.7 2380.9 2403.1 2404.5 2415.3 2417.4 2022.2 2359.4 2391.0 2394.7 2401.1 8806.3 8856.8 8889.0 8917.0 8942.5 4294.0 4597.1 4943.1 5337.5 5442.2 3052.9 3386.5 3515.8 3729.0 3729.9 1064.3 1093.9 1096.3 1114.7 1122.0 11738.6 11761.8 11801.9 11887.5 11944.4 4786.4 4805.0 4865.5 4965.0 4984.1 10100.7 10201.6 10214.0 10217.9 10219.5 4481.9 5351.7 5410.9 5434.0 5442.0 4398.9 4571.3 4749.8 4869.2 4936.6 4011.4 4078.5 4129.9 4157.6 4191.1 1120.0 1289.2 1298.6 1311.1 1316.4 797.4 808.5 882.0 912.6 936.9 5009.0 5088.4 5697.0 5841.0 5964.0 858.0 928.4 954.7 963.1 978.5 3759.7 3923.5 4006.6 4013.0 4038.7 10595.3 11058.9 11082.4 11120.2 11125.3 8092.0 10730.2 10772.5 10952.9 10958.6 8105.4 8332.7 8335.8 8341.4 8342.2 4520.6 5434.0 5475.5 5759.7 5788.2 9747.4 9860.7 9889.9 9980.6 10116.8 9682.3 9967.4 10104.8 10229.7 10419.4 6661.6 7532.2 7533.4 7551.2 7635.2 8429.2 8471.4 8534.4 8543.3 8544.8 7213.5 7440.5 7552.2 7631.8 7659.7 6649.6 7037.9 7239.5 7416.6 7516.0 6858.8 6864.6 6922.3 6928.2 6930.2 4544.5 4594.4 4949.8 4956.5 5054.9 4779.1 4925.9 5063.8 5085.7 5143.7 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#4] prototype.html Cycles:180 Average:4362.28 Median:4805.19 stddev:1326.20 (27.6%) stddev-sans-first:1293.45 09:25:44 INFO - PROCESS | 2260 | Values: 246.7 253.9 272.1 301.2 308.5 4676.7 5368.5 5404.6 5579.3 5712.3 5411.6 5619.0 5625.5 5630.7 5652.8 3703.4 4058.8 4267.3 4280.2 4321.3 3891.7 4125.3 4281.1 4296.0 4352.9 3320.6 3525.3 4253.7 4254.2 4258.0 3687.4 4245.8 4256.3 4268.4 4272.8 3344.5 3609.0 3630.5 3631.6 3634.6 5175.7 5456.5 5460.5 5521.9 5544.9 3975.0 4851.6 4867.9 4900.8 4912.0 5701.5 5922.8 5965.9 5971.4 6002.6 3980.0 3993.8 3994.3 4009.7 4019.7 5527.5 5568.6 6296.0 6334.4 6336.7 3110.3 3152.8 3782.5 3993.7 4019.9 4051.6 4096.4 4110.0 4113.2 4128.9 4837.4 4849.0 4875.8 4877.0 4895.8 4586.3 5545.3 5577.8 5625.4 5767.5 1113.0 1302.2 1315.5 1353.1 1381.7 754.8 899.5 915.9 932.3 940.2 4614.5 4840.5 5228.5 5385.8 5504.6 5149.9 5615.6 6108.5 6132.7 6138.9 4038.4 4351.6 5046.1 5119.7 5441.6 2356.9 2422.5 2559.8 2717.3 2723.0 4416.5 4811.1 5296.9 5350.2 5375.8 4219.9 4498.9 4536.0 5190.0 5284.8 4357.7 4883.0 5104.8 5281.8 5291.8 5131.7 5169.7 5171.4 5175.5 5201.6 3702.5 4514.8 4684.7 4723.5 5147.3 4697.0 4937.7 4997.6 5282.3 5331.8 3456.4 3508.0 3517.1 3535.5 3542.2 4247.1 4395.8 5158.7 5204.5 5261.7 4177.9 4611.5 4811.7 4866.9 4965.8 4317.5 4360.5 4506.9 4606.5 4792.8 4096.0 4492.2 4897.7 4908.8 4913.4 4718.9 4799.3 4982.3 5012.0 5019.0 4074.4 4426.5 4854.7 4912.6 4962.0 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - PROCESS | 2260 | [#5] yui.html Cycles:180 Average:26112.03 Median:3431.01 stddev:43214.46 (1259.5%) stddev-sans-first:43293.22 09:25:44 INFO - PROCESS | 2260 | Values: 595.8 636.8 701.8 777.3 786.9 15162.8 15867.4 15946.8 15959.5 16006.2 14350.5 14480.9 14491.4 14498.4 14510.9 672.5 691.2 850.1 850.6 869.7 869.4 869.8 871.6 872.3 874.7 921.7 926.1 926.9 929.4 930.2 893.6 919.1 922.7 925.6 925.9 662.0 664.8 688.3 689.2 689.9 14854.3 14878.9 15079.6 15080.8 15084.0 14661.6 14729.6 14733.7 14790.6 14839.7 11885.3 12009.6 12237.6 12273.2 12452.3 3719.9 3741.6 3896.8 3962.1 3965.4 116469.4 116738.0 117728.4 121252.8 122529.0 11214.5 11356.7 11386.6 11415.3 11832.9 24120.4 24132.2 24178.3 24206.9 24263.9 6498.3 7692.2 7808.9 7827.8 7923.4 4348.3 4391.8 4399.6 4482.5 4522.6 3378.3 3483.7 3589.3 3659.8 3711.7 544.4 564.6 565.9 634.9 635.1 553.5 581.8 626.0 642.1 701.2 8008.4 8806.9 8911.0 9286.6 9302.6 596.5 606.0 652.3 653.3 677.1 3638.6 4302.8 4644.3 4693.5 4706.5 135506.0 166801.0 169199.2 172085.0 179176.7 133943.3 134441.0 135423.3 141081.6 148469.2 124859.0 127278.0 131008.0 131706.3 133831.3 6938.4 8856.6 9000.7 9003.6 9022.6 51485.0 52048.7 55348.2 56743.4 68523.0 76309.0 82370.0 86099.0 89782.6 93301.5 12475.3 13015.3 13163.9 13352.7 13558.9 14628.9 14732.1 14739.0 14756.0 14796.2 13257.6 13431.9 13447.6 13558.9 13587.9 13331.7 13387.9 13598.6 13642.2 13657.5 12137.9 12976.9 13244.2 13355.9 13381.5 11146.4 11221.7 11230.6 11427.5 11666.5 10059.3 10603.5 10701.3 11167.3 11447.1 09:25:44 INFO - PROCESS | 2260 | -------- Summary: end -------- 09:25:44 INFO - PROCESS | 2260 | 09:25:44 INFO - TEST-INFO | 2260: exit 0 09:25:44 INFO - TEST-OK | dromaeo_css | took 1114379ms 09:25:44 INFO - TEST-START | kraken 09:25:44 INFO - operating with platform_type : mac_ 09:25:44 INFO - Initialising browser for kraken test... 09:25:44 INFO - TEST-INFO | started process 2265 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpfyJizy/profile http://localhost:54287/getInfo.html) 09:25:47 INFO - PROCESS | 2265 | __metrics Screen width/height:1600/1200 09:25:47 INFO - PROCESS | 2265 | colorDepth:24 09:25:47 INFO - PROCESS | 2265 | Browser inner width/height: 1024/689 09:25:47 INFO - PROCESS | 2265 | __metrics 09:25:47 ERROR - PROCESS | 2265 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 09:25:48 INFO - TEST-INFO | 2265: exit 0 09:25:48 INFO - Browser initialized. 09:25:48 INFO - Running cycle 1/1 for kraken test... 09:25:48 INFO - TEST-INFO | started process 2267 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/T/tmpfyJizy/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:26:00 INFO - PROCESS | 2267 | RSS: Main: 244408320 09:26:00 INFO - PROCESS | 2267 | 09:26:03 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:54287/tests/kraken/driver.html?testName=audio-beat-detection) 09:26:04 INFO - PROCESS | 2267 | RSS: Main: 257159168 09:26:04 INFO - PROCESS | 2267 | 09:26:06 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:54287/tests/kraken/driver.html?testName=audio-dft) 09:26:07 INFO - PROCESS | 2267 | RSS: Main: 265895936 09:26:07 INFO - PROCESS | 2267 | 09:26:11 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:54287/tests/kraken/driver.html?testName=audio-fft) 09:26:11 INFO - PROCESS | 2267 | RSS: Main: 265752576 09:26:11 INFO - PROCESS | 2267 | 09:26:13 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:54287/tests/kraken/driver.html?testName=audio-oscillator) 09:26:14 INFO - PROCESS | 2267 | RSS: Main: 268070912 09:26:14 INFO - PROCESS | 2267 | 09:26:16 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:54287/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:26:16 INFO - PROCESS | 2267 | RSS: Main: 268644352 09:26:16 INFO - PROCESS | 2267 | 09:26:19 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:54287/tests/kraken/driver.html?testName=imaging-darkroom) 09:26:20 INFO - PROCESS | 2267 | RSS: Main: 269438976 09:26:20 INFO - PROCESS | 2267 | 09:26:23 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:54287/tests/kraken/driver.html?testName=imaging-desaturate) 09:26:23 INFO - PROCESS | 2267 | RSS: Main: 269844480 09:26:23 INFO - PROCESS | 2267 | 09:26:26 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:54287/tests/kraken/driver.html?testName=json-parse-financial) 09:26:27 INFO - PROCESS | 2267 | RSS: Main: 269934592 09:26:27 INFO - PROCESS | 2267 | 09:26:29 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:54287/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:26:29 INFO - PROCESS | 2267 | RSS: Main: 269033472 09:26:29 INFO - PROCESS | 2267 | 09:26:31 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:26:32 INFO - PROCESS | 2267 | RSS: Main: 269398016 09:26:32 INFO - PROCESS | 2267 | 09:26:34 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:26:35 INFO - PROCESS | 2267 | RSS: Main: 269430784 09:26:35 INFO - PROCESS | 2267 | 09:26:38 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:26:38 INFO - PROCESS | 2267 | RSS: Main: 269742080 09:26:38 INFO - PROCESS | 2267 | 09:26:41 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:26:42 INFO - PROCESS | 2267 | RSS: Main: 269619200 09:26:42 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | Cycle 1(1): loaded http://localhost:54287/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:54287/tests/kraken/driver.html?testName=ai-astar) 09:26:44 INFO - PROCESS | 2267 | RSS: Main: 271384576 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | __start_tp_report 09:26:44 INFO - PROCESS | 2267 | _x_x_mozilla_page_load 09:26:44 INFO - PROCESS | 2267 | _x_x_mozilla_page_load_details 09:26:44 INFO - PROCESS | 2267 | |i|pagename|runs| 09:26:44 INFO - PROCESS | 2267 | |0;ai-astar;107;118;118;112;125;117;117;117;124;195 09:26:44 INFO - PROCESS | 2267 | |1;audio-beat-detection;151;112;142;149;105;171;164;103;164;131 09:26:44 INFO - PROCESS | 2267 | |2;audio-dft;242;265;204;199;205;206;203;202;197;199 09:26:44 INFO - PROCESS | 2267 | |3;audio-fft;70;90;80;152;60;66;83;86;140;55 09:26:44 INFO - PROCESS | 2267 | |4;audio-oscillator;70;73;120;71;71;72;107;65;73;117 09:26:44 INFO - PROCESS | 2267 | |5;imaging-gaussian-blur;92;88;89;88;91;86;90;90;94;85 09:26:44 INFO - PROCESS | 2267 | |6;imaging-darkroom;99;99;99;133;99;99;99;100;99;100 09:26:44 INFO - PROCESS | 2267 | |7;imaging-desaturate;143;150;92;95;92;91;90;95;76;101 09:26:44 INFO - PROCESS | 2267 | |8;json-parse-financial;57;58;57;58;57;59;59;57;57;56 09:26:44 INFO - PROCESS | 2267 | |9;json-stringify-tinderbox;65;69;73;74;82;74;68;94;63;78 09:26:44 INFO - PROCESS | 2267 | |10;stanford-crypto-aes;91;89;118;74;75;97;78;84;108;108 09:26:44 INFO - PROCESS | 2267 | |11;stanford-crypto-ccm;131;160;164;127;156;159;193;142;177;141 09:26:44 INFO - PROCESS | 2267 | |12;stanford-crypto-pbkdf2;172;174;176;233;172;173;171;171;176;174 09:26:44 INFO - PROCESS | 2267 | |13;stanford-crypto-sha256-iterative;77;69;68;70;66;85;94;91;82;68 09:26:44 INFO - PROCESS | 2267 | __end_tp_report 09:26:44 INFO - PROCESS | 2267 | __start_cc_report 09:26:44 INFO - PROCESS | 2267 | _x_x_mozilla_cycle_collect,963 09:26:44 INFO - PROCESS | 2267 | __end_cc_report 09:26:44 INFO - PROCESS | 2267 | __startTimestamp1473697604541__endTimestamp 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | ------- Summary: start ------- 09:26:44 INFO - PROCESS | 2267 | Number of tests: 14 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#0] ai-astar Cycles:10 Average:125.00 Median:118.00 stddev:25.13 (21.3%) stddev-sans-first:25.80 09:26:44 INFO - PROCESS | 2267 | Values: 107.0 118.0 118.0 112.0 125.0 117.0 117.0 117.0 124.0 195.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#1] audio-beat-detection Cycles:10 Average:139.20 Median:150.00 stddev:25.32 (16.9%) stddev-sans-first:26.50 09:26:44 INFO - PROCESS | 2267 | Values: 151.0 112.0 142.0 149.0 105.0 171.0 164.0 103.0 164.0 131.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#2] audio-dft Cycles:10 Average:212.20 Median:204.50 stddev:22.61 (11.1%) stddev-sans-first:21.26 09:26:44 INFO - PROCESS | 2267 | Values: 242.0 265.0 204.0 199.0 205.0 206.0 203.0 202.0 197.0 199.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#3] audio-fft Cycles:10 Average:88.20 Median:75.00 stddev:32.62 (43.5%) stddev-sans-first:33.93 09:26:44 INFO - PROCESS | 2267 | Values: 70.0 90.0 80.0 152.0 60.0 66.0 83.0 86.0 140.0 55.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#4] audio-oscillator Cycles:10 Average:83.90 Median:71.00 stddev:21.59 (30.4%) stddev-sans-first:22.31 09:26:44 INFO - PROCESS | 2267 | Values: 70.0 73.0 120.0 71.0 71.0 72.0 107.0 65.0 73.0 117.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#5] imaging-gaussian-blur Cycles:10 Average:89.30 Median:90.00 stddev:2.71 (3.0%) stddev-sans-first:2.69 09:26:44 INFO - PROCESS | 2267 | Values: 92.0 88.0 89.0 88.0 91.0 86.0 90.0 90.0 94.0 85.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#6] imaging-darkroom Cycles:10 Average:102.60 Median:99.00 stddev:10.69 (10.8%) stddev-sans-first:11.26 09:26:44 INFO - PROCESS | 2267 | Values: 99.0 99.0 99.0 133.0 99.0 99.0 99.0 100.0 99.0 100.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#7] imaging-desaturate Cycles:10 Average:102.50 Median:91.50 stddev:24.09 (26.3%) stddev-sans-first:20.62 09:26:44 INFO - PROCESS | 2267 | Values: 143.0 150.0 92.0 95.0 92.0 91.0 90.0 95.0 76.0 101.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#8] json-parse-financial Cycles:10 Average:57.50 Median:57.50 stddev:0.97 (1.7%) stddev-sans-first:1.01 09:26:44 INFO - PROCESS | 2267 | Values: 57.0 58.0 57.0 58.0 57.0 59.0 59.0 57.0 57.0 56.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#9] json-stringify-tinderbox Cycles:10 Average:74.00 Median:74.00 stddev:9.09 (12.3%) stddev-sans-first:9.04 09:26:44 INFO - PROCESS | 2267 | Values: 65.0 69.0 73.0 74.0 82.0 74.0 68.0 94.0 63.0 78.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#10] stanford-crypto-aes Cycles:10 Average:92.20 Median:81.00 stddev:15.26 (18.8%) stddev-sans-first:16.18 09:26:44 INFO - PROCESS | 2267 | Values: 91.0 89.0 118.0 74.0 75.0 97.0 78.0 84.0 108.0 108.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#11] stanford-crypto-ccm Cycles:10 Average:155.00 Median:159.50 stddev:20.48 (12.8%) stddev-sans-first:19.80 09:26:44 INFO - PROCESS | 2267 | Values: 131.0 160.0 164.0 127.0 156.0 159.0 193.0 142.0 177.0 141.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.20 Median:174.00 stddev:18.99 (10.9%) stddev-sans-first:19.96 09:26:44 INFO - PROCESS | 2267 | Values: 172.0 174.0 176.0 233.0 172.0 173.0 171.0 171.0 176.0 174.0 09:26:44 INFO - PROCESS | 2267 | 09:26:44 INFO - PROCESS | 2267 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.00 Median:79.50 stddev:10.38 (13.1%) stddev-sans-first:11.01 09:26:44 INFO - PROCESS | 2267 | Values: 77.0 69.0 68.0 70.0 66.0 85.0 94.0 91.0 82.0 68.0 09:26:44 INFO - PROCESS | 2267 | -------- Summary: end -------- 09:26:44 INFO - PROCESS | 2267 | 09:26:45 INFO - TEST-INFO | 2267: exit 0 09:26:45 INFO - TEST-OK | kraken | took 60733ms 09:26:45 INFO - SUITE-END | took 1175s 09:26:45 INFO - Completed test suite (00:19:35) 09:26:45 INFO - javascript benchmark 09:26:45 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7358.938075189935, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2617.887397855911, 2844.31736383268, 2940.514815055516, 3436.82815859207, 3462.1517728156596, 4475.127350166719, 4683.5550622690835, 5166.354205724285, 5708.686022268774, 5858.326292476367, 5804.129380592914, 5811.564132690055, 5819.214406055181, 5936.050231962887, 5974.372690867462, 5255.658382205154, 5306.257124002643, 5381.300430943991, 5425.538829199523, 5428.809991650293, 4821.421429428456, 5338.893222135555, 5437.673739575638, 5454.127339625661, 5494.0, 5229.163333866582, 5296.973515132399, 5323.653962492464, 5374.951884382547, 5417.377001644822, 5224.4775522447835, 5327.0, 5344.492273234076, 5353.384360798519, 5376.973115134439, 5305.522502974713, 5315.973420132875, 5327.493888080626, 5338.386085600167, 5398.865028374322, 4049.3925911113274, 5637.323521177444, 5753.050746626802, 5820.796272130455, 5869.914065897822, 6238.5944913580925, 6273.435390814803, 6280.968595157041, 6295.527835412362, 6296.0, 6274.152989346471, 6301.0, 6314.463270621956, 6316.021016742412, 6319.684015799192, 5738.684372359518, 5844.503217226498, 5852.824415267549, 5884.58807883444, 5926.4073592640825, 5805.216295800097, 5883.676397798076, 6371.808845734636, 6398.776042838478, 6402.775902843378, 6187.225380433362, 6197.4732147767045, 6218.19163508741, 6257.092721555349, 6288.874222515524, 6171.074338849208, 6210.4100110489435, 6266.624002559861, 6314.9684251579365, 6331.905021424683, 5300.9734951325, 6198.132261483396, 6299.0, 6300.621962682162, 6309.747610095545, 6165.630062196283, 6278.4035516625845, 6289.339619339995, 6322.399372059739, 6334.334894835976, 4779.235322348408, 4953.0589188054155, 5005.224190250517, 5078.339815823919, 5123.257127716461, 4418.624416924597, 4471.859675782654, 4530.161920044802, 4602.677812553089, 4617.79219935097, 5741.1388291756575, 5741.540676745851, 5802.564807639444, 5821.854453638693, 5855.4730074292665, 6227.657478838707, 6253.437190652863, 6276.968615156895, 6282.4974002079725, 6292.370762923671, 5737.340205876294, 5757.510611597977, 5774.018416869142, 5855.443732845373, 5893.793717219963, 5514.200440936042, 5520.66875987442, 5525.530329921921, 5533.6956467393875, 5544.390117087117, 6256.874862502816, 6432.839179020469, 6492.35076492347, 6507.0, 6507.934920650733, 6260.812175634738, 6267.529935254783, 6271.0, 6293.811185664437, 6294.590851594632, 6406.679666016774, 6438.0, 6474.320196379313, 6477.838054048687, 6490.253620833664, 6111.652512877656, 6177.505799536116, 6182.752689892354, 6270.7805226816845, 6318.08387783778, 5212.791488340424, 6189.164462797465, 6254.749810007549, 6295.905561416674, 6308.148399965946, 4625.375574297427, 4803.783829727601, 5133.307003554622, 6164.921138800602, 6202.596831205957, 6098.512119030555, 6115.541334399849, 6183.0, 6217.720202590985, 6233.065040244, 4446.888827779331, 5552.3892371839875, 6031.668258245663, 6057.57596968208, 6124.295705993868, 5108.872278193075, 6179.28938172098, 6210.472109870531, 6215.06773983906, 6223.688815559294, 5932.673702946379, 6126.724297406538, 6129.325774164749, 6130.2030736004035, 6132.0], "value": 5687.335015086086, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [430.44687576464247, 461.0847467776465, 463.7101811367895, 474.2956709286711, 538.9786354857545, 40235.396469052954, 41794.37308440372, 42265.366019509696, 44277.11445771091, 44520.3321950171, 41585.0, 41876.0, 42477.57522424775, 42575.78712106443, 42601.721948341525, 9723.319367644268, 9912.851307230398, 9941.652042178524, 9945.154661853714, 9958.153556947667, 9813.65652202173, 9943.552540135712, 9947.502624868765, 9951.800963980717, 9975.850362244571, 9943.10512053915, 9973.401595904234, 9979.351342162772, 9982.750431239205, 9988.101070903653, 9862.0, 9891.25815563832, 9927.0, 9929.55317010729, 9958.502074896227, 9769.706908792676, 9841.114299712988, 9841.704748857546, 9851.458169800659, 9865.506724663737, 35028.299434011176, 40292.992675183064, 40952.79523602393, 41267.79366103181, 41483.377749333784, 3328.700416962485, 3366.764326497146, 3422.469517224834, 3559.430491121408, 3777.942176190671, 30318.242043948856, 30852.0, 30871.536926946115, 30943.916962906416, 31541.695199915852, 3157.137541857869, 3199.392115498048, 3204.3431096625136, 3217.3726123405945, 3278.8852390166467, 487387.0, 487480.0, 489441.0, 492172.0, 493229.0, 58191.70904145537, 58490.0, 58633.4136658628, 58712.41287587155, 58811.82376352449, 28089.297767555974, 33511.83244083764, 34762.65237347594, 60762.696186518784, 61121.77756444935, 16522.58693532671, 16696.58258543522, 16726.0, 16740.330386784393, 16757.664846703243, 12702.237865728086, 12888.677783055311, 12975.480980760853, 12978.35108244584, 12981.805272920914, 18811.623767524572, 18884.43346699601, 18895.43313700591, 18910.243590256236, 18929.81070189308, 7624.618769061635, 7786.454948153575, 7789.0, 7828.84342313162, 7874.448788584858, 855.5679381912095, 949.2975198353307, 984.3749219245831, 1020.6529779874878, 1030.1038096855634, 28943.0, 29955.85022074919, 30031.249218769706, 30266.0, 30680.846595766878, 1016.1058268723476, 1031.8452232165234, 1047.8899715529913, 1049.7847941172042, 1143.616888342398, 13270.66823329405, 13527.86472135266, 14862.777058344134, 15099.54701358961, 15666.451674191349, 685239.0, 706952.0, 709637.0, 709638.0, 709993.4500327465, 46162.538374615826, 57052.71473642605, 57071.0, 57910.7104464475, 57980.130298045566, 1370.4997675848244, 1380.9792853107213, 1414.0525847682077, 1421.6588018875395, 1426.4864648726657, 28155.155345339674, 29015.56476652894, 29152.979645713145, 29829.70170298356, 29885.850570747007, 51585.484145158065, 51735.74132129315, 52066.0, 52218.738906305225, 52238.47761522488, 649118.0, 655044.0, 655263.0, 655430.0, 659447.0, 41887.58112418837, 42263.7886810564, 42881.14237715291, 43060.77044221633, 43116.13767724691, 45824.0, 46879.06241875075, 47137.2929406066, 47369.52630473789, 47443.05113897772, 38771.0, 38796.41805372865, 38834.41748373833, 39590.208195835345, 40219.59780402276, 38285.6171438282, 38350.42474362831, 38919.0, 39627.405588916765, 39892.60107398889, 27658.861705691343, 33174.83412583018, 34100.317993640485, 35919.28161436704, 36052.819735901154, 31381.686183137877, 34097.65902340945, 34420.82789586036, 34729.13177170591, 34796.30407391889, 22898.54202915899, 24863.881125349042, 24878.502429951663, 25430.237092887244, 25917.611235831853], "value": 20167.115266727025, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [306.995395069074, 362.1561761096645, 367.34245700196647, 378.8314200180917, 391.0438976701963, 19175.52061198469, 20021.299254526093, 20183.596328073465, 20952.371428857157, 21133.68299475509, 20155.59688806223, 20591.794082059212, 20623.69064464034, 20644.967751612436, 21068.841213733238, 5812.738426770784, 5855.209546711193, 5939.524838012949, 6046.576739628228, 6107.603005804631, 5957.31490878548, 6053.909191362133, 6059.878802423949, 6061.757529698807, 6071.119687645289, 5718.0, 5794.623349482291, 5880.911786323209, 5992.910106348409, 6029.849253768647, 5852.326982397015, 5903.114532820068, 5911.556633252523, 5920.97039514804, 5960.642361458305, 5039.370078740158, 5109.591232701412, 5164.819231326923, 5227.242049902743, 5227.424983251839, 25665.743342566708, 25709.0, 25801.61297580538, 26099.0, 26175.0, 2167.5664867026658, 2480.503899220163, 2959.1270575180374, 3027.515597504411, 3135.4512960232096, 14934.029288096239, 15692.686146277125, 15755.44855930037, 15779.211039447982, 16080.758788618181, 1820.681380758362, 1861.4229588827507, 1882.7641678996674, 1897.67739484288, 1941.2720229913782, 116317.0, 118467.0, 118586.81413185844, 119093.40453297678, 119441.40279298548, 9930.404175749478, 9999.0, 10166.237532185116, 11108.277961932448, 11533.0, 13688.863111368959, 13728.039037267496, 13728.176309421468, 13742.65643358924, 13746.312684365741, 7518.323350898446, 8351.331893448509, 8657.567121643893, 8661.0, 8775.868361974575, 7166.713331466787, 8084.474509157004, 8676.499508357916, 8686.6525338987, 9059.501727404924, 5370.677759334374, 6757.459403247728, 6777.322267773183, 6815.454763618898, 6816.079829222991, 1836.1461920207169, 1852.4535262097713, 1863.1988245054486, 1868.6729822281318, 1878.0609695152423, 799.3085980626832, 831.2061980808313, 872.6989188729877, 930.55798495714, 1034.4724190662816, 15532.912696111389, 17979.820201798077, 21359.359219223446, 21635.02642381065, 22276.66585001226, 923.5751554285104, 957.8419560772534, 967.6564819489121, 976.4678250353575, 1047.104725459721, 9594.904050959402, 11468.426578671033, 11758.882411175779, 11801.52793888235, 11870.0, 569159.0, 594976.0, 598752.0, 612655.0, 614003.0, 107090.0, 109759.0, 113981.43009284901, 121699.39150304192, 126481.89459427219, 1007.29992655104, 1035.3787727363522, 1082.8213344798178, 1087.4399684162663, 1091.4542728635681, 16554.83445165533, 19666.01669916527, 19736.40790776279, 20020.399388018384, 20045.298414555422, 55775.442245577025, 55846.44153558413, 57421.13868291895, 57486.425135748104, 58200.0, 66736.0, 74809.0, 78787.0, 79245.0, 80090.1990980106, 7895.0, 7906.367490600853, 8170.060443049126, 8172.0, 8263.214994575506, 12320.454567952534, 12948.223106613634, 13190.736185276433, 13309.0, 13397.464101435833, 11129.888701112885, 11167.274127181707, 11321.0, 11334.14993875446, 11472.254303470248, 9984.151347135576, 10804.513796879002, 11160.832587511031, 11518.654440366467, 11649.068074554185, 11166.106711463226, 11571.826422603835, 11663.88336116628, 11698.181127321006, 11780.234284771463, 6876.724931002704, 6913.75801846933, 7968.681252750057, 8203.958980205061, 8240.340772738287, 9252.19582181495, 10880.129589632652, 11195.048420884315, 11349.14881383883, 11488.425578720864], "value": 9777.090345853796, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1542.807149106362, 1565.6320764620314, 1786.0533917023974, 1852.7776666799987, 1902.9904850475737, 7036.472264580158, 8005.719799807008, 8338.45800022998, 9180.632774689013, 9234.261259099272, 7298.598577078258, 8798.944126704804, 8840.33697472689, 8841.0, 8962.058983806704, 2364.3379853640963, 2427.0898413095088, 2428.611422172453, 2429.7327293997732, 2439.7926176275037, 2386.284114765572, 2412.601920683085, 2434.7078350597953, 2438.8536687798787, 2440.462508619576, 2413.215704895907, 2420.3586049696846, 2425.890834912433, 2436.220409468962, 2442.743511931248, 2380.916667916632, 2403.122860156048, 2404.519096180762, 2415.347856078849, 2417.4319035026756, 2022.1911235505916, 2359.398353419884, 2391.0196819304, 2394.664746935413, 2401.1235898896953, 8806.339524535686, 8856.778580535474, 8888.97776755675, 8917.0, 8942.463452192824, 4294.0, 4597.080583883203, 4943.134951383529, 5337.546308563777, 5442.156465747816, 3052.938941221163, 3386.5258863759095, 3515.771474854126, 3729.049092481427, 3729.9254014919547, 1064.2603390643505, 1093.8523299354645, 1096.2928910852456, 1114.7380365614074, 1121.9719507012308, 11738.58914937973, 11761.823572646417, 11801.94099029508, 11887.524499020028, 11944.402779861059, 4786.353842231254, 4804.95195048052, 4865.489123642037, 4964.950350496449, 4984.077945580079, 10100.696979090491, 10201.591936322613, 10214.029667181605, 10217.948910255402, 10219.489025548693, 4481.887952801141, 5351.705656188869, 5410.864728381822, 5434.021876062267, 5442.0204363215, 4398.868033958986, 4571.314302854598, 4749.762511874462, 4869.172240719086, 4936.555709986119, 4011.3581826907553, 4078.469798926121, 4129.91740165195, 4157.625813676784, 4191.098913733562, 1120.0479592346603, 1289.1500696500552, 1298.6363818130937, 1311.1280998136133, 1316.394458549059, 797.4417907464798, 808.458332916947, 882.029767256026, 912.6082723846055, 936.857034418009, 5008.99820035987, 5088.389393272831, 5696.971515142398, 5840.970795145997, 5963.970180149072, 857.9871301930476, 928.3965422475445, 954.6801821389912, 963.1187463470985, 978.5303054533721, 3759.736818422739, 3923.548791888912, 4006.559278479365, 4013.0, 4038.676905847525, 10595.31130476532, 11058.949399807005, 11082.445877706083, 11120.221584489009, 11125.276857004268, 8091.959540202261, 10730.248882578144, 10772.515236814363, 10952.890471095187, 10958.616448424267, 8105.351571874236, 8332.70835520766, 8335.833283334421, 8341.416100873003, 8342.249197572126, 4520.638348932143, 5434.0, 5475.479829416199, 5759.683217423082, 5788.189653448605, 9747.3664211826, 9860.704178874645, 9889.85165222508, 9980.550875210487, 10116.84824727615, 9682.322237443312, 9967.40195588267, 10104.84842727345, 10229.74425639365, 10419.42693151884, 6661.633610151488, 7532.171461139161, 7533.359664428366, 7551.244875512624, 7635.198304177981, 8429.157084291766, 8471.449355791854, 8534.359923005675, 8543.273821725223, 8544.829103417773, 7213.458990575833, 7440.479166458296, 7552.207018263003, 7631.847363052819, 7659.693612255447, 6649.634270114997, 7037.929620703728, 7239.4932354734665, 7416.629168541659, 7515.962420187864, 6858.794236172923, 6864.553804002724, 6922.307769223037, 6928.16861976566, 6930.237673855771, 4544.454665440168, 4594.448666160082, 4949.802007919643, 4956.53782134264, 5054.9494505055945, 4779.115863565252, 4925.852224433273, 5063.822766203165, 5085.669431487089, 5143.665661731976], "value": 4580.9557144164055, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [246.6571465662728, 253.87052603172376, 272.1359683006454, 301.1642691531001, 308.54643673799535, 4676.719396836188, 5368.463153684639, 5404.567634589233, 5579.330480342354, 5712.314522257329, 5411.565935027211, 5618.977168219438, 5625.493705566499, 5630.746616402262, 5652.7846512999695, 3703.4444833274956, 4058.8285267381957, 4267.253230684633, 4280.229558679436, 4321.330193819963, 3891.708121890855, 4125.257453658341, 4281.143771245747, 4295.9785201073955, 4352.934705979413, 3320.5683261176014, 3525.3301872644242, 4253.702240843127, 4254.212970600449, 4257.978710106445, 3687.3635350724226, 4245.830166793325, 4256.2551553478015, 4268.445102136702, 4272.846331490511, 3344.4648856182894, 3608.953403512978, 3630.473581330719, 3631.564212294515, 3634.582023067328, 5175.689458632456, 5456.48163424474, 5460.481254280837, 5521.923224971132, 5544.944550554524, 3974.960250397517, 4851.587615052725, 4867.8783030424165, 4900.779464924087, 4911.961535962571, 5701.486866182022, 5922.756221193555, 5965.910511342333, 5971.373005834412, 6002.609830361056, 3979.9801000994808, 3993.8402463901407, 3994.3409337459575, 4009.719319647626, 4019.658329042035, 5527.53015993641, 5568.610197286192, 6295.968520157416, 6334.3665633436285, 6336.746530138789, 3110.3204269694565, 3152.8156561872675, 3782.527184101987, 3993.740406873544, 4019.9196016079513, 4051.6021972419467, 4096.446979657708, 4109.97945010273, 4113.218488487178, 4128.938065929014, 4837.395325584302, 4848.951510484921, 4875.780589873464, 4876.975615121973, 4895.779689913962, 4586.312053192048, 5545.251391062236, 5577.828655982208, 5625.353084395347, 5767.480926716616, 1113.0261021606095, 1302.1991475242658, 1315.5001099582098, 1353.1137105196112, 1381.696026874086, 754.7697952124655, 899.4603238057244, 915.9404638698463, 932.3473568502076, 940.210223412337, 4614.469336026333, 4840.507138823508, 5228.503292187236, 5385.78456861729, 5504.614676972574, 5149.922751158736, 5615.578831587565, 6108.511319094462, 6132.724027418777, 6138.877222455526, 4038.353863381845, 4351.608355248043, 5046.091703493406, 5119.744012799345, 5441.591880608891, 2356.929292121205, 2422.49127683185, 2559.8096884948586, 2717.320669832542, 2722.9591556126675, 4416.466826538756, 4811.051524132732, 5296.9141326028075, 5350.170723537858, 5375.828261325836, 4219.894502637459, 4498.908007776399, 4535.967995399539, 5189.962007598496, 5284.81503147388, 4357.694961352739, 4883.047805677929, 5104.821331253388, 5281.815136470282, 5291.809342897895, 5131.7157019508295, 5169.741512924414, 5171.431142574389, 5175.456577059505, 5201.609879258995, 3702.5371828521434, 4514.751688657156, 4684.742339171378, 4723.480417154179, 5147.305113809737, 4697.0, 4937.679050861682, 4997.550220480174, 5282.3132992712235, 5331.786728530816, 3456.3605732939486, 3507.965150280724, 3517.120719820045, 3535.497413599159, 3542.220711443478, 4247.110036033935, 4395.7582332972, 5158.690478571298, 5204.516712736852, 5261.684858662176, 4177.937330940099, 4611.538846115362, 4811.676788882945, 4866.878328041827, 4965.82619608312, 4317.546657600907, 4360.454943132108, 4506.887327816831, 4606.493285738633, 4792.808287668454, 4095.959040409558, 4492.192379762649, 4897.730624815526, 4908.797344650584, 4913.410390753133, 4718.929216061831, 4799.328094066903, 4982.327385803015, 5012.022655582126, 5019.0, 4074.409210664378, 4426.517116765919, 4854.708717476821, 4912.631552633638, 4961.950380496293], "value": 3938.4297347132847, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [595.8182754259949, 636.7612145445459, 701.7789396340149, 777.2538363171356, 786.9055713314401, 15162.848371516282, 15867.444639437623, 15946.840531594695, 15959.521214363558, 16006.19969001548, 14350.497732579362, 14480.927595362034, 14491.420343186286, 14498.42006319746, 14510.927445362784, 672.4586707700299, 691.1844024051617, 850.137110832507, 850.5577099908044, 869.6869127114234, 869.42183448007, 869.8173383589453, 871.5642178910545, 872.2760109109456, 874.7200895713374, 921.6681994482014, 926.1433174313767, 926.8517037274038, 929.4376901974309, 930.1814403325064, 893.5710858787818, 919.0855099176329, 922.7416323429384, 925.5511077127587, 925.9166674999249, 661.993380066198, 664.7540410048313, 688.3013741052836, 689.1661090081008, 689.9068625735512, 14854.331555080047, 14878.851211487963, 15079.623009424742, 15080.849191508163, 15084.0, 14661.627964795343, 14729.631759205999, 14733.705325893423, 14790.556283311518, 14839.703205935928, 11885.34630595317, 12009.63971080869, 12237.632871013884, 12273.202241854251, 12452.31512266825, 3719.8698045568544, 3741.5884252732176, 3896.8246428910484, 3962.0689138052535, 3965.405189221611, 116469.41765291119, 116738.0, 117728.41135794352, 121252.78747212591, 122529.0, 11214.495347709373, 11356.716082097848, 11386.601468948713, 11415.258008229439, 11832.88167118335, 24120.396990075387, 24132.155374561808, 24178.252370882543, 24206.878965605058, 24263.878680606485, 6498.252700939453, 7692.19231980634, 7808.882866757003, 7827.804304892309, 7923.366130709529, 4348.347747837786, 4391.780410979438, 4399.5820397062225, 4482.462104547475, 4522.570355816258, 3378.290558982592, 3483.7387195960405, 3589.3180295743723, 3659.8170091495317, 3711.721620878445, 544.3794074754783, 564.5596434780878, 565.8839937812738, 634.8571571396399, 635.0981606119229, 553.5433267554284, 581.8429024163452, 625.9655718935502, 642.1042645509582, 701.1516065560627, 8008.39937004727, 8806.911930880737, 8911.0, 9286.609134876948, 9302.627894884128, 596.5466245653334, 605.9606125601822, 652.3118110393581, 653.2716021635937, 677.1434135818193, 3638.599754027055, 4302.849400270976, 4644.256918892961, 4693.530646935279, 4706.458757242893, 135506.0, 166801.0, 169199.1540042292, 172085.0, 179176.66586673036, 133943.3302833499, 134441.0, 135423.32288338692, 141081.58918410685, 148469.2143099967, 124859.0, 127278.0, 131008.0, 131706.34146829206, 133831.27109111554, 6938.410235129869, 8856.64573417056, 9000.729978100668, 9003.639854406012, 9022.5939832709, 51485.0, 52048.73975630249, 55348.16977745257, 56743.39556621962, 68523.0, 76309.0, 82370.0, 86099.0, 89782.55108724415, 93301.5334923321, 12475.313857737912, 13015.284159371326, 13163.934180329037, 13352.666183345105, 13558.864411356155, 14628.853711462749, 14732.116073035651, 14739.0, 14756.0, 14796.186209758567, 13257.602271931857, 13431.86568134306, 13447.596572102853, 13558.86441135576, 13587.864121359054, 13331.66670833237, 13387.866121338662, 13598.640135986718, 13642.181469111885, 13657.453701851815, 12137.939310303393, 12976.935115324362, 13244.173019917149, 13355.866441335462, 13381.464741410624, 11146.386948717898, 11221.66335009951, 11230.606928757454, 11427.485763140512, 11666.533338666359, 10059.29584929048, 10603.522841471999, 10701.304415212986, 11167.281007684844, 11447.084233261152], "value": 7848.756571998165, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1577.8000000000002, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 118.0, 118.0, 112.0, 125.0, 117.0, 117.0, 117.0, 124.0, 195.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [151.0, 112.0, 142.0, 149.0, 105.0, 171.0, 164.0, 103.0, 164.0, 131.0], "value": 139.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [242.0, 265.0, 204.0, 199.0, 205.0, 206.0, 203.0, 202.0, 197.0, 199.0], "value": 212.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 90.0, 80.0, 152.0, 60.0, 66.0, 83.0, 86.0, 140.0, 55.0], "value": 88.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 73.0, 120.0, 71.0, 71.0, 72.0, 107.0, 65.0, 73.0, 117.0], "value": 83.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 88.0, 89.0, 88.0, 91.0, 86.0, 90.0, 90.0, 94.0, 85.0], "value": 89.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 99.0, 99.0, 133.0, 99.0, 99.0, 99.0, 100.0, 99.0, 100.0], "value": 102.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 150.0, 92.0, 95.0, 92.0, 91.0, 90.0, 95.0, 76.0, 101.0], "value": 102.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 58.0, 57.0, 58.0, 57.0, 59.0, 59.0, 57.0, 57.0, 56.0], "value": 57.5, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 69.0, 73.0, 74.0, 82.0, 74.0, 68.0, 94.0, 63.0, 78.0], "value": 74.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 118.0, 74.0, 75.0, 97.0, 78.0, 84.0, 108.0, 108.0], "value": 92.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [131.0, 160.0, 164.0, 127.0, 156.0, 159.0, 193.0, 142.0, 177.0, 141.0], "value": 155.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 176.0, 233.0, 172.0, 173.0, 171.0, 171.0, 176.0, 174.0], "value": 179.2, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 69.0, 68.0, 70.0, 66.0, 85.0, 94.0, 91.0, 82.0, 68.0], "value": 77.0, "unit": "score"}]}]} 09:26:45 INFO - Return code: 0 09:26:45 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:26:45 ERROR - # TBPL SUCCESS # 09:26:45 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:26:45 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:26:45 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:26:45 INFO - 2016-09-12 09:26:45.878 screenresolution[2272:78439] starting screenresolution argv=screenresolution get 09:26:45 INFO - 2016-09-12 09:26:45.881 screenresolution[2272:78439] Display 0: 1600x1200x32@60 09:26:45 INFO - 2016-09-12 09:26:45.886 screenresolution[2273:78442] starting screenresolution argv=screenresolution list 09:26:45 INFO - Available Modes on Display 0 09:26:45 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:26:45 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:26:45 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:26:45 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:26:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:26:46 INFO - Intel Iris: 09:26:46 INFO - Chipset Model: Intel Iris 09:26:46 INFO - Type: GPU 09:26:46 INFO - Bus: Built-In 09:26:46 INFO - VRAM (Dynamic, Max): 1536 MB 09:26:46 INFO - Vendor: Intel (0x8086) 09:26:46 INFO - Device ID: 0x0a2e 09:26:46 INFO - Revision ID: 0x0009 09:26:46 INFO - Displays: 09:26:46 INFO - Display: 09:26:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:26:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:26:46 INFO - Display Serial Number: 1600x1200 60 09:26:46 INFO - Main Display: Yes 09:26:46 INFO - Mirror: Off 09:26:46 INFO - Online: Yes 09:26:46 INFO - Rotation: Supported 09:26:46 INFO - Return code: 0 09:26:46 INFO - Running post-action listener: _resource_record_post_action 09:26:46 INFO - [mozharness: 2016-09-12 16:26:46.127911Z] Finished run-tests step (success) 09:26:46 INFO - Running post-run listener: _resource_record_post_run 09:26:46 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 28672; Write bytes: 706240512; Read time: 0; Write time: 2491 09:26:46 INFO - TinderboxPrint: CPU usage
54.5% 09:26:46 INFO - TinderboxPrint: I/O read bytes / time
28,672 / 0 09:26:46 INFO - TinderboxPrint: I/O write bytes / time
706,240,512 / 2,491 09:26:46 INFO - TinderboxPrint: CPU idle
2,204.1 (45.5%) 09:26:46 INFO - TinderboxPrint: CPU system
980.3 (20.2%) 09:26:46 INFO - TinderboxPrint: CPU user
1,657.3 (34.2%) 09:26:46 INFO - TinderboxPrint: Swap in / out
405,356,544 / 0 09:26:46 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 398422528; Write bytes: 392881152; Read time: 29608; Write time: 826 09:26:46 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 28672; Write bytes: 296750080; Read time: 0; Write time: 1624 09:26:46 INFO - Running post-run listener: _upload_blobber_files 09:26:46 INFO - Blob upload gear active. 09:26:46 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:26:46 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 09:26:46 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'] 09:26:46 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 09:26:47 INFO - (blobuploader) - INFO - Open directory for files ... 09:26:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:26:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:26:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:48 INFO - (blobuploader) - INFO - Done attempting. 09:26:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:26:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:26:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:49 INFO - (blobuploader) - INFO - Done attempting. 09:26:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:26:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:26:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:26:52 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:26:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:26:52 INFO - (blobuploader) - INFO - Done attempting. 09:26:52 INFO - (blobuploader) - INFO - Iteration through files over. 09:26:52 INFO - Return code: 0 09:26:52 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:26:52 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:26:52 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9cb1d5a642e39fa4e2063803bf7f71a726ee3c52af0b0eaf164ef47691d24a17b0f51e867b34f7e5accc3c1127280ce2139f838bd5c67a768727b6b314273921", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5962cdf49d7c31ede229d9182048781ad9b13f015c5001cf1e75492c27a70d7304f252d3502fb15ced730f2eb852a12d14a85ea35d92e6bb720f2c4f160b89d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1e2e0ebabef8de83f517a5865fc1451bf9938e935fd3c5081a991e66d7796f882c6360300f8567d0cc2989691e8414be914ed5b7932ec2504f348131e9beab"} 09:26:52 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:26:52 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:26:52 INFO - Contents: 09:26:52 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/9cb1d5a642e39fa4e2063803bf7f71a726ee3c52af0b0eaf164ef47691d24a17b0f51e867b34f7e5accc3c1127280ce2139f838bd5c67a768727b6b314273921", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5962cdf49d7c31ede229d9182048781ad9b13f015c5001cf1e75492c27a70d7304f252d3502fb15ced730f2eb852a12d14a85ea35d92e6bb720f2c4f160b89d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1e2e0ebabef8de83f517a5865fc1451bf9938e935fd3c5081a991e66d7796f882c6360300f8567d0cc2989691e8414be914ed5b7932ec2504f348131e9beab"} 09:26:52 INFO - Running post-run listener: copy_logs_to_upload_dir 09:26:52 INFO - Copying logs to upload dir... 09:26:52 INFO - mkdir: /builds/slave/test/build/upload/logs 09:26:52 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.619417 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2016-09-12 09:26:52.208811) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 09:26:52.212090) ========= 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.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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/9cb1d5a642e39fa4e2063803bf7f71a726ee3c52af0b0eaf164ef47691d24a17b0f51e867b34f7e5accc3c1127280ce2139f838bd5c67a768727b6b314273921", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5962cdf49d7c31ede229d9182048781ad9b13f015c5001cf1e75492c27a70d7304f252d3502fb15ced730f2eb852a12d14a85ea35d92e6bb720f2c4f160b89d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1e2e0ebabef8de83f517a5865fc1451bf9938e935fd3c5081a991e66d7796f882c6360300f8567d0cc2989691e8414be914ed5b7932ec2504f348131e9beab"} build_url:https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011610 build_url: 'https://queue.taskcluster.net/v1/task/aSaKTahXTjOe3jMacQeL-w/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/9cb1d5a642e39fa4e2063803bf7f71a726ee3c52af0b0eaf164ef47691d24a17b0f51e867b34f7e5accc3c1127280ce2139f838bd5c67a768727b6b314273921", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/5962cdf49d7c31ede229d9182048781ad9b13f015c5001cf1e75492c27a70d7304f252d3502fb15ced730f2eb852a12d14a85ea35d92e6bb720f2c4f160b89d4", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/fe1e2e0ebabef8de83f517a5865fc1451bf9938e935fd3c5081a991e66d7796f882c6360300f8567d0cc2989691e8414be914ed5b7932ec2504f348131e9beab"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 09:26:52.250873) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:26:52.251607) ========= 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.Cr7LlCImIO/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.PcabCR5jYp/Listeners TMPDIR=/var/folders/46/l2pgwt217d93gf4d7qtgkmq000000w/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.005049 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 09:26:52.304459) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 09:26:52.305176) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 09:26:52.305895) ========= ========= Total master_lag: 0.24 =========