builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0080 starttime: 1473269147.66 results: success (0) buildid: 20160907081416 builduid: 32fc3d4e2538445cbcb8a7597ce7c18b revision: a14f88a9af7a59e677478694bafd9375ac53683e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.660185) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.661182) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.661840) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.692359) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.693059) ========= 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.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-07 10:25:47-- 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% 75.2M=0s 2016-09-07 10:25:47 (75.2 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.148383 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.867997) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.868676) ========= 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.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.021871 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:47.921134) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:25:47.921562) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev a14f88a9af7a59e677478694bafd9375ac53683e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev a14f88a9af7a59e677478694bafd9375ac53683e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-07 10:25:47,998 truncating revision to first 12 chars 2016-09-07 10:25:47,998 Setting DEBUG logging. 2016-09-07 10:25:47,998 attempt 1/10 2016-09-07 10:25:47,998 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/a14f88a9af7a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-07 10:25:49,128 unpacking tar archive at: mozilla-central-a14f88a9af7a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.402281 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-09-07 10:25:49.349608) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:49.350006) ========= script_repo_revision: a14f88a9af7a59e677478694bafd9375ac53683e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:49.350442) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:49.350757) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-07 10:25:49.371124) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-07 10:25:49.372028) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 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', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 10:25:49 INFO - MultiFileLogger online at 20160907 10:25:49 in /builds/slave/test 10:25:49 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:25:49 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:25:49 INFO - {'append_to_log': False, 10:25:49 INFO - 'base_work_dir': '/builds/slave/test', 10:25:49 INFO - 'blob_upload_branch': 'Firefox', 10:25:49 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:25:49 INFO - 'branch': 'Firefox', 10:25:49 INFO - 'buildbot_json_path': 'buildprops.json', 10:25:49 INFO - 'config_files': ('talos/mac_config.py',), 10:25:49 INFO - 'default_actions': ('clobber', 10:25:49 INFO - 'read-buildbot-config', 10:25:49 INFO - 'download-and-extract', 10:25:49 INFO - 'populate-webroot', 10:25:49 INFO - 'create-virtualenv', 10:25:49 INFO - 'install', 10:25:49 INFO - 'run-tests'), 10:25:49 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:25:49 INFO - 'download_minidump_stackwalk': True, 10:25:49 INFO - 'download_symbols': 'ondemand', 10:25:49 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:25:49 INFO - 'tooltool.py': '/tools/tooltool.py', 10:25:49 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:25:49 INFO - '/tools/misc-python/virtualenv.py')}, 10:25:49 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:25:49 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:25:49 INFO - 'installer_path': 'installer.exe', 10:25:49 INFO - 'log_level': 'info', 10:25:49 INFO - 'log_name': 'talos', 10:25:49 INFO - 'log_to_console': True, 10:25:49 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:25:49 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:25:49 INFO - 'opt_config_files': (), 10:25:49 INFO - 'pip_index': False, 10:25:49 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:49 INFO - 'cmd': ('bash', 10:25:49 INFO - '-c', 10:25:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:49 INFO - 'enabled': True, 10:25:49 INFO - 'halt_on_failure': False, 10:25:49 INFO - 'name': 'check_screen_resolution'},), 10:25:49 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:25:49 INFO - 'cmd': ('bash', 10:25:49 INFO - '-c', 10:25:49 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:25:49 INFO - 'enabled': True, 10:25:49 INFO - 'halt_on_failure': False, 10:25:49 INFO - 'name': 'check_screen_resolution'},), 10:25:49 INFO - 'run_cmd_checks_enabled': True, 10:25:49 INFO - 'sps_profile': False, 10:25:49 INFO - 'sps_profile_interval': 0, 10:25:49 INFO - 'suite': 'dromaeojs-e10s', 10:25:49 INFO - 'system_bits': '32', 10:25:49 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:25:49 INFO - 'title': 't-yosemite-r7-0080', 10:25:49 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:25:49 INFO - 'use_talos_json': True, 10:25:49 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:25:49 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:25:49 INFO - 'work_dir': 'build'} 10:25:49 INFO - [mozharness: 2016-09-07 17:25:49.579403Z] Running clobber step. 10:25:49 INFO - Running pre-action listener: _resource_record_pre_action 10:25:49 INFO - Running main action method: clobber 10:25:49 INFO - rmtree: /builds/slave/test/build 10:25:49 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:25:49 INFO - Running post-action listener: _resource_record_post_action 10:25:49 INFO - [mozharness: 2016-09-07 17:25:49.829828Z] Finished clobber step (success) 10:25:49 INFO - [mozharness: 2016-09-07 17:25:49.830014Z] Running read-buildbot-config step. 10:25:49 INFO - Running pre-action listener: _resource_record_pre_action 10:25:49 INFO - Running main action method: read_buildbot_config 10:25:49 INFO - Using buildbot properties: 10:25:49 INFO - { 10:25:49 INFO - "project": "", 10:25:49 INFO - "product": "firefox", 10:25:49 INFO - "script_repo_revision": "production", 10:25:49 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:25:49 INFO - "repository": "", 10:25:49 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:25:49 INFO - "buildid": "20160907081416", 10:25:49 INFO - "pgo_build": "False", 10:25:49 INFO - "basedir": "/builds/slave/test", 10:25:49 INFO - "buildnumber": 21, 10:25:49 INFO - "slavename": "t-yosemite-r7-0080", 10:25:49 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 10:25:49 INFO - "platform": "yosemite_r7", 10:25:49 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:25:49 INFO - "branch": "mozilla-central", 10:25:49 INFO - "repo_path": "mozilla-central", 10:25:49 INFO - "revision": "a14f88a9af7a59e677478694bafd9375ac53683e", 10:25:49 INFO - "stage_platform": "macosx64", 10:25:49 INFO - "builduid": "32fc3d4e2538445cbcb8a7597ce7c18b", 10:25:49 INFO - "slavebuilddir": "test" 10:25:49 INFO - } 10:25:49 INFO - Found installer url https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 10:25:49 INFO - Running post-action listener: _resource_record_post_action 10:25:49 INFO - [mozharness: 2016-09-07 17:25:49.834007Z] Finished read-buildbot-config step (success) 10:25:49 INFO - [mozharness: 2016-09-07 17:25:49.834119Z] Running download-and-extract step. 10:25:49 INFO - Running pre-action listener: _resource_record_pre_action 10:25:49 INFO - Running main action method: download_and_extract 10:25:49 INFO - mkdir: /builds/slave/test/build/tests 10:25:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:25:49 INFO - trying https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 10:25:49 INFO - Downloading https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:25:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 10:25:52 INFO - Downloaded 1199 bytes. 10:25:52 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 10:25:52 INFO - Using the following test package requirements: 10:25:52 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:25:52 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 10:25:52 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 10:25:52 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'jsshell-mac.zip'], 10:25:52 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 10:25:52 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 10:25:52 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 10:25:52 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 10:25:52 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 10:25:52 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 10:25:52 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 10:25:52 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:25:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:25:52 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:25:59 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:26:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 10:26:00 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:26:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 10:26:08 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 10:26: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')]} 10:26:08 INFO - trying https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:26:08 INFO - Downloading https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 10:26:08 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 10:26:11 INFO - Downloaded 111507651 bytes. 10:26:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:26:11 INFO - mkdir: /builds/slave/test/properties 10:26:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:26:11 INFO - Writing to file /builds/slave/test/properties/build_url 10:26:11 INFO - Contents: 10:26:11 INFO - build_url:https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 10:26:12 INFO - Running post-action listener: _resource_record_post_action 10:26:12 INFO - Running post-action listener: set_extra_try_arguments 10:26:12 INFO - [mozharness: 2016-09-07 17:26:12.279697Z] Finished download-and-extract step (success) 10:26:12 INFO - [mozharness: 2016-09-07 17:26:12.279817Z] Running populate-webroot step. 10:26:12 INFO - Running pre-action listener: _resource_record_pre_action 10:26:12 INFO - Running main action method: populate_webroot 10:26:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:26:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:26:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:26:12 INFO - '--tppagecycles', 10:26:12 INFO - '7'], 10:26:12 INFO - 'tests': ['tsvgm']}}, 10:26:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:26:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:26:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:26:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:26:12 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:12 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:26:12 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['damp', 'tps']}, 10:26:12 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'tests': ['damp', 'tps']}, 10:26:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['dromaeo_dom']}, 10:26:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:26:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['basic_compositor_video']}, 10:26:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:26:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['a11yr', 10:26:12 INFO - 'ts_paint', 10:26:12 INFO - 'tpaint', 10:26:12 INFO - 'sessionrestore', 10:26:12 INFO - 'sessionrestore_no_auto_restore', 10:26:12 INFO - 'tabpaint']}, 10:26:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tsvgx', 10:26:12 INFO - 'tsvgr_opacity', 10:26:12 INFO - 'tart', 10:26:12 INFO - 'tscrollx', 10:26:12 INFO - 'cart']}, 10:26:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:26:12 INFO - 'tsvgr_opacity', 10:26:12 INFO - 'tart', 10:26:12 INFO - 'tscrollx', 10:26:12 INFO - 'cart']}, 10:26:12 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s'], 10:26:12 INFO - 'tests': ['tp5o']}, 10:26:12 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'tests': ['tp5o']}, 10:26:12 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'talos_options': ['--disable-e10s', 10:26:12 INFO - '--xperf_path', 10:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:12 INFO - 'tests': ['tp5n']}, 10:26:12 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:26:12 INFO - 'talos_options': ['--xperf_path', 10:26:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:26:12 INFO - 'tests': ['tp5n']}}, 10:26:12 INFO - 'talos.zip': {'path': '', 10:26:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:26:12 INFO - Running post-action listener: _resource_record_post_action 10:26:12 INFO - [mozharness: 2016-09-07 17:26:12.298508Z] Finished populate-webroot step (success) 10:26:12 INFO - [mozharness: 2016-09-07 17:26:12.298598Z] Running create-virtualenv step. 10:26:12 INFO - Running pre-action listener: _resource_record_pre_action 10:26:12 INFO - Running main action method: create_virtualenv 10:26:12 INFO - Creating virtualenv /builds/slave/test/build/venv 10:26:12 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 10:26:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:26:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:26:12 INFO - Using real prefix '/tools/python27' 10:26:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:26:13 INFO - Installing distribute.............................................................................................................................................................................................done. 10:26:15 INFO - Installing pip.................done. 10:26:15 INFO - Return code: 0 10:26:15 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 10:26:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 10:26:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:15 INFO - 'HOME': '/Users/cltbld', 10:26:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:15 INFO - 'LOGNAME': 'cltbld', 10:26:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:15 INFO - 'MOZ_NO_REMOTE': '1', 10:26:15 INFO - 'NO_EM_RESTART': '1', 10:26:15 INFO - 'PAGER': '/bin/cat', 10:26:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:15 INFO - 'PWD': '/builds/slave/test', 10:26:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:15 INFO - 'SHELL': '/bin/bash', 10:26:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:15 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:15 INFO - 'USER': 'cltbld', 10:26:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:15 INFO - 'XPC_FLAGS': '0x0', 10:26:15 INFO - 'XPC_SERVICE_NAME': '0', 10:26:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:16 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 10:26:16 INFO - Cleaning up... 10:26:16 INFO - Return code: 0 10:26:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:26: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')]} 10:26:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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')]} 10:26:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26: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', '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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:26: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 psutil>=3.1.1 10:26:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:16 INFO - 'HOME': '/Users/cltbld', 10:26:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:16 INFO - 'LOGNAME': 'cltbld', 10:26:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:16 INFO - 'MOZ_NO_REMOTE': '1', 10:26:16 INFO - 'NO_EM_RESTART': '1', 10:26:16 INFO - 'PAGER': '/bin/cat', 10:26:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:16 INFO - 'PWD': '/builds/slave/test', 10:26:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:16 INFO - 'SHELL': '/bin/bash', 10:26:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:16 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:16 INFO - 'USER': 'cltbld', 10:26:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:16 INFO - 'XPC_FLAGS': '0x0', 10:26:16 INFO - 'XPC_SERVICE_NAME': '0', 10:26:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:16 INFO - Downloading/unpacking psutil>=3.1.1 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:26:19 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:19 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:19 INFO - Installing collected packages: psutil 10:26:19 INFO - Running setup.py install for psutil 10:26:20 INFO - building 'psutil._psutil_osx' extension 10:26:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 10:26:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 10:26:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 10:26:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 10:26:20 INFO - building 'psutil._psutil_posix' extension 10:26:20 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 10:26:20 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:26:20 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:26:20 INFO - ^ 10:26:20 INFO - 1 warning generated. 10:26:20 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 10:26:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:26:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:26:20 INFO - Successfully installed psutil 10:26:20 INFO - Cleaning up... 10:26:20 INFO - Return code: 0 10:26:20 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:20 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:20 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:26:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:26:20 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:20 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:20 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:20 INFO - 'HOME': '/Users/cltbld', 10:26:20 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:20 INFO - 'LOGNAME': 'cltbld', 10:26:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:20 INFO - 'MOZ_NO_REMOTE': '1', 10:26:20 INFO - 'NO_EM_RESTART': '1', 10:26:20 INFO - 'PAGER': '/bin/cat', 10:26:20 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:20 INFO - 'PWD': '/builds/slave/test', 10:26:20 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:20 INFO - 'SHELL': '/bin/bash', 10:26:20 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:20 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:20 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:20 INFO - 'USER': 'cltbld', 10:26:20 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:20 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:20 INFO - 'XPC_FLAGS': '0x0', 10:26:20 INFO - 'XPC_SERVICE_NAME': '0', 10:26:20 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:21 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:26:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:26:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:26:23 INFO - Installing collected packages: mozsystemmonitor 10:26:23 INFO - Running setup.py install for mozsystemmonitor 10:26:23 INFO - Successfully installed mozsystemmonitor 10:26:23 INFO - Cleaning up... 10:26:23 INFO - Return code: 0 10:26:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 10:26:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 10:26:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:23 INFO - 'HOME': '/Users/cltbld', 10:26:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:23 INFO - 'LOGNAME': 'cltbld', 10:26:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:23 INFO - 'MOZ_NO_REMOTE': '1', 10:26:23 INFO - 'NO_EM_RESTART': '1', 10:26:23 INFO - 'PAGER': '/bin/cat', 10:26:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:23 INFO - 'PWD': '/builds/slave/test', 10:26:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:23 INFO - 'SHELL': '/bin/bash', 10:26:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:23 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:23 INFO - 'USER': 'cltbld', 10:26:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:23 INFO - 'XPC_FLAGS': '0x0', 10:26:23 INFO - 'XPC_SERVICE_NAME': '0', 10:26:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:24 INFO - Downloading/unpacking jsonschema==2.5.1 10:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:24 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:24 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:24 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:24 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:26 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:26:26 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:27 INFO - Downloading functools32-3.2.3-2.tar.gz 10:26:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:26:27 INFO - warning: no files found matching '*.txt' 10:26:27 INFO - no previously-included directories found matching 'build' 10:26:27 INFO - no previously-included directories found matching 'dist' 10:26:27 INFO - no previously-included directories found matching '.git*' 10:26:27 INFO - Installing collected packages: jsonschema, functools32 10:26:27 INFO - Running setup.py install for functools32 10:26:27 INFO - warning: no files found matching '*.txt' 10:26:27 INFO - no previously-included directories found matching 'build' 10:26:27 INFO - no previously-included directories found matching 'dist' 10:26:27 INFO - no previously-included directories found matching '.git*' 10:26:27 INFO - Successfully installed jsonschema functools32 10:26:27 INFO - Cleaning up... 10:26:27 INFO - Return code: 0 10:26:27 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:27 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:27 INFO - 'HOME': '/Users/cltbld', 10:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:27 INFO - 'LOGNAME': 'cltbld', 10:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:27 INFO - 'MOZ_NO_REMOTE': '1', 10:26:27 INFO - 'NO_EM_RESTART': '1', 10:26:27 INFO - 'PAGER': '/bin/cat', 10:26:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:27 INFO - 'PWD': '/builds/slave/test', 10:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:27 INFO - 'SHELL': '/bin/bash', 10:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:27 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:27 INFO - 'USER': 'cltbld', 10:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:27 INFO - 'XPC_FLAGS': '0x0', 10:26:27 INFO - 'XPC_SERVICE_NAME': '0', 10:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:27 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:26:27 INFO - Cleaning up... 10:26:27 INFO - Return code: 0 10:26:27 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:27 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:27 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:27 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:27 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:27 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 10:26:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 10:26:27 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:27 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:27 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:27 INFO - 'HOME': '/Users/cltbld', 10:26:27 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:27 INFO - 'LOGNAME': 'cltbld', 10:26:27 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:27 INFO - 'MOZ_NO_REMOTE': '1', 10:26:27 INFO - 'NO_EM_RESTART': '1', 10:26:27 INFO - 'PAGER': '/bin/cat', 10:26:27 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:27 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:27 INFO - 'PWD': '/builds/slave/test', 10:26:27 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:27 INFO - 'SHELL': '/bin/bash', 10:26:27 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:27 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:27 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:27 INFO - 'USER': 'cltbld', 10:26:27 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:27 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:27 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:27 INFO - 'XPC_FLAGS': '0x0', 10:26:27 INFO - 'XPC_SERVICE_NAME': '0', 10:26:27 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:27 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:27 INFO - Downloading/unpacking blobuploader==1.2.4 10:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:27 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:27 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:27 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:27 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:30 INFO - Downloading blobuploader-1.2.4.tar.gz 10:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:26:30 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:26:31 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26: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 10:26:31 INFO - Downloading docopt-0.6.1.tar.gz 10:26:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:26:31 INFO - Installing collected packages: blobuploader, requests, docopt 10:26:31 INFO - Running setup.py install for blobuploader 10:26:31 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:26:31 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:26:31 INFO - Running setup.py install for requests 10:26:32 INFO - Running setup.py install for docopt 10:26:32 INFO - Successfully installed blobuploader requests docopt 10:26:32 INFO - Cleaning up... 10:26:32 INFO - Return code: 0 10:26:32 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:32 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:32 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:32 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:32 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:32 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:32 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:26:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:32 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:32 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:32 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:32 INFO - 'HOME': '/Users/cltbld', 10:26:32 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:32 INFO - 'LOGNAME': 'cltbld', 10:26:32 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:32 INFO - 'MOZ_NO_REMOTE': '1', 10:26:32 INFO - 'NO_EM_RESTART': '1', 10:26:32 INFO - 'PAGER': '/bin/cat', 10:26:32 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:32 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:32 INFO - 'PWD': '/builds/slave/test', 10:26:32 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:32 INFO - 'SHELL': '/bin/bash', 10:26:32 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:32 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:32 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:32 INFO - 'USER': 'cltbld', 10:26:32 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:32 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:32 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:32 INFO - 'XPC_FLAGS': '0x0', 10:26:32 INFO - 'XPC_SERVICE_NAME': '0', 10:26:32 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:32 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-MgCLsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:32 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:32 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-i5Sur1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-8_b_nK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-HqwMrx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-BTBfkH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-4CUMkO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-TZoE4v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-CZ4iG5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-QQfQx8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-W8Sqtv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-qJf4vJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:33 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:33 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-g_wSJ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:34 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-iaZlAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:34 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-dY8hcK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:34 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-z3E8OY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:34 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Jj2y6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:34 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-urCztk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:34 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:26:34 INFO - Running setup.py install for manifestparser 10:26:34 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:26:34 INFO - Running setup.py install for mozcrash 10:26:34 INFO - Running setup.py install for mozdebug 10:26:35 INFO - Running setup.py install for mozdevice 10:26:35 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Running setup.py install for mozfile 10:26:35 INFO - Running setup.py install for mozhttpd 10:26:35 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Running setup.py install for mozinfo 10:26:35 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Running setup.py install for mozInstall 10:26:35 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:26:35 INFO - Running setup.py install for mozleak 10:26:35 INFO - Running setup.py install for mozlog 10:26:36 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Running setup.py install for moznetwork 10:26:36 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Running setup.py install for mozprocess 10:26:36 INFO - Running setup.py install for mozprofile 10:26:36 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Running setup.py install for mozrunner 10:26:36 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:26:36 INFO - Running setup.py install for mozscreenshot 10:26:36 INFO - Running setup.py install for moztest 10:26:37 INFO - Running setup.py install for mozversion 10:26:37 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:26:37 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:26:37 INFO - Cleaning up... 10:26:37 INFO - Return code: 0 10:26:37 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:37 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:37 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 10:26:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:37 INFO - 'HOME': '/Users/cltbld', 10:26:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:37 INFO - 'LOGNAME': 'cltbld', 10:26:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:37 INFO - 'MOZ_NO_REMOTE': '1', 10:26:37 INFO - 'NO_EM_RESTART': '1', 10:26:37 INFO - 'PAGER': '/bin/cat', 10:26:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:37 INFO - 'PWD': '/builds/slave/test', 10:26:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:37 INFO - 'SHELL': '/bin/bash', 10:26:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:37 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:37 INFO - 'USER': 'cltbld', 10:26:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:37 INFO - 'XPC_FLAGS': '0x0', 10:26:37 INFO - 'XPC_SERVICE_NAME': '0', 10:26:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-6FeRRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-Zo5wot-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-L_s9bn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-vpzgKe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-m7guO0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:26:37 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 10:26:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:26:37 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-lZOjHu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-rb1YV2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-3TpklE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-dr14qW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-dsr7TJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-PgjqyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-LPTG62-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-mkQ6sa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-2fUBFy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:26:38 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:26:38 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:38 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-NyAVoC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 10:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:26:39 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-qOK5zo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:26:39 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:26:39 INFO - Running setup.py (path:/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/pip-7S3WrK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:39 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 10:26:39 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:39 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:39 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:26:39 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:26:39 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:26:41 INFO - Downloading blessings-1.6.tar.gz 10:26:41 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:26:41 INFO - Installing collected packages: blessings 10:26:41 INFO - Running setup.py install for blessings 10:26:41 INFO - Successfully installed blessings 10:26:41 INFO - Cleaning up... 10:26:42 INFO - Return code: 0 10:26:42 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:26:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:42 INFO - Reading from file tmpfile_stdout 10:26:42 INFO - Current package versions: 10:26:42 INFO - blessings == 1.6 10:26:42 INFO - blobuploader == 1.2.4 10:26:42 INFO - docopt == 0.6.1 10:26:42 INFO - functools32 == 3.2.3-2 10:26:42 INFO - jsonschema == 2.5.1 10:26:42 INFO - manifestparser == 1.1 10:26:42 INFO - mozInstall == 1.12 10:26:42 INFO - mozcrash == 0.17 10:26:42 INFO - mozdebug == 0.1 10:26:42 INFO - mozdevice == 0.48 10:26:42 INFO - mozfile == 1.2 10:26:42 INFO - mozhttpd == 0.7 10:26:42 INFO - mozinfo == 0.9 10:26:42 INFO - mozleak == 0.1 10:26:42 INFO - mozlog == 3.3 10:26:42 INFO - moznetwork == 0.27 10:26:42 INFO - mozprocess == 0.23 10:26:42 INFO - mozprofile == 0.28 10:26:42 INFO - mozrunner == 6.12 10:26:42 INFO - mozscreenshot == 0.1 10:26:42 INFO - mozsystemmonitor == 0.3 10:26:42 INFO - moztest == 0.7 10:26:42 INFO - mozversion == 1.4 10:26:42 INFO - psutil == 3.1.1 10:26:42 INFO - requests == 1.2.3 10:26:42 INFO - wsgiref == 0.1.2 10:26:42 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:42 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 10:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 10:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:42 INFO - 'HOME': '/Users/cltbld', 10:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:42 INFO - 'LOGNAME': 'cltbld', 10:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:42 INFO - 'MOZ_NO_REMOTE': '1', 10:26:42 INFO - 'NO_EM_RESTART': '1', 10:26:42 INFO - 'PAGER': '/bin/cat', 10:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:42 INFO - 'PWD': '/builds/slave/test', 10:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:42 INFO - 'SHELL': '/bin/bash', 10:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:42 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:42 INFO - 'USER': 'cltbld', 10:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:42 INFO - 'XPC_FLAGS': '0x0', 10:26:42 INFO - 'XPC_SERVICE_NAME': '0', 10:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 10:26:42 INFO - Cleaning up... 10:26:42 INFO - Return code: 0 10:26:42 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:42 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:26:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:26:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:26:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:26:42 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 0x103f85a30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10401a618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbbe1d9c380>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, '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.N9ShzF4oZe/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.ldg0unxNDF/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 10:26:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 10:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 10:26:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:26:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:26:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:26:42 INFO - 'HOME': '/Users/cltbld', 10:26:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:26:42 INFO - 'LOGNAME': 'cltbld', 10:26:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:26:42 INFO - 'MOZ_NO_REMOTE': '1', 10:26:42 INFO - 'NO_EM_RESTART': '1', 10:26:42 INFO - 'PAGER': '/bin/cat', 10:26:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:26:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:26:42 INFO - 'PWD': '/builds/slave/test', 10:26:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:26:42 INFO - 'SHELL': '/bin/bash', 10:26:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:26:42 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:26:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:26:42 INFO - 'USER': 'cltbld', 10:26:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:26:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:26:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:26:42 INFO - 'XPC_FLAGS': '0x0', 10:26:42 INFO - 'XPC_SERVICE_NAME': '0', 10:26:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:26:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:26:42 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:26:42 INFO - Cleaning up... 10:26:42 INFO - Return code: 0 10:26:42 INFO - Running post-action listener: _resource_record_post_action 10:26:42 INFO - Running post-action listener: _start_resource_monitoring 10:26:42 INFO - Starting resource monitoring. 10:26:42 INFO - [mozharness: 2016-09-07 17:26:42.917946Z] Finished create-virtualenv step (success) 10:26:42 INFO - [mozharness: 2016-09-07 17:26:42.918911Z] Running install step. 10:26:42 INFO - Running pre-action listener: _resource_record_pre_action 10:26:42 INFO - Running main action method: install 10:26:42 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:26:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:26:43 INFO - Reading from file tmpfile_stdout 10:26:43 INFO - Detecting whether we're running mozinstall >=1.0... 10:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:26:43 INFO - Reading from file tmpfile_stdout 10:26:43 INFO - Output received: 10:26:43 INFO - Usage: mozinstall [options] installer 10:26:43 INFO - Options: 10:26:43 INFO - -h, --help show this help message and exit 10:26:43 INFO - -d DEST, --destination=DEST 10:26:43 INFO - Directory to install application into. [default: 10:26:43 INFO - "/builds/slave/test"] 10:26:43 INFO - --app=APP Application being installed. [default: firefox] 10:26:43 INFO - mkdir: /builds/slave/test/build/application 10:26:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:26:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:27:17 INFO - Reading from file tmpfile_stdout 10:27:17 INFO - Output received: 10:27:17 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:27:17 INFO - Running post-action listener: _resource_record_post_action 10:27:17 INFO - [mozharness: 2016-09-07 17:27:17.159483Z] Finished install step (success) 10:27:17 INFO - [mozharness: 2016-09-07 17:27:17.159606Z] Running run-tests step. 10:27:17 INFO - Running pre-action listener: _resource_record_pre_action 10:27:17 INFO - Running main action method: run_tests 10:27:17 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:27:17 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:27:17 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:27:17 INFO - 2016-09-07 10:27:17.173 screenresolution[1914:9791] starting screenresolution argv=screenresolution get 10:27:17 INFO - 2016-09-07 10:27:17.176 screenresolution[1914:9791] Display 0: 1600x1200x32@60 10:27:17 INFO - 2016-09-07 10:27:17.182 screenresolution[1915:9793] starting screenresolution argv=screenresolution list 10:27:17 INFO - Available Modes on Display 0 10:27:17 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:27:17 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:27:17 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:27:17 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:27:17 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:27:17 INFO - Intel Iris: 10:27:17 INFO - Chipset Model: Intel Iris 10:27:17 INFO - Type: GPU 10:27:17 INFO - Bus: Built-In 10:27:17 INFO - VRAM (Dynamic, Max): 1536 MB 10:27:17 INFO - Vendor: Intel (0x8086) 10:27:17 INFO - Device ID: 0x0a2e 10:27:17 INFO - Revision ID: 0x0009 10:27:17 INFO - Displays: 10:27:17 INFO - Display: 10:27:17 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:27:17 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:27:17 INFO - Display Serial Number: 1600x1200 60 10:27:17 INFO - Main Display: Yes 10:27:17 INFO - Mirror: Off 10:27:17 INFO - Online: Yes 10:27:17 INFO - Rotation: Supported 10:27:17 INFO - Return code: 0 10:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:27:17 INFO - Python 2.7.3 10:27:17 INFO - Return code: 0 10:27:17 INFO - grabbing minidump binary from tooltool 10:27:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:27:17 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 0x103ee9d30>, '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 0x103c4edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103ee1b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:27:17 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 10:27:17 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 10:27:17 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 10:27:17 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:27:17 INFO - Return code: 0 10:27:17 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:27:17 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:27:17 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:27:17 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:27:17 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:27:17 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 10:27:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0080 --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 10:27:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.N9ShzF4oZe/Render', 10:27:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:27:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:27:17 INFO - 'HOME': '/Users/cltbld', 10:27:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:27:17 INFO - 'LOGNAME': 'cltbld', 10:27:17 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:27:17 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:27:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:27:17 INFO - 'MOZ_NO_REMOTE': '1', 10:27:17 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:27:17 INFO - 'NO_EM_RESTART': '1', 10:27:17 INFO - 'PAGER': '/bin/cat', 10:27:17 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:27:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:27:17 INFO - 'PWD': '/builds/slave/test', 10:27:17 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:27:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:27:17 INFO - 'SHELL': '/bin/bash', 10:27:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.ldg0unxNDF/Listeners', 10:27:17 INFO - 'TMPDIR': '/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/', 10:27:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:27:17 INFO - 'USER': 'cltbld', 10:27:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:27:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:27:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:27:17 INFO - 'XPC_FLAGS': '0x0', 10:27:17 INFO - 'XPC_SERVICE_NAME': '0', 10:27:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:27:17 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0080', '--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 10:27:17 INFO - mozversion application_buildid: 20160907081416 10:27:17 INFO - mozversion application_changeset: a14f88a9af7a59e677478694bafd9375ac53683e 10:27:17 INFO - mozversion application_display_name: Nightly 10:27:17 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:27:17 INFO - mozversion application_name: Firefox 10:27:17 INFO - mozversion application_remotingname: firefox 10:27:17 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:27:17 INFO - mozversion application_vendor: Mozilla 10:27:17 INFO - mozversion application_version: 51.0a1 10:27:17 INFO - mozversion platform_buildid: 20160907081416 10:27:17 INFO - mozversion platform_changeset: a14f88a9af7a59e677478694bafd9375ac53683e 10:27:17 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:27:17 INFO - mozversion platform_version: 51.0a1 10:27:17 INFO - using testdate: 1473269237 10:27:17 INFO - actual date: 1473269237 10:27:17 INFO - starting webserver on 'localhost:49438' 10:27:17 INFO - SUITE-START | Running 2 tests 10:27:17 INFO - TEST-START | dromaeo_css 10:27:17 INFO - operating with platform_type : mac_ 10:27:17 INFO - Initialising browser for dromaeo_css test... 10:27:17 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpDPMVkg/profile http://localhost:49438/getInfo.html) 10:27:20 INFO - PROCESS | 1925 | __metrics Screen width/height:1600/1200 10:27:20 INFO - PROCESS | 1925 | colorDepth:24 10:27:20 INFO - PROCESS | 1925 | Browser inner width/height: 1024/689 10:27:20 INFO - PROCESS | 1925 | __metrics 10:27:20 ERROR - PROCESS | 1925 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:27:21 INFO - TEST-INFO | 1925: exit 0 10:27:21 INFO - Browser initialized. 10:27:21 INFO - Running cycle 1/1 for dromaeo_css test... 10:27:21 INFO - TEST-INFO | started process 1928 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpDPMVkg/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:27:33 INFO - PROCESS | 1928 | RSS: Main: 244031488 10:27:33 INFO - PROCESS | 1928 | 10:28:22 INFO - PROCESS | 1928 | 1473269302961 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:30:35 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49438/tests/dromaeo/cssquery-ext.html) 10:30:36 INFO - PROCESS | 1928 | RSS: Main: 267661312 10:30:36 INFO - PROCESS | 1928 | 10:33:38 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-ext.html (next: http://localhost:49438/tests/dromaeo/cssquery-jquery.html) 10:33:39 INFO - PROCESS | 1928 | RSS: Main: 274685952 10:33:39 INFO - PROCESS | 1928 | 10:36:41 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49438/tests/dromaeo/cssquery-mootools.html) 10:36:42 INFO - PROCESS | 1928 | RSS: Main: 275283968 10:36:42 INFO - PROCESS | 1928 | 10:39:44 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49438/tests/dromaeo/cssquery-prototype.html) 10:39:44 INFO - PROCESS | 1928 | RSS: Main: 269479936 10:39:44 INFO - PROCESS | 1928 | 10:42:47 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49438/tests/dromaeo/cssquery-yui.html) 10:42:48 INFO - PROCESS | 1928 | RSS: Main: 275894272 10:42:48 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | Cycle 1(1): loaded http://localhost:49438/tests/dromaeo/cssquery-yui.html (next: http://localhost:49438/tests/dromaeo/cssquery-dojo.html) 10:45:51 INFO - PROCESS | 1928 | RSS: Main: 279670784 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | __start_tp_report 10:45:51 INFO - PROCESS | 1928 | _x_x_mozilla_page_load 10:45:51 INFO - PROCESS | 1928 | _x_x_mozilla_page_load_details 10:45:51 INFO - PROCESS | 1928 | |i|pagename|runs| 10:45:51 INFO - PROCESS | 1928 | |0;dojo.html;3371.1740623547234;3396.4565669492886;3402.8468718907648;3407.148212946763;3407.352603005429;5665.23519324891;5739.655620662763;5750.166225897247;5807.535397168227;5859.531237501001;5746.109353050273;5772.191893134954;5789.160571717109;5867.88264234715;5903.793367232148;5166.457521960197;5268.341457317835;5272.1300985337375;5361.973190134064;5364.570834333244;5358.490943360374;5373.7581808818695;5377.65045272058;5412.9458705412835;5432.348118225798;5190.091733946582;5193.766280517386;5240.951809638088;5274.156135018378;5275.57795376369;5193.350831146106;5219.921701174486;5244.947550524522;5288.286081379003;5317.255584218213;5240.842774716764;5268.236105764643;5288.048151332759;5303.15149576066;5317.973410132925;5677.886442271131;5684.744186511617;5729.312482502127;5751;5851.824445266649;6150.96924515379;6214.25428948529;6233.968830155865;6256.562040657156;6262.467690246334;6237.625742455467;6242.313345532033;6259.311475737665;6266.780662676784;6283.8114856554375;5747;5845.766169353264;5901.940980590225;5953.107033944856;5987.670678112746;6279.0095420226835;6336.429721325103;6339.302676705559;6340.904886426707;6372.681365931685;6204.224471941007;6227.221597300338;6235.282942461584;6254.906176407358;6259.78090766821;6120.569035656853;6169.96915015431;6171.660558669227;6186.969065154645;6216.502679785606;6120.873812997669;6151.954167791486;6162.969185154136;6205.348438413992;6225.782097626561;6143.84640383994;6209.720562574695;6248.781292654826;6257;6292.087647291117;4253.383259427365;4364.8472303469225;4529.139463501924;4813.638977076734;4818.445878723922;4420.226460369422;4457.910841783146;4484.327350897392;4497.752623605733;4515.593596576258;5774.942250577525;5832.795852145155;5874.618149820248;5888.852778680482;5918.141869428995;6183.412575805291;6184.5980011299125;6186;6240.032794916795;6242.750289988349;4962.305277261186;5860.384659610765;5875.588708790429;5877.764889404461;5890.4698577128265;5432.809851655173;5457.699826509501;5460.180972854024;5479.53423958968;5497.505224529811;5406.351237851483;6165.383461653799;6262.561620686508;6283.151774510383;6369.235691716931;6231.719572619334;6236.407541283571;6239.313675495782;6260.874782504416;6310.337414571588;6319.988801791691;6377.489800816016;6398.680065996775;6402.93597064042;6403.007533832263;6121.8469538260115;6141.301904043321;6174.073888916699;6248.0003199487865;6258.061290806233;6184.938150618616;6188.566800323934;6225.28409232944;6229.470495007975;6249.718762655601;5980.5215582754145;6143.508519318354;6215.627062376272;6234.812955611339;6241.812745617639;6072.362401947732;6105.186826067751;6121;6156.876862462636;6205;5839.562032847469;6083.574149809471;6098.085287206777;6113.816585502442;6118.571699980958;6158.938410616016;6173.876522469435;6199.969000154971;6228.439440450381;6257.937420625735;5908.261467316585;6012.248468941382;6037.818865434044;6057.151998720271;6093.75624974996 10:45:51 INFO - PROCESS | 1928 | |1;ext.html;471.1636844600833;483.5044079818188;503.99201596806387;534.2547146730311;539.4470667565746;37381.43927841084;39553;39609;39678.404823927594;39682.40476392856;41156;41665.16669666605;41693.74918752442;41744.373834392434;41986.58013419865;9556.42661440314;9703.75740606487;9730.464824434654;9732.805343893118;9779.804403911918;8432.873506897402;9626.566804493814;9700.902990970071;9714.31999760017;9716.902830971705;9665;9693.903060969405;9704.514774261293;9734.17259532937;9760.804783904317;9654.372465789736;9690.51547422626;9699.563519641668;9726.319157658967;9728.464934428604;9633.855492167622;9719.542068689683;9725.319227654067;9732.854007189828;9748.976357482505;39412.40881386781;39455.605443945766;39564.01089972745;39736.40395394072;39883.00292492711;3241.222106694376;3318.7179089777396;3319.535265062893;3335.116194208537;3374.645662205482;30087.849560752056;30564.847175763978;31011.844940775376;31587.52618710721;31732.365352693047;3208.215579349582;3236;3244.172735952317;3271.7873338233176;3310.4372256716174;488028;493088.0691193078;493392;493667;493873;51071.23393149106;52932.47067529352;56734.71632641811;57737.422625774045;58233.41766582365;33433.832830836174;34251.82874085614;34502.82748586241;60205.49486262791;60527.394726053055;15834.970726902715;15962.281697323648;15975.440859569859;16003.599910002344;16018;12350.320732359627;12406.069544784177;12477.438515266835;12489.938355239907;12505.811947864939;18073.45779626613;18088.457346279632;18145.092745362945;18158.5460363492;18180.27278908829;6739.887918493492;7711;7790.883136752953;7886.605669716491;7904.407169462198;847.4915050969369;903.9683611073581;938.0057139432224;962.8748262725941;970.3110791338088;22317.888410558167;25560.360990975;29607.703922960925;30043.69956300409;30063.699363006526;858.9656413743505;1008.8587597736323;1086.1745073744012;1125.6285425809463;1142.2118738070706;14454.277286135652;14880.437554056885;14926.402943882124;14956.326965286587;15487;620273;641509;645418;646393;649867;55455.89088218213;55585.44414555802;55658.886822263325;56021.43978560162;56400;1192.791261529229;1304.2565737529517;1342.684469149749;1358.9116707413987;1365.7951307304184;28341;29008.854955725088;29253.85373073121;29618.851905740332;29788.702112978594;48037.799055023905;48745.268820967;49032;49057.75471122622;49086.50913490819;593284;593789;593809;594659.0267048638;596591;22698.546029079655;26252.474950500502;26610.334741631614;26641.86679066592;26654.733452666;45682.086358273315;46484.30273545832;46784.7660761694;47236.527634723214;48006.75996619995;32134;38883.306085921795;38884.80557597307;38928;39439.13992176524;39108.80445597868;39130.60869391383;39332;39558.80220598879;39738.60261397349;37353.81323093367;37560.812195939936;37705.43441848319;37829.43255851219;38052.61947380491;31451.685483144876;33149.17127071843;33822.66177338195;34659;34864.651353487156;25592.616110758725;25764.61353079743;25972.610410843474;25993.74006259989;26192 10:45:51 INFO - PROCESS | 1928 | |2;jquery.html;348.62871042339907;366.8514251728051;377.2586866806725;383.7160501229091;399.670272025579;19152.90423547882;19951.900240498795;20083.09626066827;20768.06543705533;20945.162193512242;11598.130140239495;11999.10006749493;12009.399530023507;12033.638990830268;13428.59714208573;5251.737413129338;5539;5539.196816461611;5545.861353466155;5548.140038294071;3894.162755007673;5410.621256512046;5791.07342825148;6069.908951365711;6115.205023346959;5587.1339942309005;5591.993441180586;6127.969360153215;6143.539234557404;6149.108379285001;5831.533477321826;6386.904196437057;6802.65986700663;6821.658917054127;6840.555363901355;5137.306463627438;5706.514946229576;5936.673482958436;5979.581429299951;5996;12963.805542916769;12969;13018.218906865619;13073.86926130736;21225.68161477579;1629;1806.6115785106133;1966.7344908437321;2107.9894600527055;2156.2453141400542;9853.50732463381;10039.04629060238;10150.796984060278;10438.164946804238;15674.981126226872;1617.8543931046145;1714.7513610526403;1869.2896699254197;1876.652819228447;1876.9155388007437;118625;125082;128836;130041.34979325137;130748.3462582681;11013.229073964745;11414.258073225214;11449.59926402572;11518.539258429737;11534.884651153548;14533.636659083608;14570.198639074953;14586.854131458762;14591.197484138474;14641.780373294408;8251.876221856677;8385.329173666092;8542.316614670812;8637.870431943526;8689.391742577958;8340.541270230196;8364.916350836414;8377.078521362646;8505;8531.360147988926;3895.4935858338235;3991.8004099794894;4482.350059241457;4577.130345234395;4630.58324750774;1367.4940272099386;1565.381674238692;1621.78366225331;1890.3383815664408;1949.2982526290525;919.2278486071598;920.5719340506696;959.2757468111671;980.6273616025865;1015.1320620869199;15931.964422312512;16694.247104054142;20110.79889201089;20328.491787705425;20362.592748144954;853.8292341531718;920.7007722490217;924.3575714878095;933.290699068699;934.1452570897552;10680.038796508352;11086.279391839505;11204;11232.719182020515;11246.606368777053;494158.5292073517;497504;503179;584887;590223;81146.18853811505;86751.13248867556;136221;141377;145637;932.4032619123766;950.3917492804611;977.3305285879053;978.295627148452;1019.9235057370727;15972.28124734413;19115.853048817116;19296.32462863793;19462.8053719461;19565.021748912783;53896.73051634717;54639.45360546344;54650;54977;55470.72264638812;76881.23118768964;82932;83366;83749;83846.74229886678;5727.39862314451;8099.190080991853;8125.67497300125;8229.753107406787;8236.40553225732;10580.629677961235;12687.492500300254;13041.478340866259;13069.542566010145;13219.735605287648;10863.619773307897;11037.944810275898;11103.389313587508;11403.372814494951;11505.25215861;9987.101160895554;10909.945450272699;10960.945195273973;10987.39569323695;11317.603883864025;11382.886171138514;11445.828312575151;11544.653660390202;11666.941665291619;11669.008134308679;7899.249571290718;7931.960340198262;8145.8370832584205;8154.184581541798;8271.669133234604;8158.1026087129185;10270.6405275815;10655;10749.032587067202;10944.960228778253 10:45:51 INFO - PROCESS | 1928 | |3;mootools.html;1520.8783297336215;1539.9153046582437;1556.9922150389245;1871.6631006418838;1906.6377388296223;7849.921500785004;8343.457675251111;8517.446365986207;8909.242714369278;8937.642494300228;8514.829703405929;8697.869531957025;8725.563721813916;8731.738047858558;8744.387892847502;2295.9770402296012;2332.9883350583223;2335.789778919897;2340.6723058771786;2348.9429756609507;2349.6710460535537;2355.9057637694473;2368.6447032945025;2394.6527753475734;2402.495475950044;2044.7035179898905;2337.2637619149914;2351.9294421167306;2373.2405630198346;2377.988110059447;2340.2979106268053;2341.426350544119;2352.0591763294647;2362.078789272185;2376.2039716694926;2340.0639744102496;2352.3413444235644;2354.7880690737743;2356.835021548475;2358.3986083548757;8634.78413039679;8657.783555411102;8672.262857657106;8689.3048556116;8714.041455439896;5126.512981266774;5134.05020071288;5157.633227194759;5166.819161329373;5282.102042652758;3462.8614855405754;3573.982130089359;3593.5328407307143;3641.5630124385243;3644.4533320001947;933.7852293972356;1056.7358160459885;1091.0398849012897;1105.6959336182533;1114.2868564118971;9396;11258.324500529994;11259.324440533594;11331.603393881174;11344.262622929484;4030.5163380393956;4096.877093687194;4616.6075883550275;4778.617710583215;4789.592884604847;9574.617015319449;9601.47191904452;9611.711648650551;9631.940486546475;9694.806103877883;4711.67018308722;5003.574696150795;5255.527002569787;5273.6572122811895;5322.946770532245;4198.5591512891315;4349.869503914888;4686.156491831503;4865.270209468608;4865.927011094836;3196.9360612788078;3884.349151610554;3939.487866577384;3964.4251583520227;3974.940375894364;1304.9021323400782;1307.5881097454271;1310.8492523359746;1319.4788058716754;1323.7087840675035;745.4818900863847;918.4948278446827;923.89375221849;933.9813203735887;939.5537119868145;5562.638428502134;5699.857503562444;5889.154091592577;5941.673207973603;5969.015112506475;446.187937952929;454.2913055633223;461.55229427455316;462.84494694277123;463.73567066771614;3846.5961074087377;3919.490466239428;4032;4035.959640403617;4048.959510404858;10177.084062434269;10640;10651.786964260671;10666.679999600025;10709.46452677363;10210.489475526194;10329.225308101772;10543.367397956146;10581.523831427605;10653.733656658645;8018.639161237758;8092.231238032377;8109.878351824727;8131.837363252702;8137.023557173059;5531.253280807039;5654.660720356627;5724.627899186539;5736.139579063007;5827.096799996008;9881.258905582254;9897.35667181631;9938.354006989523;9943.900560994587;9958.302918795614;9669.27480438984;10054.648087317202;10066.345687530287;10073.496325183858;10102.494875256354;6753.257141714505;7079.504434689522;7204.243554426919;7321.487495875238;7400.407967362706;8169.223923727236;8239.052508961306;8250.504969701837;8261.049979252462;8271.17288271168;7234.348908598251;7363.410927125924;7376.225496323023;7430.996815430064;7509.286617771303;6874.312568743086;6886;7078.079849619517;7087.68105435267;7251.854962900607;6657.1345725055435;6760.459163266829;6831.795046148623;6836.658167091526;6919.308069193041;4943.826966056171;4982.402111746614;5117.1300878851425;5173.991071740975;5234.18870075139;5023.522765337287;5026.346574945233;5052.368453943257;5170.3278573785165;5294.549963253166 10:45:51 INFO - PROCESS | 1928 | |4;prototype.html;265.7621428821205;272.14410678416385;306.6427611832214;316.12591185372435;316.2000139646695;5034.748262586874;5284.841454756363;5579.103104944318;5580.451931675687;5652.547796176306;5448.929406825401;5494.917576236359;5495.780168793243;5521.503064724174;5534.446555344463;3174.6666400111894;3987.1826275613503;4131.256373852705;4180.310248808943;4199.131386532994;4158.168366326747;4167.1040726243855;4195.895102622429;4203.222403855281;4221.915561688765;3133.3733253349305;3519.7184225261917;3646.9270614587695;3841.5966323536045;4192.2244384788755;3875.748076375027;4121.969507623095;4163.417121602978;4226.091390351089;4234.386014027964;3536;3551.2187318789825;3552.2948984487334;3552.8045957472327;3565.16218688608;5427.972860135714;5448.155535891943;5449.618526703132;5464.23500709901;5476.096444086721;3957.6042395760483;4517.390152329426;4770.594499467515;4807.75961201942;4812.783424745895;5100.081985242655;5147.897042059175;5779.803674603666;5873.207117039187;5936.46571808535;3935.7048221383225;3942.6451619354107;3944.349182384903;3960.861369852041;3969.126792105732;6020.819375418744;6155.569110162291;6207.534434917399;6231.594946328474;6290.119383286344;3496.0946284275133;3881.1849511602313;3918.4725851825415;3972.04082958198;4001.7598944063448;3536.6109727929907;4037.2329257440992;4046.2312160689376;4053.5541090480024;4066.7356621819486;4541.250693635513;4775.976120119377;4792.281157826354;4805.8077676892535;4818.614510839125;5417.566594672417;5770.615052387397;5933.732982015819;5934.82195534126;5982.192404025488;1148.471703016605;1377.2149874571398;1384.221275660771;1385.8267716535433;1398.5454727213696;787.1223585702015;798.3373799746197;832.3258160889699;842.9030661473886;850.4387104511117;4914.90170196594;5277;5571.693556854412;5573.860653483695;5596.86007849807;5833;5976.043832986788;6152.431130061806;6182.661362864093;6206.255249370104;5100.974495127575;5146.639735218499;5314.654547454403;5538.224648549206;5571.359293681197;3133.4046531159006;3167.2873603439043;3184.6496885342594;3187.3943950649304;3231.7557740269694;4982.153033984159;5325.147976323769;5734.795692904453;5909.758950620332;6009.699515024231;5183.550812176129;5788.710564471844;5809.8503553685405;5976.85057873548;6163.322034576193;5806.44838740319;6331.145295385064;6340.175777148941;6355.523335749745;6398.616083035033;5109.299444510984;5388.622796404214;5459.972700136474;5743.540516758566;6099.908501372394;5069.011542749276;6092.9086063709965;6100.657855271833;6134.803713275868;6148.631082135086;5770.278715160605;5881.705914704333;6107.358727333743;6146.8082343658925;6174;3812.447195156631;3986.4418981343215;3994.7403418777685;4007.998000499875;4022.537408198095;6106.389361063858;6109.908351374644;6170.50635949132;6224.502039836892;6225.470834979077;5072.721000345016;5428.321459817523;5635.633683810539;6020.608660437058;6038.426349496791;4981.875453113701;5549.417311182267;5670.121131224667;5907.2911250650195;6025.041861394977;4913.385826771653;5572.749226284745;5871.2073870027;5895.263092113486;5903.2325797647745;4989.650724449254;5484.506394424521;5658.830235092953;5903.0224874667465;5997.880042399041;5564.860878478071;5796.217510636179;5826.941730582809;5870.706464676835;5905.763769449174 10:45:51 INFO - PROCESS | 1928 | |5;yui.html;689.1798759476226;696.6238231355069;721.3688022979893;768.465916188249;827.1893544326559;14929.701405971875;15538.378464861393;15540.222988850555;15658.138802365866;15684.137372444511;14163.858361416384;14252.144871307702;14254.073485223453;14281.928590357034;14359.210243436606;661.35187516234;689.9551529150589;845.8562044452427;854.7093988044095;862.279996203168;853.1468531468531;853.7182729699184;854.1117238072397;854.8119413729;858.5063588436643;915.0986278515657;919.2370332623906;920.4799288402065;921.230772305126;922.0318665401302;906.3519583497798;917.2799352508282;918.1461241045824;918.4397517514311;920.682364584224;668.9998452313822;670.8188789026984;671.4527659957168;672.7140965089817;675.0313300413922;14666.266686665731;14670.853291467161;14672;14672.339744711513;14726;14331.853451723837;14392;14393.928030359886;14450.566483005527;14487.985840991134;10357.792844143074;11097.500612472377;11710.76578468426;11850;11925.573863891908;3634.327649384872;3758.9812050939845;3823.808809559511;3838.213166300902;3928;78550.60724696398;86581;89622.55188724014;113439.43280283546;115211;10051.145652619609;10683.786324273626;10738.838917416246;10820.296680715728;10899.182561307935;16822;20323.898380508002;20455.8977205116;20462.079206435745;20526;6064.393560643988;7041.4718896082995;7896.131425543185;7902.802429939183;8153.388495862715;4272.5086615039045;4343.283358245854;4358.869233922987;4365.628921541641;4376.102898905717;3497.4928635347733;3618.8552457901915;3619.7104231661406;3622.7826330420257;3752.3620984432714;581.2530897796312;690.6684791300204;710.6269208665492;711.2602892991334;721.2679130682361;619.6870580356891;652.1391762873056;674.7334802752885;677.8781117250958;682.4233522673333;9226.446413215095;9241;9386.202172815387;9527.714168574954;9640.855387169198;641.8555824939351;665.4177594604721;667.8697653957529;676.9526133170631;679.6126208061357;3650.3611867923537;4260.510041345285;4295.892602684896;4339.956600433955;4495.033567782943;88462.11537884538;109070.45464772625;143881.28059359637;144414;148409;120256.19615705771;128121;130012;130714;135834.96247556293;75256.62371688215;104640;105607.94392056135;106205;107099.46450267854;8386.496810191407;8847.911520884967;8905.287576993695;8938.597763100546;8950.910490895269;45974.77012614915;59922;61274.54901803878;61804;62469.68765156145;85346;90931.63354580678;92844;92884.53557732169;96154;10105.9494702526;10337.483125843828;11578.536858525902;11660.425842511153;11745.17783755129;12645.810312845131;12740.235585864877;12940.029497787515;13103.868961310265;13484.662883427993;12164.635060948185;12211.618734346717;12502.812457813323;12594.118411711092;12626.936865315614;11998;12156.817647735468;12277.324747138993;12396.194247374253;12514.937425312815;11925.761484770082;12158.75682486363;12304;12428.13003089775;12448.190867593577;9870.605175793175;10085.647002354883;10088.747781305527;10102.595896164365;10322.638707645196;8843.646254149762;9019.729408117766;9519.036584579124;9776.65486610757;10291.485425728833 10:45:51 INFO - PROCESS | 1928 | __end_tp_report 10:45:51 INFO - PROCESS | 1928 | __start_cc_report 10:45:51 INFO - PROCESS | 1928 | _x_x_mozilla_cycle_collect,393 10:45:51 INFO - PROCESS | 1928 | __end_cc_report 10:45:51 INFO - PROCESS | 1928 | __startTimestamp1473270351255__endTimestamp 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | ------- Summary: start ------- 10:45:51 INFO - PROCESS | 1928 | Number of tests: 6 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#0] dojo.html Cycles:165 Average:5777.13 Median:6072.36 stddev:650.29 (10.7%) stddev-sans-first:624.29 10:45:51 INFO - PROCESS | 1928 | Values: 3371.2 3396.5 3402.8 3407.1 3407.4 5665.2 5739.7 5750.2 5807.5 5859.5 5746.1 5772.2 5789.2 5867.9 5903.8 5166.5 5268.3 5272.1 5362.0 5364.6 5358.5 5373.8 5377.7 5412.9 5432.3 5190.1 5193.8 5241.0 5274.2 5275.6 5193.4 5219.9 5244.9 5288.3 5317.3 5240.8 5268.2 5288.0 5303.2 5318.0 5677.9 5684.7 5729.3 5751.0 5851.8 6151.0 6214.3 6234.0 6256.6 6262.5 6237.6 6242.3 6259.3 6266.8 6283.8 5747.0 5845.8 5901.9 5953.1 5987.7 6279.0 6336.4 6339.3 6340.9 6372.7 6204.2 6227.2 6235.3 6254.9 6259.8 6120.6 6170.0 6171.7 6187.0 6216.5 6120.9 6152.0 6163.0 6205.3 6225.8 6143.8 6209.7 6248.8 6257.0 6292.1 4253.4 4364.8 4529.1 4813.6 4818.4 4420.2 4457.9 4484.3 4497.8 4515.6 5774.9 5832.8 5874.6 5888.9 5918.1 6183.4 6184.6 6186.0 6240.0 6242.8 4962.3 5860.4 5875.6 5877.8 5890.5 5432.8 5457.7 5460.2 5479.5 5497.5 5406.4 6165.4 6262.6 6283.2 6369.2 6231.7 6236.4 6239.3 6260.9 6310.3 6320.0 6377.5 6398.7 6402.9 6403.0 6121.8 6141.3 6174.1 6248.0 6258.1 6184.9 6188.6 6225.3 6229.5 6249.7 5980.5 6143.5 6215.6 6234.8 6241.8 6072.4 6105.2 6121.0 6156.9 6205.0 5839.6 6083.6 6098.1 6113.8 6118.6 6158.9 6173.9 6200.0 6228.4 6257.9 5908.3 6012.2 6037.8 6057.2 6093.8 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#1] ext.html Cycles:180 Average:69854.55 Median:39586.51 stddev:154942.70 (391.4%) stddev-sans-first:155289.79 10:45:51 INFO - PROCESS | 1928 | Values: 471.2 483.5 504.0 534.3 539.4 37381.4 39553.0 39609.0 39678.4 39682.4 41156.0 41665.2 41693.7 41744.4 41986.6 9556.4 9703.8 9730.5 9732.8 9779.8 8432.9 9626.6 9700.9 9714.3 9716.9 9665.0 9693.9 9704.5 9734.2 9760.8 9654.4 9690.5 9699.6 9726.3 9728.5 9633.9 9719.5 9725.3 9732.9 9749.0 39412.4 39455.6 39564.0 39736.4 39883.0 3241.2 3318.7 3319.5 3335.1 3374.6 30087.8 30564.8 31011.8 31587.5 31732.4 3208.2 3236.0 3244.2 3271.8 3310.4 488028.0 493088.1 493392.0 493667.0 493873.0 51071.2 52932.5 56734.7 57737.4 58233.4 33433.8 34251.8 34502.8 60205.5 60527.4 15835.0 15962.3 15975.4 16003.6 16018.0 12350.3 12406.1 12477.4 12489.9 12505.8 18073.5 18088.5 18145.1 18158.5 18180.3 6739.9 7711.0 7790.9 7886.6 7904.4 847.5 904.0 938.0 962.9 970.3 22317.9 25560.4 29607.7 30043.7 30063.7 859.0 1008.9 1086.2 1125.6 1142.2 14454.3 14880.4 14926.4 14956.3 15487.0 620273.0 641509.0 645418.0 646393.0 649867.0 55455.9 55585.4 55658.9 56021.4 56400.0 1192.8 1304.3 1342.7 1358.9 1365.8 28341.0 29008.9 29253.9 29618.9 29788.7 48037.8 48745.3 49032.0 49057.8 49086.5 593284.0 593789.0 593809.0 594659.0 596591.0 22698.5 26252.5 26610.3 26641.9 26654.7 45682.1 46484.3 46784.8 47236.5 48006.8 32134.0 38883.3 38884.8 38928.0 39439.1 39108.8 39130.6 39332.0 39558.8 39738.6 37353.8 37560.8 37705.4 37829.4 38052.6 31451.7 33149.2 33822.7 34659.0 34864.7 25592.6 25764.6 25972.6 25993.7 26192.0 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#2] jquery.html Cycles:180 Average:32763.28 Median:11690.96 stddev:90345.03 (772.8%) stddev-sans-first:90565.69 10:45:51 INFO - PROCESS | 1928 | Values: 348.6 366.9 377.3 383.7 399.7 19152.9 19951.9 20083.1 20768.1 20945.2 11598.1 11999.1 12009.4 12033.6 13428.6 5251.7 5539.0 5539.2 5545.9 5548.1 3894.2 5410.6 5791.1 6069.9 6115.2 5587.1 5592.0 6128.0 6143.5 6149.1 5831.5 6386.9 6802.7 6821.7 6840.6 5137.3 5706.5 5936.7 5979.6 5996.0 12963.8 12969.0 13018.2 13073.9 21225.7 1629.0 1806.6 1966.7 2108.0 2156.2 9853.5 10039.0 10150.8 10438.2 15675.0 1617.9 1714.8 1869.3 1876.7 1876.9 118625.0 125082.0 128836.0 130041.3 130748.3 11013.2 11414.3 11449.6 11518.5 11534.9 14533.6 14570.2 14586.9 14591.2 14641.8 8251.9 8385.3 8542.3 8637.9 8689.4 8340.5 8364.9 8377.1 8505.0 8531.4 3895.5 3991.8 4482.4 4577.1 4630.6 1367.5 1565.4 1621.8 1890.3 1949.3 919.2 920.6 959.3 980.6 1015.1 15932.0 16694.2 20110.8 20328.5 20362.6 853.8 920.7 924.4 933.3 934.1 10680.0 11086.3 11204.0 11232.7 11246.6 494158.5 497504.0 503179.0 584887.0 590223.0 81146.2 86751.1 136221.0 141377.0 145637.0 932.4 950.4 977.3 978.3 1019.9 15972.3 19115.9 19296.3 19462.8 19565.0 53896.7 54639.5 54650.0 54977.0 55470.7 76881.2 82932.0 83366.0 83749.0 83846.7 5727.4 8099.2 8125.7 8229.8 8236.4 10580.6 12687.5 13041.5 13069.5 13219.7 10863.6 11037.9 11103.4 11403.4 11505.3 9987.1 10909.9 10960.9 10987.4 11317.6 11382.9 11445.8 11544.7 11666.9 11669.0 7899.2 7932.0 8145.8 8154.2 8271.7 8158.1 10270.6 10655.0 10749.0 10945.0 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#3] mootools.html Cycles:180 Average:5599.54 Median:5013.55 stddev:3132.14 (62.5%) stddev-sans-first:3125.93 10:45:51 INFO - PROCESS | 1928 | Values: 1520.9 1539.9 1557.0 1871.7 1906.6 7849.9 8343.5 8517.4 8909.2 8937.6 8514.8 8697.9 8725.6 8731.7 8744.4 2296.0 2333.0 2335.8 2340.7 2348.9 2349.7 2355.9 2368.6 2394.7 2402.5 2044.7 2337.3 2351.9 2373.2 2378.0 2340.3 2341.4 2352.1 2362.1 2376.2 2340.1 2352.3 2354.8 2356.8 2358.4 8634.8 8657.8 8672.3 8689.3 8714.0 5126.5 5134.1 5157.6 5166.8 5282.1 3462.9 3574.0 3593.5 3641.6 3644.5 933.8 1056.7 1091.0 1105.7 1114.3 9396.0 11258.3 11259.3 11331.6 11344.3 4030.5 4096.9 4616.6 4778.6 4789.6 9574.6 9601.5 9611.7 9631.9 9694.8 4711.7 5003.6 5255.5 5273.7 5322.9 4198.6 4349.9 4686.2 4865.3 4865.9 3196.9 3884.3 3939.5 3964.4 3974.9 1304.9 1307.6 1310.8 1319.5 1323.7 745.5 918.5 923.9 934.0 939.6 5562.6 5699.9 5889.2 5941.7 5969.0 446.2 454.3 461.6 462.8 463.7 3846.6 3919.5 4032.0 4036.0 4049.0 10177.1 10640.0 10651.8 10666.7 10709.5 10210.5 10329.2 10543.4 10581.5 10653.7 8018.6 8092.2 8109.9 8131.8 8137.0 5531.3 5654.7 5724.6 5736.1 5827.1 9881.3 9897.4 9938.4 9943.9 9958.3 9669.3 10054.6 10066.3 10073.5 10102.5 6753.3 7079.5 7204.2 7321.5 7400.4 8169.2 8239.1 8250.5 8261.0 8271.2 7234.3 7363.4 7376.2 7431.0 7509.3 6874.3 6886.0 7078.1 7087.7 7251.9 6657.1 6760.5 6831.8 6836.7 6919.3 4943.8 4982.4 5117.1 5174.0 5234.2 5023.5 5026.3 5052.4 5170.3 5294.5 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#4] prototype.html Cycles:180 Average:4734.45 Median:5428.15 stddev:1492.50 (27.5%) stddev-sans-first:1458.52 10:45:51 INFO - PROCESS | 1928 | Values: 265.8 272.1 306.6 316.1 316.2 5034.7 5284.8 5579.1 5580.5 5652.5 5448.9 5494.9 5495.8 5521.5 5534.4 3174.7 3987.2 4131.3 4180.3 4199.1 4158.2 4167.1 4195.9 4203.2 4221.9 3133.4 3519.7 3646.9 3841.6 4192.2 3875.7 4122.0 4163.4 4226.1 4234.4 3536.0 3551.2 3552.3 3552.8 3565.2 5428.0 5448.2 5449.6 5464.2 5476.1 3957.6 4517.4 4770.6 4807.8 4812.8 5100.1 5147.9 5779.8 5873.2 5936.5 3935.7 3942.6 3944.3 3960.9 3969.1 6020.8 6155.6 6207.5 6231.6 6290.1 3496.1 3881.2 3918.5 3972.0 4001.8 3536.6 4037.2 4046.2 4053.6 4066.7 4541.3 4776.0 4792.3 4805.8 4818.6 5417.6 5770.6 5933.7 5934.8 5982.2 1148.5 1377.2 1384.2 1385.8 1398.5 787.1 798.3 832.3 842.9 850.4 4914.9 5277.0 5571.7 5573.9 5596.9 5833.0 5976.0 6152.4 6182.7 6206.3 5101.0 5146.6 5314.7 5538.2 5571.4 3133.4 3167.3 3184.6 3187.4 3231.8 4982.2 5325.1 5734.8 5909.8 6009.7 5183.6 5788.7 5809.9 5976.9 6163.3 5806.4 6331.1 6340.2 6355.5 6398.6 5109.3 5388.6 5460.0 5743.5 6099.9 5069.0 6092.9 6100.7 6134.8 6148.6 5770.3 5881.7 6107.4 6146.8 6174.0 3812.4 3986.4 3994.7 4008.0 4022.5 6106.4 6109.9 6170.5 6224.5 6225.5 5072.7 5428.3 5635.6 6020.6 6038.4 4981.9 5549.4 5670.1 5907.3 6025.0 4913.4 5572.7 5871.2 5895.3 5903.2 4989.7 5484.5 5658.8 5903.0 5997.9 5564.9 5796.2 5826.9 5870.7 5905.8 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - PROCESS | 1928 | [#5] yui.html Cycles:180 Average:23107.00 Median:4094.26 stddev:36749.98 (897.6%) stddev-sans-first:36814.53 10:45:51 INFO - PROCESS | 1928 | Values: 689.2 696.6 721.4 768.5 827.2 14929.7 15538.4 15540.2 15658.1 15684.1 14163.9 14252.1 14254.1 14281.9 14359.2 661.4 690.0 845.9 854.7 862.3 853.1 853.7 854.1 854.8 858.5 915.1 919.2 920.5 921.2 922.0 906.4 917.3 918.1 918.4 920.7 669.0 670.8 671.5 672.7 675.0 14666.3 14670.9 14672.0 14672.3 14726.0 14331.9 14392.0 14393.9 14450.6 14488.0 10357.8 11097.5 11710.8 11850.0 11925.6 3634.3 3759.0 3823.8 3838.2 3928.0 78550.6 86581.0 89622.6 113439.4 115211.0 10051.1 10683.8 10738.8 10820.3 10899.2 16822.0 20323.9 20455.9 20462.1 20526.0 6064.4 7041.5 7896.1 7902.8 8153.4 4272.5 4343.3 4358.9 4365.6 4376.1 3497.5 3618.9 3619.7 3622.8 3752.4 581.3 690.7 710.6 711.3 721.3 619.7 652.1 674.7 677.9 682.4 9226.4 9241.0 9386.2 9527.7 9640.9 641.9 665.4 667.9 677.0 679.6 3650.4 4260.5 4295.9 4340.0 4495.0 88462.1 109070.5 143881.3 144414.0 148409.0 120256.2 128121.0 130012.0 130714.0 135835.0 75256.6 104640.0 105607.9 106205.0 107099.5 8386.5 8847.9 8905.3 8938.6 8950.9 45974.8 59922.0 61274.5 61804.0 62469.7 85346.0 90931.6 92844.0 92884.5 96154.0 10105.9 10337.5 11578.5 11660.4 11745.2 12645.8 12740.2 12940.0 13103.9 13484.7 12164.6 12211.6 12502.8 12594.1 12626.9 11998.0 12156.8 12277.3 12396.2 12514.9 11925.8 12158.8 12304.0 12428.1 12448.2 9870.6 10085.6 10088.7 10102.6 10322.6 8843.6 9019.7 9519.0 9776.7 10291.5 10:45:51 INFO - PROCESS | 1928 | -------- Summary: end -------- 10:45:51 INFO - PROCESS | 1928 | 10:45:51 INFO - TEST-INFO | 1928: exit 0 10:45:51 INFO - TEST-OK | dromaeo_css | took 1114088ms 10:45:51 INFO - TEST-START | kraken 10:45:51 INFO - operating with platform_type : mac_ 10:45:51 INFO - Initialising browser for kraken test... 10:45:51 INFO - TEST-INFO | started process 1937 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpGCEmMz/profile http://localhost:49438/getInfo.html) 10:45:54 INFO - PROCESS | 1937 | __metrics Screen width/height:1600/1200 10:45:54 INFO - PROCESS | 1937 | colorDepth:24 10:45:54 INFO - PROCESS | 1937 | Browser inner width/height: 1024/689 10:45:54 INFO - PROCESS | 1937 | __metrics 10:45:54 ERROR - PROCESS | 1937 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 2998: TypeError: this.tabs is undefined 10:45:55 INFO - TEST-INFO | 1937: exit 0 10:45:55 INFO - Browser initialized. 10:45:55 INFO - Running cycle 1/1 for kraken test... 10:45:55 INFO - TEST-INFO | started process 1939 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/T/tmpGCEmMz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:46:07 INFO - PROCESS | 1939 | RSS: Main: 244060160 10:46:07 INFO - PROCESS | 1939 | 10:46:10 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection) 10:46:11 INFO - PROCESS | 1939 | RSS: Main: 256946176 10:46:11 INFO - PROCESS | 1939 | 10:46:14 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-dft) 10:46:14 INFO - PROCESS | 1939 | RSS: Main: 265584640 10:46:14 INFO - PROCESS | 1939 | 10:46:18 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-fft) 10:46:19 INFO - PROCESS | 1939 | RSS: Main: 262414336 10:46:19 INFO - PROCESS | 1939 | 10:46:20 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator) 10:46:21 INFO - PROCESS | 1939 | RSS: Main: 264695808 10:46:21 INFO - PROCESS | 1939 | 10:46:23 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:46:24 INFO - PROCESS | 1939 | RSS: Main: 265355264 10:46:24 INFO - PROCESS | 1939 | 10:46:26 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom) 10:46:27 INFO - PROCESS | 1939 | RSS: Main: 266088448 10:46:27 INFO - PROCESS | 1939 | 10:46:30 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate) 10:46:31 INFO - PROCESS | 1939 | RSS: Main: 266428416 10:46:31 INFO - PROCESS | 1939 | 10:46:34 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial) 10:46:34 INFO - PROCESS | 1939 | RSS: Main: 266694656 10:46:34 INFO - PROCESS | 1939 | 10:46:36 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:46:36 INFO - PROCESS | 1939 | RSS: Main: 265629696 10:46:36 INFO - PROCESS | 1939 | 10:46:38 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:46:39 INFO - PROCESS | 1939 | RSS: Main: 265601024 10:46:39 INFO - PROCESS | 1939 | 10:46:41 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:46:42 INFO - PROCESS | 1939 | RSS: Main: 265940992 10:46:42 INFO - PROCESS | 1939 | 10:46:45 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:46:46 INFO - PROCESS | 1939 | RSS: Main: 266313728 10:46:46 INFO - PROCESS | 1939 | 10:46:49 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:46:49 INFO - PROCESS | 1939 | RSS: Main: 266219520 10:46:49 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | Cycle 1(1): loaded http://localhost:49438/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49438/tests/kraken/driver.html?testName=ai-astar) 10:46:51 INFO - PROCESS | 1939 | RSS: Main: 268365824 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | __start_tp_report 10:46:51 INFO - PROCESS | 1939 | _x_x_mozilla_page_load 10:46:51 INFO - PROCESS | 1939 | _x_x_mozilla_page_load_details 10:46:51 INFO - PROCESS | 1939 | |i|pagename|runs| 10:46:51 INFO - PROCESS | 1939 | |0;ai-astar;109;118;119;115;121;147;168;119;119;132 10:46:51 INFO - PROCESS | 1939 | |1;audio-beat-detection;142;116;167;194;133;129;129;135;198;117 10:46:51 INFO - PROCESS | 1939 | |2;audio-dft;226;237;267;272;197;197;201;195;194;216 10:46:51 INFO - PROCESS | 1939 | |3;audio-fft;75;82;77;149;60;60;60;57;130;62 10:46:51 INFO - PROCESS | 1939 | |4;audio-oscillator;70;71;127;72;62;73;109;66;71;114 10:46:51 INFO - PROCESS | 1939 | |5;imaging-gaussian-blur;89;94;88;87;91;86;90;107;90;86 10:46:51 INFO - PROCESS | 1939 | |6;imaging-darkroom;99;100;100;133;102;100;100;101;100;100 10:46:51 INFO - PROCESS | 1939 | |7;imaging-desaturate;94;98;141;144;91;92;93;92;91;91 10:46:51 INFO - PROCESS | 1939 | |8;json-parse-financial;56;56;56;59;58;58;58;57;57;56 10:46:51 INFO - PROCESS | 1939 | |9;json-stringify-tinderbox;67;70;81;94;72;66;96;66;100;99 10:46:51 INFO - PROCESS | 1939 | |10;stanford-crypto-aes;86;90;92;88;90;88;89;86;90;113 10:46:51 INFO - PROCESS | 1939 | |11;stanford-crypto-ccm;141;148;163;164;146;136;129;134;191;136 10:46:51 INFO - PROCESS | 1939 | |12;stanford-crypto-pbkdf2;173;173;170;176;172;162;160;159;227;173 10:46:51 INFO - PROCESS | 1939 | |13;stanford-crypto-sha256-iterative;69;71;78;75;83;73;69;71;69;67 10:46:51 INFO - PROCESS | 1939 | __end_tp_report 10:46:51 INFO - PROCESS | 1939 | __start_cc_report 10:46:51 INFO - PROCESS | 1939 | _x_x_mozilla_cycle_collect,969 10:46:51 INFO - PROCESS | 1939 | __end_cc_report 10:46:51 INFO - PROCESS | 1939 | __startTimestamp1473270411683__endTimestamp 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | ------- Summary: start ------- 10:46:51 INFO - PROCESS | 1939 | Number of tests: 14 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#0] ai-astar Cycles:10 Average:126.70 Median:120.00 stddev:17.90 (14.9%) stddev-sans-first:17.80 10:46:51 INFO - PROCESS | 1939 | Values: 109.0 118.0 119.0 115.0 121.0 147.0 168.0 119.0 119.0 132.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#1] audio-beat-detection Cycles:10 Average:146.00 Median:138.50 stddev:29.95 (21.6%) stddev-sans-first:31.73 10:46:51 INFO - PROCESS | 1939 | Values: 142.0 116.0 167.0 194.0 133.0 129.0 129.0 135.0 198.0 117.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#2] audio-dft Cycles:10 Average:220.20 Median:221.00 stddev:29.77 (13.5%) stddev-sans-first:31.50 10:46:51 INFO - PROCESS | 1939 | Values: 226.0 237.0 267.0 272.0 197.0 197.0 201.0 195.0 194.0 216.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#3] audio-fft Cycles:10 Average:81.20 Median:61.00 stddev:32.21 (52.8%) stddev-sans-first:34.09 10:46:51 INFO - PROCESS | 1939 | Values: 75.0 82.0 77.0 149.0 60.0 60.0 60.0 57.0 130.0 62.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#4] audio-oscillator Cycles:10 Average:83.50 Median:70.50 stddev:23.52 (33.4%) stddev-sans-first:24.43 10:46:51 INFO - PROCESS | 1939 | Values: 70.0 71.0 127.0 72.0 62.0 73.0 109.0 66.0 71.0 114.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#5] imaging-gaussian-blur Cycles:10 Average:90.80 Median:89.50 stddev:6.20 (6.9%) stddev-sans-first:6.54 10:46:51 INFO - PROCESS | 1939 | Values: 89.0 94.0 88.0 87.0 91.0 86.0 90.0 107.0 90.0 86.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#6] imaging-darkroom Cycles:10 Average:103.50 Median:100.50 stddev:10.39 (10.3%) stddev-sans-first:10.90 10:46:51 INFO - PROCESS | 1939 | Values: 99.0 100.0 100.0 133.0 102.0 100.0 100.0 101.0 100.0 100.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#7] imaging-desaturate Cycles:10 Average:102.70 Median:92.00 stddev:21.09 (22.9%) stddev-sans-first:22.14 10:46:51 INFO - PROCESS | 1939 | Values: 94.0 98.0 141.0 144.0 91.0 92.0 93.0 92.0 91.0 91.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#8] json-parse-financial Cycles:10 Average:57.10 Median:57.50 stddev:1.10 (1.9%) stddev-sans-first:1.09 10:46:51 INFO - PROCESS | 1939 | Values: 56.0 56.0 56.0 59.0 58.0 58.0 58.0 57.0 57.0 56.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#9] json-stringify-tinderbox Cycles:10 Average:81.10 Median:76.50 stddev:14.63 (19.1%) stddev-sans-first:14.60 10:46:51 INFO - PROCESS | 1939 | Values: 67.0 70.0 81.0 94.0 72.0 66.0 96.0 66.0 100.0 99.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#10] stanford-crypto-aes Cycles:10 Average:91.20 Median:89.50 stddev:7.89 (8.8%) stddev-sans-first:8.14 10:46:51 INFO - PROCESS | 1939 | Values: 86.0 90.0 92.0 88.0 90.0 88.0 89.0 86.0 90.0 113.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#11] stanford-crypto-ccm Cycles:10 Average:148.80 Median:147.00 stddev:18.92 (12.9%) stddev-sans-first:19.86 10:46:51 INFO - PROCESS | 1939 | Values: 141.0 148.0 163.0 164.0 146.0 136.0 129.0 134.0 191.0 136.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:174.50 Median:173.00 stddev:19.43 (11.2%) stddev-sans-first:20.60 10:46:51 INFO - PROCESS | 1939 | Values: 173.0 173.0 170.0 176.0 172.0 162.0 160.0 159.0 227.0 173.0 10:46:51 INFO - PROCESS | 1939 | 10:46:51 INFO - PROCESS | 1939 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.50 Median:72.00 stddev:4.93 (6.8%) stddev-sans-first:5.06 10:46:51 INFO - PROCESS | 1939 | Values: 69.0 71.0 78.0 75.0 83.0 73.0 69.0 71.0 69.0 67.0 10:46:51 INFO - PROCESS | 1939 | -------- Summary: end -------- 10:46:51 INFO - PROCESS | 1939 | 10:46:52 INFO - TEST-INFO | 1939: exit 0 10:46:52 INFO - TEST-OK | kraken | took 60724ms 10:46:52 INFO - SUITE-END | took 1174s 10:46:52 INFO - Completed test suite (00:19:35) 10:46:52 INFO - javascript benchmark 10:46:52 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7245.682563563426, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3371.1740623547234, 3396.4565669492886, 3402.8468718907648, 3407.148212946763, 3407.352603005429, 5665.23519324891, 5739.655620662763, 5750.166225897247, 5807.535397168227, 5859.531237501001, 5746.109353050273, 5772.191893134954, 5789.160571717109, 5867.88264234715, 5903.793367232148, 5166.457521960197, 5268.341457317835, 5272.1300985337375, 5361.973190134064, 5364.570834333244, 5358.490943360374, 5373.7581808818695, 5377.65045272058, 5412.9458705412835, 5432.348118225798, 5190.091733946582, 5193.766280517386, 5240.951809638088, 5274.156135018378, 5275.57795376369, 5193.350831146106, 5219.921701174486, 5244.947550524522, 5288.286081379003, 5317.255584218213, 5240.842774716764, 5268.236105764643, 5288.048151332759, 5303.15149576066, 5317.973410132925, 5677.886442271131, 5684.744186511617, 5729.312482502127, 5751.0, 5851.824445266649, 6150.96924515379, 6214.25428948529, 6233.968830155865, 6256.562040657156, 6262.467690246334, 6237.625742455467, 6242.313345532033, 6259.311475737665, 6266.780662676784, 6283.8114856554375, 5747.0, 5845.766169353264, 5901.940980590225, 5953.107033944856, 5987.670678112746, 6279.0095420226835, 6336.429721325103, 6339.302676705559, 6340.904886426707, 6372.681365931685, 6204.224471941007, 6227.221597300338, 6235.282942461584, 6254.906176407358, 6259.78090766821, 6120.569035656853, 6169.96915015431, 6171.660558669227, 6186.969065154645, 6216.502679785606, 6120.873812997669, 6151.954167791486, 6162.969185154136, 6205.348438413992, 6225.782097626561, 6143.84640383994, 6209.720562574695, 6248.781292654826, 6257.0, 6292.087647291117, 4253.383259427365, 4364.8472303469225, 4529.139463501924, 4813.638977076734, 4818.445878723922, 4420.226460369422, 4457.910841783146, 4484.327350897392, 4497.752623605733, 4515.593596576258, 5774.942250577525, 5832.795852145155, 5874.618149820248, 5888.852778680482, 5918.141869428995, 6183.412575805291, 6184.5980011299125, 6186.0, 6240.032794916795, 6242.750289988349, 4962.305277261186, 5860.384659610765, 5875.588708790429, 5877.764889404461, 5890.4698577128265, 5432.809851655173, 5457.699826509501, 5460.180972854024, 5479.53423958968, 5497.505224529811, 5406.351237851483, 6165.383461653799, 6262.561620686508, 6283.151774510383, 6369.235691716931, 6231.719572619334, 6236.407541283571, 6239.313675495782, 6260.874782504416, 6310.337414571588, 6319.988801791691, 6377.489800816016, 6398.680065996775, 6402.93597064042, 6403.007533832263, 6121.8469538260115, 6141.301904043321, 6174.073888916699, 6248.0003199487865, 6258.061290806233, 6184.938150618616, 6188.566800323934, 6225.28409232944, 6229.470495007975, 6249.718762655601, 5980.5215582754145, 6143.508519318354, 6215.627062376272, 6234.812955611339, 6241.812745617639, 6072.362401947732, 6105.186826067751, 6121.0, 6156.876862462636, 6205.0, 5839.562032847469, 6083.574149809471, 6098.085287206777, 6113.816585502442, 6118.571699980958, 6158.938410616016, 6173.876522469435, 6199.969000154971, 6228.439440450381, 6257.937420625735, 5908.261467316585, 6012.248468941382, 6037.818865434044, 6057.151998720271, 6093.75624974996], "value": 5734.715022574146, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [471.1636844600833, 483.5044079818188, 503.99201596806387, 534.2547146730311, 539.4470667565746, 37381.43927841084, 39553.0, 39609.0, 39678.404823927594, 39682.40476392856, 41156.0, 41665.16669666605, 41693.74918752442, 41744.373834392434, 41986.58013419865, 9556.42661440314, 9703.75740606487, 9730.464824434654, 9732.805343893118, 9779.804403911918, 8432.873506897402, 9626.566804493814, 9700.902990970071, 9714.31999760017, 9716.902830971705, 9665.0, 9693.903060969405, 9704.514774261293, 9734.17259532937, 9760.804783904317, 9654.372465789736, 9690.51547422626, 9699.563519641668, 9726.319157658967, 9728.464934428604, 9633.855492167622, 9719.542068689683, 9725.319227654067, 9732.854007189828, 9748.976357482505, 39412.40881386781, 39455.605443945766, 39564.01089972745, 39736.40395394072, 39883.00292492711, 3241.222106694376, 3318.7179089777396, 3319.535265062893, 3335.116194208537, 3374.645662205482, 30087.849560752056, 30564.847175763978, 31011.844940775376, 31587.52618710721, 31732.365352693047, 3208.215579349582, 3236.0, 3244.172735952317, 3271.7873338233176, 3310.4372256716174, 488028.0, 493088.0691193078, 493392.0, 493667.0, 493873.0, 51071.23393149106, 52932.47067529352, 56734.71632641811, 57737.422625774045, 58233.41766582365, 33433.832830836174, 34251.82874085614, 34502.82748586241, 60205.49486262791, 60527.394726053055, 15834.970726902715, 15962.281697323648, 15975.440859569859, 16003.599910002344, 16018.0, 12350.320732359627, 12406.069544784177, 12477.438515266835, 12489.938355239907, 12505.811947864939, 18073.45779626613, 18088.457346279632, 18145.092745362945, 18158.5460363492, 18180.27278908829, 6739.887918493492, 7711.0, 7790.883136752953, 7886.605669716491, 7904.407169462198, 847.4915050969369, 903.9683611073581, 938.0057139432224, 962.8748262725941, 970.3110791338088, 22317.888410558167, 25560.360990975, 29607.703922960925, 30043.69956300409, 30063.699363006526, 858.9656413743505, 1008.8587597736323, 1086.1745073744012, 1125.6285425809463, 1142.2118738070706, 14454.277286135652, 14880.437554056885, 14926.402943882124, 14956.326965286587, 15487.0, 620273.0, 641509.0, 645418.0, 646393.0, 649867.0, 55455.89088218213, 55585.44414555802, 55658.886822263325, 56021.43978560162, 56400.0, 1192.791261529229, 1304.2565737529517, 1342.684469149749, 1358.9116707413987, 1365.7951307304184, 28341.0, 29008.854955725088, 29253.85373073121, 29618.851905740332, 29788.702112978594, 48037.799055023905, 48745.268820967, 49032.0, 49057.75471122622, 49086.50913490819, 593284.0, 593789.0, 593809.0, 594659.0267048638, 596591.0, 22698.546029079655, 26252.474950500502, 26610.334741631614, 26641.86679066592, 26654.733452666, 45682.086358273315, 46484.30273545832, 46784.7660761694, 47236.527634723214, 48006.75996619995, 32134.0, 38883.306085921795, 38884.80557597307, 38928.0, 39439.13992176524, 39108.80445597868, 39130.60869391383, 39332.0, 39558.80220598879, 39738.60261397349, 37353.81323093367, 37560.812195939936, 37705.43441848319, 37829.43255851219, 38052.61947380491, 31451.685483144876, 33149.17127071843, 33822.66177338195, 34659.0, 34864.651353487156, 25592.616110758725, 25764.61353079743, 25972.610410843474, 25993.74006259989, 26192.0], "value": 19515.937885782078, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [348.62871042339907, 366.8514251728051, 377.2586866806725, 383.7160501229091, 399.670272025579, 19152.90423547882, 19951.900240498795, 20083.09626066827, 20768.06543705533, 20945.162193512242, 11598.130140239495, 11999.10006749493, 12009.399530023507, 12033.638990830268, 13428.59714208573, 5251.737413129338, 5539.0, 5539.196816461611, 5545.861353466155, 5548.140038294071, 3894.162755007673, 5410.621256512046, 5791.07342825148, 6069.908951365711, 6115.205023346959, 5587.1339942309005, 5591.993441180586, 6127.969360153215, 6143.539234557404, 6149.108379285001, 5831.533477321826, 6386.904196437057, 6802.65986700663, 6821.658917054127, 6840.555363901355, 5137.306463627438, 5706.514946229576, 5936.673482958436, 5979.581429299951, 5996.0, 12963.805542916769, 12969.0, 13018.218906865619, 13073.86926130736, 21225.68161477579, 1629.0, 1806.6115785106133, 1966.7344908437321, 2107.9894600527055, 2156.2453141400542, 9853.50732463381, 10039.04629060238, 10150.796984060278, 10438.164946804238, 15674.981126226872, 1617.8543931046145, 1714.7513610526403, 1869.2896699254197, 1876.652819228447, 1876.9155388007437, 118625.0, 125082.0, 128836.0, 130041.34979325137, 130748.3462582681, 11013.229073964745, 11414.258073225214, 11449.59926402572, 11518.539258429737, 11534.884651153548, 14533.636659083608, 14570.198639074953, 14586.854131458762, 14591.197484138474, 14641.780373294408, 8251.876221856677, 8385.329173666092, 8542.316614670812, 8637.870431943526, 8689.391742577958, 8340.541270230196, 8364.916350836414, 8377.078521362646, 8505.0, 8531.360147988926, 3895.4935858338235, 3991.8004099794894, 4482.350059241457, 4577.130345234395, 4630.58324750774, 1367.4940272099386, 1565.381674238692, 1621.78366225331, 1890.3383815664408, 1949.2982526290525, 919.2278486071598, 920.5719340506696, 959.2757468111671, 980.6273616025865, 1015.1320620869199, 15931.964422312512, 16694.247104054142, 20110.79889201089, 20328.491787705425, 20362.592748144954, 853.8292341531718, 920.7007722490217, 924.3575714878095, 933.290699068699, 934.1452570897552, 10680.038796508352, 11086.279391839505, 11204.0, 11232.719182020515, 11246.606368777053, 494158.5292073517, 497504.0, 503179.0, 584887.0, 590223.0, 81146.18853811505, 86751.13248867556, 136221.0, 141377.0, 145637.0, 932.4032619123766, 950.3917492804611, 977.3305285879053, 978.295627148452, 1019.9235057370727, 15972.28124734413, 19115.853048817116, 19296.32462863793, 19462.8053719461, 19565.021748912783, 53896.73051634717, 54639.45360546344, 54650.0, 54977.0, 55470.72264638812, 76881.23118768964, 82932.0, 83366.0, 83749.0, 83846.74229886678, 5727.39862314451, 8099.190080991853, 8125.67497300125, 8229.753107406787, 8236.40553225732, 10580.629677961235, 12687.492500300254, 13041.478340866259, 13069.542566010145, 13219.735605287648, 10863.619773307897, 11037.944810275898, 11103.389313587508, 11403.372814494951, 11505.25215861, 9987.101160895554, 10909.945450272699, 10960.945195273973, 10987.39569323695, 11317.603883864025, 11382.886171138514, 11445.828312575151, 11544.653660390202, 11666.941665291619, 11669.008134308679, 7899.249571290718, 7931.960340198262, 8145.8370832584205, 8154.184581541798, 8271.669133234604, 8158.1026087129185, 10270.6405275815, 10655.0, 10749.032587067202, 10944.960228778253], "value": 9143.285851854296, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1520.8783297336215, 1539.9153046582437, 1556.9922150389245, 1871.6631006418838, 1906.6377388296223, 7849.921500785004, 8343.457675251111, 8517.446365986207, 8909.242714369278, 8937.642494300228, 8514.829703405929, 8697.869531957025, 8725.563721813916, 8731.738047858558, 8744.387892847502, 2295.9770402296012, 2332.9883350583223, 2335.789778919897, 2340.6723058771786, 2348.9429756609507, 2349.6710460535537, 2355.9057637694473, 2368.6447032945025, 2394.6527753475734, 2402.495475950044, 2044.7035179898905, 2337.2637619149914, 2351.9294421167306, 2373.2405630198346, 2377.988110059447, 2340.2979106268053, 2341.426350544119, 2352.0591763294647, 2362.078789272185, 2376.2039716694926, 2340.0639744102496, 2352.3413444235644, 2354.7880690737743, 2356.835021548475, 2358.3986083548757, 8634.78413039679, 8657.783555411102, 8672.262857657106, 8689.3048556116, 8714.041455439896, 5126.512981266774, 5134.05020071288, 5157.633227194759, 5166.819161329373, 5282.102042652758, 3462.8614855405754, 3573.982130089359, 3593.5328407307143, 3641.5630124385243, 3644.4533320001947, 933.7852293972356, 1056.7358160459885, 1091.0398849012897, 1105.6959336182533, 1114.2868564118971, 9396.0, 11258.324500529994, 11259.324440533594, 11331.603393881174, 11344.262622929484, 4030.5163380393956, 4096.877093687194, 4616.6075883550275, 4778.617710583215, 4789.592884604847, 9574.617015319449, 9601.47191904452, 9611.711648650551, 9631.940486546475, 9694.806103877883, 4711.67018308722, 5003.574696150795, 5255.527002569787, 5273.6572122811895, 5322.946770532245, 4198.5591512891315, 4349.869503914888, 4686.156491831503, 4865.270209468608, 4865.927011094836, 3196.9360612788078, 3884.349151610554, 3939.487866577384, 3964.4251583520227, 3974.940375894364, 1304.9021323400782, 1307.5881097454271, 1310.8492523359746, 1319.4788058716754, 1323.7087840675035, 745.4818900863847, 918.4948278446827, 923.89375221849, 933.9813203735887, 939.5537119868145, 5562.638428502134, 5699.857503562444, 5889.154091592577, 5941.673207973603, 5969.015112506475, 446.187937952929, 454.2913055633223, 461.55229427455316, 462.84494694277123, 463.73567066771614, 3846.5961074087377, 3919.490466239428, 4032.0, 4035.959640403617, 4048.959510404858, 10177.084062434269, 10640.0, 10651.786964260671, 10666.679999600025, 10709.46452677363, 10210.489475526194, 10329.225308101772, 10543.367397956146, 10581.523831427605, 10653.733656658645, 8018.639161237758, 8092.231238032377, 8109.878351824727, 8131.837363252702, 8137.023557173059, 5531.253280807039, 5654.660720356627, 5724.627899186539, 5736.139579063007, 5827.096799996008, 9881.258905582254, 9897.35667181631, 9938.354006989523, 9943.900560994587, 9958.302918795614, 9669.27480438984, 10054.648087317202, 10066.345687530287, 10073.496325183858, 10102.494875256354, 6753.257141714505, 7079.504434689522, 7204.243554426919, 7321.487495875238, 7400.407967362706, 8169.223923727236, 8239.052508961306, 8250.504969701837, 8261.049979252462, 8271.17288271168, 7234.348908598251, 7363.410927125924, 7376.225496323023, 7430.996815430064, 7509.286617771303, 6874.312568743086, 6886.0, 7078.079849619517, 7087.68105435267, 7251.854962900607, 6657.1345725055435, 6760.459163266829, 6831.795046148623, 6836.658167091526, 6919.308069193041, 4943.826966056171, 4982.402111746614, 5117.1300878851425, 5173.991071740975, 5234.18870075139, 5023.522765337287, 5026.346574945233, 5052.368453943257, 5170.3278573785165, 5294.549963253166], "value": 4445.897553502529, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [265.7621428821205, 272.14410678416385, 306.6427611832214, 316.12591185372435, 316.2000139646695, 5034.748262586874, 5284.841454756363, 5579.103104944318, 5580.451931675687, 5652.547796176306, 5448.929406825401, 5494.917576236359, 5495.780168793243, 5521.503064724174, 5534.446555344463, 3174.6666400111894, 3987.1826275613503, 4131.256373852705, 4180.310248808943, 4199.131386532994, 4158.168366326747, 4167.1040726243855, 4195.895102622429, 4203.222403855281, 4221.915561688765, 3133.3733253349305, 3519.7184225261917, 3646.9270614587695, 3841.5966323536045, 4192.2244384788755, 3875.748076375027, 4121.969507623095, 4163.417121602978, 4226.091390351089, 4234.386014027964, 3536.0, 3551.2187318789825, 3552.2948984487334, 3552.8045957472327, 3565.16218688608, 5427.972860135714, 5448.155535891943, 5449.618526703132, 5464.23500709901, 5476.096444086721, 3957.6042395760483, 4517.390152329426, 4770.594499467515, 4807.75961201942, 4812.783424745895, 5100.081985242655, 5147.897042059175, 5779.803674603666, 5873.207117039187, 5936.46571808535, 3935.7048221383225, 3942.6451619354107, 3944.349182384903, 3960.861369852041, 3969.126792105732, 6020.819375418744, 6155.569110162291, 6207.534434917399, 6231.594946328474, 6290.119383286344, 3496.0946284275133, 3881.1849511602313, 3918.4725851825415, 3972.04082958198, 4001.7598944063448, 3536.6109727929907, 4037.2329257440992, 4046.2312160689376, 4053.5541090480024, 4066.7356621819486, 4541.250693635513, 4775.976120119377, 4792.281157826354, 4805.8077676892535, 4818.614510839125, 5417.566594672417, 5770.615052387397, 5933.732982015819, 5934.82195534126, 5982.192404025488, 1148.471703016605, 1377.2149874571398, 1384.221275660771, 1385.8267716535433, 1398.5454727213696, 787.1223585702015, 798.3373799746197, 832.3258160889699, 842.9030661473886, 850.4387104511117, 4914.90170196594, 5277.0, 5571.693556854412, 5573.860653483695, 5596.86007849807, 5833.0, 5976.043832986788, 6152.431130061806, 6182.661362864093, 6206.255249370104, 5100.974495127575, 5146.639735218499, 5314.654547454403, 5538.224648549206, 5571.359293681197, 3133.4046531159006, 3167.2873603439043, 3184.6496885342594, 3187.3943950649304, 3231.7557740269694, 4982.153033984159, 5325.147976323769, 5734.795692904453, 5909.758950620332, 6009.699515024231, 5183.550812176129, 5788.710564471844, 5809.8503553685405, 5976.85057873548, 6163.322034576193, 5806.44838740319, 6331.145295385064, 6340.175777148941, 6355.523335749745, 6398.616083035033, 5109.299444510984, 5388.622796404214, 5459.972700136474, 5743.540516758566, 6099.908501372394, 5069.011542749276, 6092.9086063709965, 6100.657855271833, 6134.803713275868, 6148.631082135086, 5770.278715160605, 5881.705914704333, 6107.358727333743, 6146.8082343658925, 6174.0, 3812.447195156631, 3986.4418981343215, 3994.7403418777685, 4007.998000499875, 4022.537408198095, 6106.389361063858, 6109.908351374644, 6170.50635949132, 6224.502039836892, 6225.470834979077, 5072.721000345016, 5428.321459817523, 5635.633683810539, 6020.608660437058, 6038.426349496791, 4981.875453113701, 5549.417311182267, 5670.121131224667, 5907.2911250650195, 6025.041861394977, 4913.385826771653, 5572.749226284745, 5871.2073870027, 5895.263092113486, 5903.2325797647745, 4989.650724449254, 5484.506394424521, 5658.830235092953, 5903.0224874667465, 5997.880042399041, 5564.860878478071, 5796.217510636179, 5826.941730582809, 5870.706464676835, 5905.763769449174], "value": 4240.242665004932, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [689.1798759476226, 696.6238231355069, 721.3688022979893, 768.465916188249, 827.1893544326559, 14929.701405971875, 15538.378464861393, 15540.222988850555, 15658.138802365866, 15684.137372444511, 14163.858361416384, 14252.144871307702, 14254.073485223453, 14281.928590357034, 14359.210243436606, 661.35187516234, 689.9551529150589, 845.8562044452427, 854.7093988044095, 862.279996203168, 853.1468531468531, 853.7182729699184, 854.1117238072397, 854.8119413729, 858.5063588436643, 915.0986278515657, 919.2370332623906, 920.4799288402065, 921.230772305126, 922.0318665401302, 906.3519583497798, 917.2799352508282, 918.1461241045824, 918.4397517514311, 920.682364584224, 668.9998452313822, 670.8188789026984, 671.4527659957168, 672.7140965089817, 675.0313300413922, 14666.266686665731, 14670.853291467161, 14672.0, 14672.339744711513, 14726.0, 14331.853451723837, 14392.0, 14393.928030359886, 14450.566483005527, 14487.985840991134, 10357.792844143074, 11097.500612472377, 11710.76578468426, 11850.0, 11925.573863891908, 3634.327649384872, 3758.9812050939845, 3823.808809559511, 3838.213166300902, 3928.0, 78550.60724696398, 86581.0, 89622.55188724014, 113439.43280283546, 115211.0, 10051.145652619609, 10683.786324273626, 10738.838917416246, 10820.296680715728, 10899.182561307935, 16822.0, 20323.898380508002, 20455.8977205116, 20462.079206435745, 20526.0, 6064.393560643988, 7041.4718896082995, 7896.131425543185, 7902.802429939183, 8153.388495862715, 4272.5086615039045, 4343.283358245854, 4358.869233922987, 4365.628921541641, 4376.102898905717, 3497.4928635347733, 3618.8552457901915, 3619.7104231661406, 3622.7826330420257, 3752.3620984432714, 581.2530897796312, 690.6684791300204, 710.6269208665492, 711.2602892991334, 721.2679130682361, 619.6870580356891, 652.1391762873056, 674.7334802752885, 677.8781117250958, 682.4233522673333, 9226.446413215095, 9241.0, 9386.202172815387, 9527.714168574954, 9640.855387169198, 641.8555824939351, 665.4177594604721, 667.8697653957529, 676.9526133170631, 679.6126208061357, 3650.3611867923537, 4260.510041345285, 4295.892602684896, 4339.956600433955, 4495.033567782943, 88462.11537884538, 109070.45464772625, 143881.28059359637, 144414.0, 148409.0, 120256.19615705771, 128121.0, 130012.0, 130714.0, 135834.96247556293, 75256.62371688215, 104640.0, 105607.94392056135, 106205.0, 107099.46450267854, 8386.496810191407, 8847.911520884967, 8905.287576993695, 8938.597763100546, 8950.910490895269, 45974.77012614915, 59922.0, 61274.54901803878, 61804.0, 62469.68765156145, 85346.0, 90931.63354580678, 92844.0, 92884.53557732169, 96154.0, 10105.9494702526, 10337.483125843828, 11578.536858525902, 11660.425842511153, 11745.17783755129, 12645.810312845131, 12740.235585864877, 12940.029497787515, 13103.868961310265, 13484.662883427993, 12164.635060948185, 12211.618734346717, 12502.812457813323, 12594.118411711092, 12626.936865315614, 11998.0, 12156.817647735468, 12277.324747138993, 12396.194247374253, 12514.937425312815, 11925.761484770082, 12158.75682486363, 12304.0, 12428.13003089775, 12448.190867593577, 9870.605175793175, 10085.647002354883, 10088.747781305527, 10102.595896164365, 10322.638707645196, 8843.646254149762, 9019.729408117766, 9519.036584579124, 9776.65486610757, 10291.485425728833], "value": 7500.293208629993, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.8, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [109.0, 118.0, 119.0, 115.0, 121.0, 147.0, 168.0, 119.0, 119.0, 132.0], "value": 126.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [142.0, 116.0, 167.0, 194.0, 133.0, 129.0, 129.0, 135.0, 198.0, 117.0], "value": 146.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 237.0, 267.0, 272.0, 197.0, 197.0, 201.0, 195.0, 194.0, 216.0], "value": 220.2, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [75.0, 82.0, 77.0, 149.0, 60.0, 60.0, 60.0, 57.0, 130.0, 62.0], "value": 81.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 71.0, 127.0, 72.0, 62.0, 73.0, 109.0, 66.0, 71.0, 114.0], "value": 83.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 94.0, 88.0, 87.0, 91.0, 86.0, 90.0, 107.0, 90.0, 86.0], "value": 90.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 100.0, 100.0, 133.0, 102.0, 100.0, 100.0, 101.0, 100.0, 100.0], "value": 103.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 141.0, 144.0, 91.0, 92.0, 93.0, 92.0, 91.0, 91.0], "value": 102.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 56.0, 56.0, 59.0, 58.0, 58.0, 58.0, 57.0, 57.0, 56.0], "value": 57.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 70.0, 81.0, 94.0, 72.0, 66.0, 96.0, 66.0, 100.0, 99.0], "value": 81.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 90.0, 92.0, 88.0, 90.0, 88.0, 89.0, 86.0, 90.0, 113.0], "value": 91.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 148.0, 163.0, 164.0, 146.0, 136.0, 129.0, 134.0, 191.0, 136.0], "value": 148.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 173.0, 170.0, 176.0, 172.0, 162.0, 160.0, 159.0, 227.0, 173.0], "value": 174.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 78.0, 75.0, 83.0, 73.0, 69.0, 71.0, 69.0, 67.0], "value": 72.5, "unit": "score"}]}]} 10:46:52 INFO - Return code: 0 10:46:53 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:46:53 ERROR - # TBPL SUCCESS # 10:46:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:46:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:46:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:46:53 INFO - 2016-09-07 10:46:53.033 screenresolution[1944:12619] starting screenresolution argv=screenresolution get 10:46:53 INFO - 2016-09-07 10:46:53.036 screenresolution[1944:12619] Display 0: 1600x1200x32@60 10:46:53 INFO - 2016-09-07 10:46:53.042 screenresolution[1945:12622] starting screenresolution argv=screenresolution list 10:46:53 INFO - Available Modes on Display 0 10:46:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:46:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:46:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:46:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:46:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:46:53 INFO - Intel Iris: 10:46:53 INFO - Chipset Model: Intel Iris 10:46:53 INFO - Type: GPU 10:46:53 INFO - Bus: Built-In 10:46:53 INFO - VRAM (Dynamic, Max): 1536 MB 10:46:53 INFO - Vendor: Intel (0x8086) 10:46:53 INFO - Device ID: 0x0a2e 10:46:53 INFO - Revision ID: 0x0009 10:46:53 INFO - Displays: 10:46:53 INFO - Display: 10:46:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:46:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:46:53 INFO - Display Serial Number: 1600x1200 60 10:46:53 INFO - Main Display: Yes 10:46:53 INFO - Mirror: Off 10:46:53 INFO - Online: Yes 10:46:53 INFO - Rotation: Supported 10:46:53 INFO - Return code: 0 10:46:53 INFO - Running post-action listener: _resource_record_post_action 10:46:53 INFO - [mozharness: 2016-09-07 17:46:53.276756Z] Finished run-tests step (success) 10:46:53 INFO - Running post-run listener: _resource_record_post_run 10:46:53 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 2424832; Write bytes: 704443392; Read time: 27; Write time: 2159 10:46:53 INFO - TinderboxPrint: CPU usage
54.5% 10:46:53 INFO - TinderboxPrint: I/O read bytes / time
2,424,832 / 27 10:46:53 INFO - TinderboxPrint: I/O write bytes / time
704,443,392 / 2,159 10:46:53 INFO - TinderboxPrint: CPU idle
2,202.1 (45.5%) 10:46:53 INFO - TinderboxPrint: CPU system
977.3 (20.2%) 10:46:53 INFO - TinderboxPrint: CPU user
1,658.5 (34.3%) 10:46:53 INFO - TinderboxPrint: Swap in / out
408,965,120 / 0 10:46:53 INFO - install - Wall time: 34s; CPU: 54.0%; Read bytes: 404380160; Write bytes: 398358528; Read time: 29809; Write time: 743 10:46:53 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 1466368; Write bytes: 295398400; Read time: 22; Write time: 1383 10:46:53 INFO - Running post-run listener: _upload_blobber_files 10:46:53 INFO - Blob upload gear active. 10:46:53 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:46:53 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 10:46:53 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:46:53 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:46:54 INFO - (blobuploader) - INFO - Open directory for files ... 10:46:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:46:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:54 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:46:54 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:54 INFO - (blobuploader) - INFO - Done attempting. 10:46:54 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:46:54 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:54 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:46:56 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:46:56 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:46:56 INFO - (blobuploader) - INFO - Done attempting. 10:46:56 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:46:56 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:46:56 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:47:06 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:47:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:47:06 INFO - (blobuploader) - INFO - Done attempting. 10:47:06 INFO - (blobuploader) - INFO - Iteration through files over. 10:47:06 INFO - Return code: 0 10:47:06 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:47:06 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:47:06 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b051bf9f2c9501272c820c016701bd307550761265d25708846e50c346acf71ac492086d8a629353222549de0c3ed1f4b3ed9a9a71dd6173045571242ac4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dc8a1ff288cf7bdb42ea3ed5ea9b08722fcea50ba97ab8602b633a65b838b1740da846304074d8c72f0c087e1826ff2641ba14fcb22c42c60cfff16f20dfee5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab60381f27f077db1e13ac38fb574cae1f2624d9ef62cf75219e49e438c951f0d7924153cb2fda6f275b2acbed3a74e1ff9022f52cbf877257b0cf6318d32e7"} 10:47:06 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:47:06 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:47:06 INFO - Contents: 10:47:06 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b051bf9f2c9501272c820c016701bd307550761265d25708846e50c346acf71ac492086d8a629353222549de0c3ed1f4b3ed9a9a71dd6173045571242ac4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dc8a1ff288cf7bdb42ea3ed5ea9b08722fcea50ba97ab8602b633a65b838b1740da846304074d8c72f0c087e1826ff2641ba14fcb22c42c60cfff16f20dfee5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab60381f27f077db1e13ac38fb574cae1f2624d9ef62cf75219e49e438c951f0d7924153cb2fda6f275b2acbed3a74e1ff9022f52cbf877257b0cf6318d32e7"} 10:47:06 INFO - Running post-run listener: copy_logs_to_upload_dir 10:47:06 INFO - Copying logs to upload dir... 10:47:06 INFO - mkdir: /builds/slave/test/build/upload/logs 10:47:06 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1277.227113 ========= master_lag: 0.06 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 17 secs) (at 2016-09-07 10:47:06.657329) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:47:06.662245) ========= 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.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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/Firefox/sha512/b8b051bf9f2c9501272c820c016701bd307550761265d25708846e50c346acf71ac492086d8a629353222549de0c3ed1f4b3ed9a9a71dd6173045571242ac4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dc8a1ff288cf7bdb42ea3ed5ea9b08722fcea50ba97ab8602b633a65b838b1740da846304074d8c72f0c087e1826ff2641ba14fcb22c42c60cfff16f20dfee5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab60381f27f077db1e13ac38fb574cae1f2624d9ef62cf75219e49e438c951f0d7924153cb2fda6f275b2acbed3a74e1ff9022f52cbf877257b0cf6318d32e7"} build_url:https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010956 build_url: 'https://queue.taskcluster.net/v1/task/d_ssxoWxTfubKwic7PHK6g/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b8b051bf9f2c9501272c820c016701bd307550761265d25708846e50c346acf71ac492086d8a629353222549de0c3ed1f4b3ed9a9a71dd6173045571242ac4ba", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/4dc8a1ff288cf7bdb42ea3ed5ea9b08722fcea50ba97ab8602b633a65b838b1740da846304074d8c72f0c087e1826ff2641ba14fcb22c42c60cfff16f20dfee5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6ab60381f27f077db1e13ac38fb574cae1f2624d9ef62cf75219e49e438c951f0d7924153cb2fda6f275b2acbed3a74e1ff9022f52cbf877257b0cf6318d32e7"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-07 10:47:06.699592) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:47:06.703041) ========= 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.N9ShzF4oZe/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.ldg0unxNDF/Listeners TMPDIR=/var/folders/kj/gw3205m94z174y2y_m3c8f_800000w/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.005199 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-07 10:47:06.751670) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:47:06.752001) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-07 10:47:06.752327) ========= ========= Total master_lag: 0.21 =========