builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0183 starttime: 1473690033.78 results: success (0) buildid: 20160912043314 builduid: 8d670bc5993f4525805de53123e90f55 revision: 05bab13e18504f8c0b69076c10e0ef6a6df29333 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.781747) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.782190) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.782470) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.811462) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.811971) ========= 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.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-12 07:20:33-- 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% 16.4M=0.001s 2016-09-12 07:20:33 (16.4 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.125963 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.959769) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:33.960098) ========= 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.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.026927 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.008583) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.009045) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev 05bab13e18504f8c0b69076c10e0ef6a6df29333 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/fx-team --rev 05bab13e18504f8c0b69076c10e0ef6a6df29333 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-12 07:20:34,081 truncating revision to first 12 chars 2016-09-12 07:20:34,082 Setting DEBUG logging. 2016-09-12 07:20:34,082 attempt 1/10 2016-09-12 07:20:34,082 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/05bab13e1850?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-12 07:20:34,738 unpacking tar archive at: fx-team-05bab13e1850/testing/mozharness/ program finished with exit code 0 elapsedTime=0.925106 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.957038) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.957413) ========= script_repo_revision: 05bab13e18504f8c0b69076c10e0ef6a6df29333 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.958249) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.958560) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-12 07:20:34.978031) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-12 07:20:34.978580) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Fx-Team', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Fx-Team'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:20:35 INFO - MultiFileLogger online at 20160912 07:20:35 in /builds/slave/test 07:20:35 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 07:20:35 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 07:20:35 INFO - {'append_to_log': False, 07:20:35 INFO - 'base_work_dir': '/builds/slave/test', 07:20:35 INFO - 'blob_upload_branch': 'Fx-Team', 07:20:35 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 07:20:35 INFO - 'branch': 'Fx-Team', 07:20:35 INFO - 'buildbot_json_path': 'buildprops.json', 07:20:35 INFO - 'config_files': ('talos/mac_config.py',), 07:20:35 INFO - 'default_actions': ('clobber', 07:20:35 INFO - 'read-buildbot-config', 07:20:35 INFO - 'download-and-extract', 07:20:35 INFO - 'populate-webroot', 07:20:35 INFO - 'create-virtualenv', 07:20:35 INFO - 'install', 07:20:35 INFO - 'run-tests'), 07:20:35 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 07:20:35 INFO - 'download_minidump_stackwalk': True, 07:20:35 INFO - 'download_symbols': 'ondemand', 07:20:35 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 07:20:35 INFO - 'tooltool.py': '/tools/tooltool.py', 07:20:35 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 07:20:35 INFO - '/tools/misc-python/virtualenv.py')}, 07:20:35 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 07:20:35 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 07:20:35 INFO - 'installer_path': 'installer.exe', 07:20:35 INFO - 'log_level': 'info', 07:20:35 INFO - 'log_name': 'talos', 07:20:35 INFO - 'log_to_console': True, 07:20:35 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 07:20:35 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 07:20:35 INFO - 'opt_config_files': (), 07:20:35 INFO - 'pip_index': False, 07:20:35 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:35 INFO - 'cmd': ('bash', 07:20:35 INFO - '-c', 07:20:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:35 INFO - 'enabled': True, 07:20:35 INFO - 'halt_on_failure': False, 07:20:35 INFO - 'name': 'check_screen_resolution'},), 07:20:35 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 07:20:35 INFO - 'cmd': ('bash', 07:20:35 INFO - '-c', 07:20:35 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 07:20:35 INFO - 'enabled': True, 07:20:35 INFO - 'halt_on_failure': False, 07:20:35 INFO - 'name': 'check_screen_resolution'},), 07:20:35 INFO - 'run_cmd_checks_enabled': True, 07:20:35 INFO - 'sps_profile': False, 07:20:35 INFO - 'sps_profile_interval': 0, 07:20:35 INFO - 'suite': 'dromaeojs-e10s', 07:20:35 INFO - 'system_bits': '32', 07:20:35 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 07:20:35 INFO - 'title': 't-yosemite-r7-0183', 07:20:35 INFO - 'tooltool_cache': '/builds/tooltool_cache', 07:20:35 INFO - 'use_talos_json': True, 07:20:35 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 07:20:35 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 07:20:35 INFO - 'work_dir': 'build'} 07:20:35 INFO - [mozharness: 2016-09-12 14:20:35.178198Z] Running clobber step. 07:20:35 INFO - Running pre-action listener: _resource_record_pre_action 07:20:35 INFO - Running main action method: clobber 07:20:35 INFO - rmtree: /builds/slave/test/build 07:20:35 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 07:20:37 INFO - Running post-action listener: _resource_record_post_action 07:20:37 INFO - [mozharness: 2016-09-12 14:20:37.246010Z] Finished clobber step (success) 07:20:37 INFO - [mozharness: 2016-09-12 14:20:37.246150Z] Running read-buildbot-config step. 07:20:37 INFO - Running pre-action listener: _resource_record_pre_action 07:20:37 INFO - Running main action method: read_buildbot_config 07:20:37 INFO - Using buildbot properties: 07:20:37 INFO - { 07:20:37 INFO - "project": "", 07:20:37 INFO - "product": "firefox", 07:20:37 INFO - "script_repo_revision": "production", 07:20:37 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 07:20:37 INFO - "repository": "", 07:20:37 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 07:20:37 INFO - "buildid": "20160912043314", 07:20:37 INFO - "pgo_build": "False", 07:20:37 INFO - "basedir": "/builds/slave/test", 07:20:37 INFO - "buildnumber": 112, 07:20:37 INFO - "slavename": "t-yosemite-r7-0183", 07:20:37 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 07:20:37 INFO - "platform": "yosemite_r7", 07:20:37 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 07:20:37 INFO - "branch": "fx-team", 07:20:37 INFO - "repo_path": "integration/fx-team", 07:20:37 INFO - "revision": "05bab13e18504f8c0b69076c10e0ef6a6df29333", 07:20:37 INFO - "stage_platform": "macosx64", 07:20:37 INFO - "builduid": "8d670bc5993f4525805de53123e90f55", 07:20:37 INFO - "slavebuilddir": "test" 07:20:37 INFO - } 07:20:37 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 07:20:37 INFO - Running post-action listener: _resource_record_post_action 07:20:37 INFO - [mozharness: 2016-09-12 14:20:37.249499Z] Finished read-buildbot-config step (success) 07:20:37 INFO - [mozharness: 2016-09-12 14:20:37.249640Z] Running download-and-extract step. 07:20:37 INFO - Running pre-action listener: _resource_record_pre_action 07:20:37 INFO - Running main action method: download_and_extract 07:20:37 INFO - mkdir: /builds/slave/test/build/tests 07:20: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')]} 07:20:37 INFO - trying https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 07:20:37 INFO - Downloading https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:20:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 07:20:38 INFO - Downloaded 1199 bytes. 07:20:38 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 07:20:38 INFO - Using the following test package requirements: 07:20:38 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:20:38 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 07:20:38 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 07:20:38 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'jsshell-mac.zip'], 07:20:38 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 07:20:38 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 07:20:38 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 07:20:38 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 07:20:38 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 07:20:38 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 07:20:38 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 07:20:38 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 07:20:38 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:20:38 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:20:45 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 07:20:46 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 07:20:46 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:20:53 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 07:20:53 INFO - retry: Calling unzip with args: (), kwargs: {'file_object': >, 'verbose': False, 'extract_dirs': '*', 'extract_to': '/builds/slave/test/build/tests'}, attempt #1 07:20:54 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')]} 07:20:54 INFO - trying https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:20:54 INFO - Downloading https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 07:20:54 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 07:20:57 INFO - Downloaded 111630436 bytes. 07:20:57 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:20:57 INFO - mkdir: /builds/slave/test/properties 07:20:57 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 07:20:57 INFO - Writing to file /builds/slave/test/properties/build_url 07:20:57 INFO - Contents: 07:20:57 INFO - build_url:https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 07:20:58 INFO - Running post-action listener: _resource_record_post_action 07:20:58 INFO - Running post-action listener: set_extra_try_arguments 07:20:58 INFO - [mozharness: 2016-09-12 14:20:58.049265Z] Finished download-and-extract step (success) 07:20:58 INFO - [mozharness: 2016-09-12 14:20:58.049417Z] Running populate-webroot step. 07:20:58 INFO - Running pre-action listener: _resource_record_pre_action 07:20:58 INFO - Running main action method: populate_webroot 07:20:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 07:20:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 07:20:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 07:20:58 INFO - '--tppagecycles', 07:20:58 INFO - '7'], 07:20:58 INFO - 'tests': ['tsvgm']}}, 07:20:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 07:20:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 07:20:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 07:20:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 07:20:58 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:58 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 07:20:58 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['damp', 'tps']}, 07:20:58 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'tests': ['damp', 'tps']}, 07:20:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['dromaeo_dom']}, 07:20:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 07:20:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['basic_compositor_video']}, 07:20:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 07:20:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'other-e10s': {'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['a11yr', 07:20:58 INFO - 'ts_paint', 07:20:58 INFO - 'tpaint', 07:20:58 INFO - 'sessionrestore', 07:20:58 INFO - 'sessionrestore_no_auto_restore', 07:20:58 INFO - 'tabpaint']}, 07:20:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['tsvgx', 07:20:58 INFO - 'tsvgr_opacity', 07:20:58 INFO - 'tart', 07:20:58 INFO - 'tscrollx', 07:20:58 INFO - 'cart']}, 07:20:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 07:20:58 INFO - 'tsvgr_opacity', 07:20:58 INFO - 'tart', 07:20:58 INFO - 'tscrollx', 07:20:58 INFO - 'cart']}, 07:20:58 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'talos_options': ['--disable-e10s'], 07:20:58 INFO - 'tests': ['tp5o']}, 07:20:58 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'tests': ['tp5o']}, 07:20:58 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'talos_options': ['--disable-e10s', 07:20:58 INFO - '--xperf_path', 07:20:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:58 INFO - 'tests': ['tp5n']}, 07:20:58 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 07:20:58 INFO - 'talos_options': ['--xperf_path', 07:20:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 07:20:58 INFO - 'tests': ['tp5n']}}, 07:20:58 INFO - 'talos.zip': {'path': '', 07:20:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 07:20:58 INFO - Running post-action listener: _resource_record_post_action 07:20:58 INFO - [mozharness: 2016-09-12 14:20:58.068001Z] Finished populate-webroot step (success) 07:20:58 INFO - [mozharness: 2016-09-12 14:20:58.068138Z] Running create-virtualenv step. 07:20:58 INFO - Running pre-action listener: _resource_record_pre_action 07:20:58 INFO - Running main action method: create_virtualenv 07:20:58 INFO - Creating virtualenv /builds/slave/test/build/venv 07:20:58 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 07:20:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 07:20:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 07:20:58 INFO - Using real prefix '/tools/python27' 07:20:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 07:20:58 INFO - Installing distribute.............................................................................................................................................................................................done. 07:21:01 INFO - Installing pip.................done. 07:21:01 INFO - Return code: 0 07:21:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 07:21:01 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')]} 07:21:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:01 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')]} 07:21:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:01 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:01 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 07:21:01 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 07:21:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:01 INFO - 'HOME': '/Users/cltbld', 07:21:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:01 INFO - 'LOGNAME': 'cltbld', 07:21:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:01 INFO - 'MOZ_NO_REMOTE': '1', 07:21:01 INFO - 'NO_EM_RESTART': '1', 07:21:01 INFO - 'PAGER': '/bin/cat', 07:21:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:01 INFO - 'PWD': '/builds/slave/test', 07:21:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:01 INFO - 'SHELL': '/bin/bash', 07:21:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:01 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:01 INFO - 'USER': 'cltbld', 07:21:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:01 INFO - 'XPC_FLAGS': '0x0', 07:21:01 INFO - 'XPC_SERVICE_NAME': '0', 07:21:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:02 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 07:21:02 INFO - Cleaning up... 07:21:02 INFO - Return code: 0 07:21:02 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 07:21:02 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')]} 07:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:02 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')]} 07:21:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:02 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:02 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 07:21:02 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 07:21:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:02 INFO - 'HOME': '/Users/cltbld', 07:21:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:02 INFO - 'LOGNAME': 'cltbld', 07:21:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:02 INFO - 'MOZ_NO_REMOTE': '1', 07:21:02 INFO - 'NO_EM_RESTART': '1', 07:21:02 INFO - 'PAGER': '/bin/cat', 07:21:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:02 INFO - 'PWD': '/builds/slave/test', 07:21:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:02 INFO - 'SHELL': '/bin/bash', 07:21:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:02 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:02 INFO - 'USER': 'cltbld', 07:21:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:02 INFO - 'XPC_FLAGS': '0x0', 07:21:02 INFO - 'XPC_SERVICE_NAME': '0', 07:21:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:02 INFO - Downloading/unpacking psutil>=3.1.1 07:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 07:21:05 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:05 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:05 INFO - Installing collected packages: psutil 07:21:05 INFO - Running setup.py install for psutil 07:21:05 INFO - building 'psutil._psutil_osx' extension 07:21:05 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 07:21:06 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 07:21:06 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 07:21:06 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 07:21:06 INFO - building 'psutil._psutil_posix' extension 07:21:06 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 07:21:06 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 07:21:06 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 07:21:06 INFO - ^ 07:21:06 INFO - 1 warning generated. 07:21:06 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 07:21:06 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 07:21:06 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 07:21:06 INFO - Successfully installed psutil 07:21:06 INFO - Cleaning up... 07:21:07 INFO - Return code: 0 07:21:07 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 07:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:07 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:07 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 07:21:07 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 07:21:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:07 INFO - 'HOME': '/Users/cltbld', 07:21:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:07 INFO - 'LOGNAME': 'cltbld', 07:21:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:07 INFO - 'MOZ_NO_REMOTE': '1', 07:21:07 INFO - 'NO_EM_RESTART': '1', 07:21:07 INFO - 'PAGER': '/bin/cat', 07:21:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:07 INFO - 'PWD': '/builds/slave/test', 07:21:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:07 INFO - 'SHELL': '/bin/bash', 07:21:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:07 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:07 INFO - 'USER': 'cltbld', 07:21:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:07 INFO - 'XPC_FLAGS': '0x0', 07:21:07 INFO - 'XPC_SERVICE_NAME': '0', 07:21:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:07 INFO - Downloading/unpacking mozsystemmonitor==0.3 07:21:07 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 07:21:07 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 07:21:07 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 07:21:07 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 07:21:07 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 07:21:07 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 07:21:09 INFO - Downloading mozsystemmonitor-0.3.tar.gz 07:21:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 07:21:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 07:21:09 INFO - Installing collected packages: mozsystemmonitor 07:21:09 INFO - Running setup.py install for mozsystemmonitor 07:21:09 INFO - Successfully installed mozsystemmonitor 07:21:09 INFO - Cleaning up... 07:21:09 INFO - Return code: 0 07:21:09 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 07:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:09 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:09 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 07:21:09 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 07:21:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:09 INFO - 'HOME': '/Users/cltbld', 07:21:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:09 INFO - 'LOGNAME': 'cltbld', 07:21:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:09 INFO - 'MOZ_NO_REMOTE': '1', 07:21:09 INFO - 'NO_EM_RESTART': '1', 07:21:09 INFO - 'PAGER': '/bin/cat', 07:21:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:09 INFO - 'PWD': '/builds/slave/test', 07:21:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:09 INFO - 'SHELL': '/bin/bash', 07:21:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:09 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:09 INFO - 'USER': 'cltbld', 07:21:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:09 INFO - 'XPC_FLAGS': '0x0', 07:21:09 INFO - 'XPC_SERVICE_NAME': '0', 07:21:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:10 INFO - Downloading/unpacking jsonschema==2.5.1 07:21:10 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 07:21:10 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 07:21:10 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 07:21:10 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 07:21:10 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 07:21:10 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 07:21:12 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 07:21:12 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 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 07:21:12 INFO - Downloading functools32-3.2.3-2.tar.gz 07:21:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 07:21:13 INFO - warning: no files found matching '*.txt' 07:21:13 INFO - no previously-included directories found matching 'build' 07:21:13 INFO - no previously-included directories found matching 'dist' 07:21:13 INFO - no previously-included directories found matching '.git*' 07:21:13 INFO - Installing collected packages: jsonschema, functools32 07:21:13 INFO - Running setup.py install for functools32 07:21:13 INFO - warning: no files found matching '*.txt' 07:21:13 INFO - no previously-included directories found matching 'build' 07:21:13 INFO - no previously-included directories found matching 'dist' 07:21:13 INFO - no previously-included directories found matching '.git*' 07:21:13 INFO - Successfully installed jsonschema functools32 07:21:13 INFO - Cleaning up... 07:21:13 INFO - Return code: 0 07:21:13 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 07:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 07:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 07:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:13 INFO - 'HOME': '/Users/cltbld', 07:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:13 INFO - 'LOGNAME': 'cltbld', 07:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:13 INFO - 'MOZ_NO_REMOTE': '1', 07:21:13 INFO - 'NO_EM_RESTART': '1', 07:21:13 INFO - 'PAGER': '/bin/cat', 07:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:13 INFO - 'PWD': '/builds/slave/test', 07:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:13 INFO - 'SHELL': '/bin/bash', 07:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:13 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:13 INFO - 'USER': 'cltbld', 07:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:13 INFO - 'XPC_FLAGS': '0x0', 07:21:13 INFO - 'XPC_SERVICE_NAME': '0', 07:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:13 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 07:21:13 INFO - Cleaning up... 07:21:13 INFO - Return code: 0 07:21:13 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 07:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:13 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:13 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:13 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 07:21:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 07:21:13 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:13 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:13 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:13 INFO - 'HOME': '/Users/cltbld', 07:21:13 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:13 INFO - 'LOGNAME': 'cltbld', 07:21:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:13 INFO - 'MOZ_NO_REMOTE': '1', 07:21:13 INFO - 'NO_EM_RESTART': '1', 07:21:13 INFO - 'PAGER': '/bin/cat', 07:21:13 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:13 INFO - 'PWD': '/builds/slave/test', 07:21:13 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:13 INFO - 'SHELL': '/bin/bash', 07:21:13 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:13 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:13 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:13 INFO - 'USER': 'cltbld', 07:21:13 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:13 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:13 INFO - 'XPC_FLAGS': '0x0', 07:21:13 INFO - 'XPC_SERVICE_NAME': '0', 07:21:13 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:13 INFO - Downloading/unpacking blobuploader==1.2.4 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:13 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:13 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:16 INFO - Downloading blobuploader-1.2.4.tar.gz 07:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 07:21:16 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21: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 07:21:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 07:21:17 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 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 07:21:17 INFO - Downloading docopt-0.6.1.tar.gz 07:21:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 07:21:17 INFO - Installing collected packages: blobuploader, requests, docopt 07:21:17 INFO - Running setup.py install for blobuploader 07:21:17 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 07:21:17 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 07:21:17 INFO - Running setup.py install for requests 07:21:18 INFO - Running setup.py install for docopt 07:21:18 INFO - Successfully installed blobuploader requests docopt 07:21:18 INFO - Cleaning up... 07:21:18 INFO - Return code: 0 07:21:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:18 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')]} 07:21:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 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')]} 07:21:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:18 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:18 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 07:21:18 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 07:21:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:18 INFO - 'HOME': '/Users/cltbld', 07:21:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:18 INFO - 'LOGNAME': 'cltbld', 07:21:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:18 INFO - 'MOZ_NO_REMOTE': '1', 07:21:18 INFO - 'NO_EM_RESTART': '1', 07:21:18 INFO - 'PAGER': '/bin/cat', 07:21:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:18 INFO - 'PWD': '/builds/slave/test', 07:21:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:18 INFO - 'SHELL': '/bin/bash', 07:21:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:18 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:18 INFO - 'USER': 'cltbld', 07:21:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:18 INFO - 'XPC_FLAGS': '0x0', 07:21:18 INFO - 'XPC_SERVICE_NAME': '0', 07:21:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:18 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-GaI4qZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:18 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-R1eLad-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:18 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-QXRC5S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-vKb17n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-kdHajH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-53GR7W-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-lsXgiC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-bt_Iqb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-Ujlomb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-aDjAtY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-aczEQv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:19 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-vZ6PEw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:20 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-aVq84m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:20 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-X7mQbH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:20 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-9yqgUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:20 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-sD0Ksj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:20 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-FLDeRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:20 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 07:21:20 INFO - Running setup.py install for manifestparser 07:21:20 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 07:21:20 INFO - Running setup.py install for mozcrash 07:21:20 INFO - Running setup.py install for mozdebug 07:21:20 INFO - Running setup.py install for mozdevice 07:21:21 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Installing dm script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Running setup.py install for mozfile 07:21:21 INFO - Running setup.py install for mozhttpd 07:21:21 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Running setup.py install for mozinfo 07:21:21 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Running setup.py install for mozInstall 07:21:21 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 07:21:21 INFO - Running setup.py install for mozleak 07:21:21 INFO - Running setup.py install for mozlog 07:21:22 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Running setup.py install for moznetwork 07:21:22 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Running setup.py install for mozprocess 07:21:22 INFO - Running setup.py install for mozprofile 07:21:22 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Running setup.py install for mozrunner 07:21:22 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 07:21:22 INFO - Running setup.py install for mozscreenshot 07:21:22 INFO - Running setup.py install for moztest 07:21:22 INFO - Running setup.py install for mozversion 07:21:23 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 07:21:23 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 07:21:23 INFO - Cleaning up... 07:21:23 INFO - Return code: 0 07:21:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21: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')]} 07:21:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21: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')]} 07:21:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:23 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:23 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 07:21:23 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 07:21:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:23 INFO - 'HOME': '/Users/cltbld', 07:21:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:23 INFO - 'LOGNAME': 'cltbld', 07:21:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:23 INFO - 'MOZ_NO_REMOTE': '1', 07:21:23 INFO - 'NO_EM_RESTART': '1', 07:21:23 INFO - 'PAGER': '/bin/cat', 07:21:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:23 INFO - 'PWD': '/builds/slave/test', 07:21:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:23 INFO - 'SHELL': '/bin/bash', 07:21:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:23 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:23 INFO - 'USER': 'cltbld', 07:21:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:23 INFO - 'XPC_FLAGS': '0x0', 07:21:23 INFO - 'XPC_SERVICE_NAME': '0', 07:21:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-LxA7By-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 07:21:23 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)) 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-OpsJK0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 07:21:23 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)) 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-QLsbdf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 07:21:23 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)) 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-vwL_BP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 07:21:23 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)) 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-6P2TM3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 07:21:23 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)) 07:21:23 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 07:21:23 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-TOmzPM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-MEzxJ9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-nvnLe7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-cE7v_7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-DOA0FC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-QHoFa4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-ivHKv6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-CAgtAV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-WhrOU9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-C7hsmu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 07:21:24 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)) 07:21:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 07:21:24 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-9kIy1T-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 07:21:25 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)) 07:21:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 07:21:25 INFO - Running setup.py (path:/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/pip-nJe9ci-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 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)) 07:21:25 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 07:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:25 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:25 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 07:21:25 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 07:21:25 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 07:21:27 INFO - Downloading blessings-1.6.tar.gz 07:21:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 07:21:27 INFO - Installing collected packages: blessings 07:21:27 INFO - Running setup.py install for blessings 07:21:27 INFO - Successfully installed blessings 07:21:27 INFO - Cleaning up... 07:21:27 INFO - Return code: 0 07:21:27 INFO - Done creating virtualenv /builds/slave/test/build/venv. 07:21:27 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:28 INFO - Reading from file tmpfile_stdout 07:21:28 INFO - Current package versions: 07:21:28 INFO - blessings == 1.6 07:21:28 INFO - blobuploader == 1.2.4 07:21:28 INFO - docopt == 0.6.1 07:21:28 INFO - functools32 == 3.2.3-2 07:21:28 INFO - jsonschema == 2.5.1 07:21:28 INFO - manifestparser == 1.1 07:21:28 INFO - mozInstall == 1.12 07:21:28 INFO - mozcrash == 0.17 07:21:28 INFO - mozdebug == 0.1 07:21:28 INFO - mozdevice == 0.48 07:21:28 INFO - mozfile == 1.2 07:21:28 INFO - mozhttpd == 0.7 07:21:28 INFO - mozinfo == 0.9 07:21:28 INFO - mozleak == 0.1 07:21:28 INFO - mozlog == 3.3 07:21:28 INFO - moznetwork == 0.27 07:21:28 INFO - mozprocess == 0.23 07:21:28 INFO - mozprofile == 0.28 07:21:28 INFO - mozrunner == 6.12 07:21:28 INFO - mozscreenshot == 0.1 07:21:28 INFO - mozsystemmonitor == 0.3 07:21:28 INFO - moztest == 0.7 07:21:28 INFO - mozversion == 1.4 07:21:28 INFO - psutil == 3.1.1 07:21:28 INFO - requests == 1.2.3 07:21:28 INFO - wsgiref == 0.1.2 07:21:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 07:21:28 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')]} 07:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:28 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')]} 07:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:28 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:28 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 07:21:28 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 07:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:28 INFO - 'HOME': '/Users/cltbld', 07:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:28 INFO - 'LOGNAME': 'cltbld', 07:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:28 INFO - 'MOZ_NO_REMOTE': '1', 07:21:28 INFO - 'NO_EM_RESTART': '1', 07:21:28 INFO - 'PAGER': '/bin/cat', 07:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:28 INFO - 'PWD': '/builds/slave/test', 07:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:28 INFO - 'SHELL': '/bin/bash', 07:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:28 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:28 INFO - 'USER': 'cltbld', 07:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:28 INFO - 'XPC_FLAGS': '0x0', 07:21:28 INFO - 'XPC_SERVICE_NAME': '0', 07:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 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)) 07:21:28 INFO - Cleaning up... 07:21:28 INFO - Return code: 0 07:21:28 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 07:21:28 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')]} 07:21:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 07:21:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:28 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')]} 07:21:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 07:21:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 07:21:28 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 0x10f2e4490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f25a570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fbdcbd6fd80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, '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.j09B9cARvm/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.1u7Vn6MVLw/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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 07:21:28 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 07:21:28 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 07:21:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:21:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:21:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:21:28 INFO - 'HOME': '/Users/cltbld', 07:21:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:21:28 INFO - 'LOGNAME': 'cltbld', 07:21:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:21:28 INFO - 'MOZ_NO_REMOTE': '1', 07:21:28 INFO - 'NO_EM_RESTART': '1', 07:21:28 INFO - 'PAGER': '/bin/cat', 07:21:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:21:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:21:28 INFO - 'PWD': '/builds/slave/test', 07:21:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:21:28 INFO - 'SHELL': '/bin/bash', 07:21:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:21:28 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:21:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:21:28 INFO - 'USER': 'cltbld', 07:21:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:21:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:21:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:21:28 INFO - 'XPC_FLAGS': '0x0', 07:21:28 INFO - 'XPC_SERVICE_NAME': '0', 07:21:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:21:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 07:21:28 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 07:21:28 INFO - Cleaning up... 07:21:28 INFO - Return code: 0 07:21:28 INFO - Running post-action listener: _resource_record_post_action 07:21:28 INFO - Running post-action listener: _start_resource_monitoring 07:21:28 INFO - Starting resource monitoring. 07:21:28 INFO - [mozharness: 2016-09-12 14:21:28.817542Z] Finished create-virtualenv step (success) 07:21:28 INFO - [mozharness: 2016-09-12 14:21:28.818613Z] Running install step. 07:21:28 INFO - Running pre-action listener: _resource_record_pre_action 07:21:28 INFO - Running main action method: install 07:21:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 07:21:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 07:21:29 INFO - Reading from file tmpfile_stdout 07:21:29 INFO - Detecting whether we're running mozinstall >=1.0... 07:21:29 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 07:21:29 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 07:21:29 INFO - Reading from file tmpfile_stdout 07:21:29 INFO - Output received: 07:21:29 INFO - Usage: mozinstall [options] installer 07:21:29 INFO - Options: 07:21:29 INFO - -h, --help show this help message and exit 07:21:29 INFO - -d DEST, --destination=DEST 07:21:29 INFO - Directory to install application into. [default: 07:21:29 INFO - "/builds/slave/test"] 07:21:29 INFO - --app=APP Application being installed. [default: firefox] 07:21:29 INFO - mkdir: /builds/slave/test/build/application 07:21:29 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'] 07:21:29 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 07:22:03 INFO - Reading from file tmpfile_stdout 07:22:03 INFO - Output received: 07:22:03 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 07:22:03 INFO - Running post-action listener: _resource_record_post_action 07:22:03 INFO - [mozharness: 2016-09-12 14:22:03.548554Z] Finished install step (success) 07:22:03 INFO - [mozharness: 2016-09-12 14:22:03.548699Z] Running run-tests step. 07:22:03 INFO - Running pre-action listener: _resource_record_pre_action 07:22:03 INFO - Running main action method: run_tests 07:22:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:22:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:22:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:22:03 INFO - 2016-09-12 07:22:03.563 screenresolution[1645:7584] starting screenresolution argv=screenresolution get 07:22:03 INFO - 2016-09-12 07:22:03.565 screenresolution[1645:7584] Display 0: 1600x1200x32@60 07:22:03 INFO - 2016-09-12 07:22:03.571 screenresolution[1646:7586] starting screenresolution argv=screenresolution list 07:22:03 INFO - Available Modes on Display 0 07:22:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:22:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:22:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:22:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:22:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:22:03 INFO - Intel Iris: 07:22:03 INFO - Chipset Model: Intel Iris 07:22:03 INFO - Type: GPU 07:22:03 INFO - Bus: Built-In 07:22:03 INFO - VRAM (Dynamic, Max): 1536 MB 07:22:03 INFO - Vendor: Intel (0x8086) 07:22:03 INFO - Device ID: 0x0a2e 07:22:03 INFO - Revision ID: 0x0009 07:22:03 INFO - Displays: 07:22:03 INFO - Display: 07:22:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:22:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:22:03 INFO - Display Serial Number: 1600x1200 60 07:22:03 INFO - Main Display: Yes 07:22:03 INFO - Mirror: Off 07:22:03 INFO - Online: Yes 07:22:03 INFO - Rotation: Supported 07:22:03 INFO - Return code: 0 07:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 07:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 07:22:03 INFO - Python 2.7.3 07:22:03 INFO - Return code: 0 07:22:03 INFO - grabbing minidump binary from tooltool 07:22:03 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')]} 07:22:03 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 0x10f0f8d30>, '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 0x10ef1edb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f0f0b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 07:22:03 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 07:22:03 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 07:22:03 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 07:22:03 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 07:22:03 INFO - Return code: 0 07:22:03 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 07:22:03 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 07:22:03 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 07:22:03 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 07:22:03 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 07:22:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 07:22:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Fx-Team --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0183 --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 07:22:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.j09B9cARvm/Render', 07:22:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 07:22:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 07:22:03 INFO - 'HOME': '/Users/cltbld', 07:22:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 07:22:03 INFO - 'LOGNAME': 'cltbld', 07:22:03 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 07:22:03 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 07:22:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 07:22:03 INFO - 'MOZ_NO_REMOTE': '1', 07:22:03 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 07:22:03 INFO - 'NO_EM_RESTART': '1', 07:22:03 INFO - 'PAGER': '/bin/cat', 07:22:03 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 07:22:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 07:22:03 INFO - 'PWD': '/builds/slave/test', 07:22:03 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 07:22:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 07:22:03 INFO - 'SHELL': '/bin/bash', 07:22:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.1u7Vn6MVLw/Listeners', 07:22:03 INFO - 'TMPDIR': '/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/', 07:22:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 07:22:03 INFO - 'USER': 'cltbld', 07:22:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 07:22:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 07:22:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 07:22:03 INFO - 'XPC_FLAGS': '0x0', 07:22:03 INFO - 'XPC_SERVICE_NAME': '0', 07:22:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 07:22:03 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Fx-Team', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0183', '--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 07:22:04 INFO - mozversion application_buildid: 20160912043314 07:22:04 INFO - mozversion application_changeset: 05bab13e18504f8c0b69076c10e0ef6a6df29333 07:22:04 INFO - mozversion application_display_name: Nightly 07:22:04 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 07:22:04 INFO - mozversion application_name: Firefox 07:22:04 INFO - mozversion application_remotingname: firefox 07:22:04 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 07:22:04 INFO - mozversion application_vendor: Mozilla 07:22:04 INFO - mozversion application_version: 51.0a1 07:22:04 INFO - mozversion platform_buildid: 20160912043314 07:22:04 INFO - mozversion platform_changeset: 05bab13e18504f8c0b69076c10e0ef6a6df29333 07:22:04 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 07:22:04 INFO - mozversion platform_version: 51.0a1 07:22:04 INFO - using testdate: 1473690124 07:22:04 INFO - actual date: 1473690124 07:22:04 INFO - starting webserver on 'localhost:49271' 07:22:04 INFO - SUITE-START | Running 2 tests 07:22:04 INFO - TEST-START | dromaeo_css 07:22:04 INFO - operating with platform_type : mac_ 07:22:04 INFO - Initialising browser for dromaeo_css test... 07:22:04 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmp76v9tA/profile http://localhost:49271/getInfo.html) 07:22:06 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 07:22:06 INFO - PROCESS | 1656 | colorDepth:24 07:22:06 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 07:22:06 INFO - PROCESS | 1656 | __metrics 07:22:06 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 07:22:07 INFO - TEST-INFO | 1656: exit 0 07:22:07 INFO - Browser initialized. 07:22:07 INFO - Running cycle 1/1 for dromaeo_css test... 07:22:07 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmp76v9tA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:22:19 INFO - PROCESS | 1659 | RSS: Main: 244224000 07:22:19 INFO - PROCESS | 1659 | 07:23:09 INFO - PROCESS | 1659 | 1473690189255 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] 07:25:21 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49271/tests/dromaeo/cssquery-ext.html) 07:25:23 INFO - PROCESS | 1659 | RSS: Main: 266817536 07:25:23 INFO - PROCESS | 1659 | 07:28:25 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-ext.html (next: http://localhost:49271/tests/dromaeo/cssquery-jquery.html) 07:28:26 INFO - PROCESS | 1659 | RSS: Main: 272166912 07:28:26 INFO - PROCESS | 1659 | 07:31:28 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49271/tests/dromaeo/cssquery-mootools.html) 07:31:28 INFO - PROCESS | 1659 | RSS: Main: 272986112 07:31:28 INFO - PROCESS | 1659 | 07:34:30 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49271/tests/dromaeo/cssquery-prototype.html) 07:34:31 INFO - PROCESS | 1659 | RSS: Main: 268648448 07:34:31 INFO - PROCESS | 1659 | 07:37:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49271/tests/dromaeo/cssquery-yui.html) 07:37:34 INFO - PROCESS | 1659 | RSS: Main: 273838080 07:37:34 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49271/tests/dromaeo/cssquery-yui.html (next: http://localhost:49271/tests/dromaeo/cssquery-dojo.html) 07:40:37 INFO - PROCESS | 1659 | RSS: Main: 276602880 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | __start_tp_report 07:40:37 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 07:40:37 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 07:40:37 INFO - PROCESS | 1659 | |i|pagename|runs| 07:40:37 INFO - PROCESS | 1659 | |0;dojo.html;3231.2729635831943;3324.6675332466757;3442.9311413771725;3468.7745296555704;3477.0611934777603;5777.971110144454;5896.439838215373;5909.379515150912;5981.641101533911;6034.969825150868;5406.216098665701;5671.886562268752;5853.7365818538165;5877.382874798138;5995.820125396245;5286.074936886049;5308.761105750251;5369.812056578021;5431;5481.890362192754;5422.213779002042;5451;5456.181572764097;5496.945030549683;5520.392756796749;5253.238280449352;5282.498162674559;5287.41838397776;5303.549198318147;5376.1398176292;5303.204519322094;5316.282301889234;5367.543758780469;5416.647917885325;5464.617476776627;5275.235090911835;5277.551408130314;5296.92054619181;5327.147656374961;5383.67697938125;5762.481376676077;5826.213461182729;5880.117982302646;5982.312034116088;5984.880302393971;6108.402780883668;6125.388069147331;6154.907676384858;6164.106204600353;6168.6915654217555;6214.968925155391;6236.812895613093;6301.243850737941;6305.211848518935;6317.684115794192;6001.969990150109;6004.219451471325;6024.337322894522;6028.367021462771;6028.939710602838;6314.02132669437;6395.97664373698;6432.388923052303;6453.709583068773;6461.773837915651;6205.162303089115;6210.31686514483;6225.937740622627;6232.875342493125;6235.501159907197;6135.600157732601;6148.862460444832;6172.938270617326;6270.122182894398;6291.622502649856;6172.9074063889075;6197.721102550396;6222.408871157233;6240.843978900564;6250.3749625037135;6196.907046394308;6208.099825525273;6208.751649933952;6210.192674952227;6228.47058000066;4295.226859165317;4973.676711013773;5023.974880125649;5063.3923929128005;5117.797317630354;3888.8055597220027;4098.282800509899;4529.343245229489;4533.320001999727;4608;5747.425257474303;5923.08192230205;5960.612560183574;5974.044152935509;5978.103284507359;6173.876522469525;6232.25212974446;6299.244090709144;6312.5581209314905;6351;5850.5904586678525;5874.882502350015;5919.230500034968;5943.078822782476;5986.161937328778;5526.834194974157;5568.972155139199;5570.41510641385;5584.776608935597;5589.021921163861;5737.85655358611;5815.476607105381;6212.223472065992;6243.750249989949;6349.68251587428;6145.139680444652;6182.7217775201125;6187.3503282154725;6228.657423841732;6337.271213810471;6463;6487.740490380332;6516.152900122954;6518.054882041983;6567.014947757683;6122.265328160471;6146.739918316824;6149.907751383822;6149.938500614937;6243.188385510036;6177.444030037318;6230.314665386894;6321.810345689637;6348.047792831112;6348.143000694847;6091.664958427329;6171.722272497658;6175.475084617858;6184.103305020657;6195.039768835838;5789.768409263583;6093.664848433379;6174.9073763892675;6194.907076393948;6212.968935155295;5227.738613069408;5928.110783382527;5984.461398474158;6049.485793707584;6123.142760013513;6127.571070025056;6128.1726966858905;6151.907721384272;6175.69121543912;6181.041938499539;6023.186869772525;6129.264488261437;6134.4172303631085;6134.938650613615;6135.754569817157 07:40:37 INFO - PROCESS | 1659 | |1;ext.html;466.496184121149;521.9765110570024;522.2870781383411;536.0966770990881;563.9566801417378;39761.602383976155;41520;41595.16809663805;41647.791761041226;41684.957876053115;35949.64050359496;42538.36192457108;42763.78618106913;42858;42901.78549107258;9566.47384393858;9633.325667203264;9735.464549449778;9745.707628771148;9761.414315141079;9498.667546635868;9513.571889264966;9605.231581473501;9610.951945240264;9632;9601.90398096017;9619.855702164474;9642.2768292378;9644.951775241114;9662.9516852416;9571.712848614483;9574.904250957507;9600.183984361372;9606.375585586915;9616.567254473566;8176;9567.091126342986;9631.807363852684;9644.373115747454;9647.565859536267;39329;40153.79923100395;40426;40458.98852528681;40544.391834122514;3230.935381292361;3264.281857991238;3276.8689252429876;3296.04414719731;3370.427027405347;26726.465470690477;30601.693983060108;30603.928862489927;30711.846440767655;32188.6781132188;3168.1409747895777;3206.150370151912;3231.5960504936884;3231.7091461768555;3312.38720836646;382002.1799781994;382168.0891595552;385673;386110;387090;57658.423415765305;58044.4195558039;58137.12794308004;58171;58258.70870645619;31870;33793.324133517686;34955;61243;61550.69224653848;15368.462103826312;15370.770338372904;15503.922480387526;16649.25078371452;16716.414925477795;12710.936445317715;12812.871871281355;12912.548060818015;12931.28877911705;12933.870661293455;18515.444536663923;18888.433346999613;18947.905260473886;18967.90516047411;18983.050847457573;6365.7135428904885;7792.337651299703;7804.84390312202;7839;7904.288614024766;845.4208866926175;848.2789628815458;947.4504787223532;962.0908241711655;1058.2010582010532;29395.412091758044;29633.85183074114;29717.405651887275;30060.84969575182;30297;978.0415193110791;1025.933314334566;1033.9069483746498;1071.6088627651006;1093.415022962727;14920.40318387274;15368.615784605474;15530.534083977498;15683.372665093268;15903.52289431319;688652;689579;693780;695534;696047;57052.42947570554;57250.713746431;57483;57602;57853.71073144607;1308.718625495523;1325.3638253638308;1337.9866201338057;1341.9865801342057;1346.7845144776788;28529.857350713115;29250.41499169962;29389.70610293955;29794;29953.101406957827;48467;49241.50758492369;49263.26105108498;49317.02731890684;49516.75241623769;621842;623504;624750;625572;625773;25286.49427011486;25538.233852984442;25637.3590660235;25872.223833285032;25985.090521831644;46258.768706156254;46904.29643555281;47157;47244.76377618228;47592.7620361896;37573.4363984535;38271;38844.80577597094;39052.2189556213;39452.21095578015;33766.3246735059;34534.48198276978;39334.60665393309;39624.40563391494;40517.98705032398;27304;33336.33327333488;35041;35908.922732318075;36683.53265869335;33075.66924330726;33976.66023339735;34233.14417139492;34280.48579271263;34692.653073469955;23041.88479057594;25239.62140567856;25474.23577292684;25522.489550209262;25686 07:40:37 INFO - PROCESS | 1659 | |2;jquery.html;313.7380287459971;367.3754617150846;368.4031868373234;386.59407621996894;396.54001358424233;17813.84210026349;18377.173027213776;18400.895946243218;18652.53368665784;19782;20056;20322;20330.593388132227;20353.898230508865;20432.97835108246;5763.423657634245;5765.682887441189;5970.820875373745;6032.788852390167;6046.546509011841;5849.912251316234;5965.46310832025;5986.551008674367;5990.251218597676;6018.037114061729;5723.828285151431;5900.763969441217;5932.199153114339;5948.821535353924;5992.670403127826;5870.178175055496;5895.587308888379;5912.615679980788;5920.940790592103;5922.082077278029;4997.400311962551;5151.227315902607;5153.922691159635;5272.420033796279;5341.851501927104;12895;13118;13134.949204063652;13589.524366647218;24095.87952060246;2061.6082944240547;2090.1325949730845;2138.1233694185466;2159.98920005399;2822.9131784263;9553.61785528578;9559.235261179088;9747.56135973883;10183.54174062169;10196.643117490927;1793.3543924187281;1859.2934684819681;1867.374429566097;1886.3869242496107;1913.3590247267182;94797.10405791845;97641;97732;111410;121441;9173.58718857653;11279.097672186206;11354.388490647198;11401.372924489067;11473.827892581618;11990.280583165038;14340.928295358457;14381;14395.568132956028;14419.279036048156;6960.895586566206;8111.310538604284;8335.874961875577;8353.643163291086;8447.45091569046;8300.958495207486;8339.583020848933;8351.8329633407;8493.745187644381;8495.683169108797;4379.496357918816;4393.978030109829;4424.424824772824;4430.800613972379;4486.12520558488;1666.899986000844;1894.4222012286384;1942.494951312669;1943.679292916681;1954.8436125109956;824.5382585751998;883.8850949376668;889.515214208258;909.1408618855119;991.9318913032776;19817.504562386057;20665.276715314892;20945;20953.37139885806;21026.57946841085;896.3994123936848;931.3666706639415;948.9525523723786;958.4441024206084;965.6384497857987;10894.237403381845;11096.1123110151;11124.944375278072;11149.331040137618;11215.327080375204;577455;583182;592356;592394;594401.1399715042;134446.32776836053;138309.30845345708;139852;140009.29995349958;142856;946.9337146399686;964.2189826240767;969.6654654144309;991.4745185051934;992.5682328187262;15795.763063553826;18054.09729513493;18271.36050238235;18521.981291028584;18581.628367432844;47845.28232076591;52493.21260181024;52513.474865252385;53412.73293633662;53822.730886345315;70499.64750176216;75596.62201688957;75791;79474.20525794668;87727;10674.306170098918;11234.775304494027;11766.352850593299;11941.94029029884;12126.575569855013;13138.605841824761;13247.40386682621;13374.665633359244;13534.526291579747;13752.65618359549;11213.383263920563;11352.772944540899;11440.828387574027;11624.5350185995;11669.708257293636;4420.9115817684105;7333.056739963808;8113.432059755996;11578.189526733047;11642.6507204784;11220.382878941737;11598;11841.644750657493;11849.170558060852;11906.880931190577;11761.764764704487;11783.17517773782;12017.278963262232;12085.456154473246;12108.394580271128;10052.296339256181;11349.602763903224;11456;11582.536698531965;11611.361375124448 07:40:37 INFO - PROCESS | 1659 | |3;mootools.html;1516.2115699836086;1541.9360641157602;1718.7937447506301;1746.8340507651776;1834.7889992650848;8168.63241154148;8322;8791.988921274047;9314.301427392938;9343.205827504662;7371.668274927628;9041.54792260386;9042.502662353569;9100.726978190649;9161.587728552215;2378.6313121466155;2426.4904370082304;2429.6841410616594;2430.732619411863;2431.4286142756528;2333.4516388648653;2405.6872606561124;2415.4444477770126;2415.5651982643117;2427.9635805462844;2406.109739396426;2408.1451084864884;2410.9753354824215;2422.794062504689;2425.9029638814427;2026.8479864010185;2045.2491399068228;2370.857748535076;2389.3429306940643;2394.652775347565;2347.8943447544902;2350.576896158691;2354.0701422937846;2360.138549429463;2376.156464455111;8381.748547543584;9160.175584197388;9222.492762898039;9243.26053915685;9275.350725449221;5176.145935920568;5430.266913966603;5481.780728770845;5482.91775623366;5489.094299440588;3609.9097522561883;3655.451682247684;3664.285464334456;3724.832382542765;3738.5887552369218;1078.4230436716398;1102.2780079048157;1105.9778804423865;1115.1134847796022;1121.831725241212;12024.939875300655;12026.639200823902;12181.939090304491;12241.571544995883;12266;4324.632406245441;4561.749103799257;4647.140279048387;4830.3237546742785;4847.660663753503;10256.07695307426;10394.844077338847;10407.42759148239;10414.739631509121;10426.5829366826;5375.973120134374;5396.946030539723;5402;5412.134058550691;5412.323459567554;4426.490953540384;4590.311453281967;4794.544518270759;4939.604831613462;4939.753012349368;3646.8176591170336;3950.743201691881;4004.519457665099;4016.5581786003518;4164.479440069991;1292.9741405171844;1313.9802902956271;1321.1478596305521;1322.2529270961875;1322.636275024376;811.6307080278577;896.6951236579595;901.3104974694364;920.6961702638114;936.9845591303846;4753.952460475351;5813.331466881278;6092.994655881818;6098.878022439526;6265.464961084564;886.778305423644;920.6777627830206;933.7432206143365;942.8114377124602;1011.858339832424;4018.8794336169963;4031.23406552754;4087.7751723655483;4169.207850508394;4182.853600123981;11109.722256943642;11283.210175287652;11314.886851131549;11328.32030078198;11354.772904541864;11288.54845806175;11316.37759923212;11320;11325.433728313552;11327.150463715254;7976.3618910488185;8425.831483370299;8549.529775862387;8566.3146948244;8578.957105214435;5936.139259807217;6066.908996365147;6124.755009799736;6168.753249869955;6233.563650544418;10135.138513226164;10501.9474902625;10548.578056877639;10634.202434817265;10649.361038337725;10066.748331291776;10295.53670084863;10404.907484713996;10537.63118290856;10737.355758654505;7201.891971620535;7415.703371865281;7644.617769111634;7710.922890771245;7945.48354356965;7863.41024423182;8275.593149164537;8678.045415004219;8807.899012623422;8816.559172041501;7450.590217538087;7484.139323977813;7606.733764318223;7818;7849.136594974434;7430.7399241026305;7529;7575.621218939141;7608.277213664693;7723.111842138226;7136.250693677089;7249.492535522463;7311.3785328247695;7312.707491700486;7315.451341149329;4215.283401821759;4970.975145124251;5074.314967479343;5169.793208271777;5299.966506531189;5083.618728595444;5151.433342332415;5193.779856489001;5203.375594928632;5314.016906872241 07:40:37 INFO - PROCESS | 1659 | |4;prototype.html;296.1810593708398;301.96225471816024;310.4876953027504;312.76073803540294;314.4983750917289;4761.690490118149;5403.891922161554;5424.430434804338;5817.447342502466;5843.561732870031;5446.618736688433;5601.691906945116;5628.352739434966;5668.008098582742;5720.914186287209;3776.300664700882;3802.429635554661;3809.6475751108374;4230.365445183216;4331.180904020303;3869.9032524186837;4226.704130710851;4303.945533344319;4320.768580954428;4371.890702732426;4301.677374196947;4302.784860756965;4304.935425968613;4312.541851144849;4319.287317592609;3612.873549425784;4273.376117075507;4304.827806887721;4320.438343015419;4323.286657701476;3294.7199488043543;3456.5160877477174;3672.779633222015;3762.774233545996;3783.6730410797163;5264.236685680593;5512.228288039678;5572.804951826707;5579.051561234559;5599.440055994409;4748.71507709535;4965.627577931669;4968.378952630921;5005.849824505271;5018.2974383586325;5053.115704751691;5063.443021267658;5317.016351974897;6014.7594096236535;6121.418465245794;3460.8961731148106;3482.3557641836087;3502.842372093262;3515.753897227195;4005.61946615074;5676.602637815355;6109.63342199465;6383.265924418705;6419.35806419359;6478.805635830932;3721.2371463849845;3868.052327179859;3873.4100091391792;3899.498693003214;4105.938410923838;3408.6080100788727;3837.4052021936645;4077.7757223353;4084.326086195804;4097.282975479279;4026.4362989181536;4422.535633758409;4456.197884380777;4833.540813622701;4849.660523763373;5517.5585953124455;5784.82645520635;5985.581009329305;6001.12983617373;6005.849853753691;1253.6113804720567;1315.62504686164;1384.4254634326642;1401.3203596255807;1414.4907833180048;785.5208322923013;819.5369616166847;886.0607755778894;907.582512044467;946.1626460582435;5542.7782888684005;5567.9142567199815;5582.525485333711;5588.245586845724;5655.406182350876;6158.891399548034;6213.627182369072;6249.6562689052535;6256.343083976208;6298.748050077946;4864.635152363587;5155.61332900034;5197.506236907488;5554.361248456479;5568.888622227614;2434.6974368712654;2562.57717476618;2780.415163356867;2792.8913670178886;2817.7182281771657;4750.3824502815105;5074.3657042869645;5083.135866902636;5346.91979620306;5415.512603865671;5392.676439413569;5395.62230643859;5638.802641907513;5875.706214689249;6492.577982431127;5673.120666296731;6499.480041596756;6524.902126468107;6525.216973962965;6564.835879102965;5468.72656367188;5787.701010424018;5867.066466766617;6224.564280500503;6342.778002769881;5668.449197860897;6105.229483449927;6135.54578168736;6284.051943897406;6289.9056514153235;6173.660448675187;6554.442872355712;6583.275839657538;6600.471962242913;6627.586792801614;4023.5574086851007;4052.1287923096675;4060.939085913773;4079.938800918048;4088.8773336799945;5012.573931215742;5542.889142217214;6298.1812364392335;6310.684465776784;6310.842228944313;5292.311999440049;5895.9115613266695;6035.6076855004285;6039.849003774941;6117.816465506042;5104.795808167781;5317.308749862493;6072.813787036575;6147.692615369303;6148.292946311052;5503.926734286776;5591.356993945586;5846.795362162304;5951.315598706205;6060.727267273071;5496.983058134258;5560.5829562783765;6090.482309003784;6129.816105516842;6164.568480206522;5776.682282474504;5840.620359676607;5937.407868239826;5949.007082627369;6003.847979810635 07:40:37 INFO - PROCESS | 1659 | |5;yui.html;606.5784279925452;696.2723953468626;719.8704233238016;720.8486217894242;723.4899395925869;15096.77354839677;15268;15531.53405397837;15629.921850390761;15736.921315393407;14141.787873181886;14231.146131232133;14279.143251404923;14304.642383940381;14327.92836035821;677.474956908395;693.1785833786953;860.5310105992218;864.3171894203603;866.3588944181292;691.1257259566652;854.9316054715639;872.9519876406795;884.7788052986754;887.3522328700053;895.3732387328863;909.8544232922701;912.7854954085784;918.0910898210757;918.7106061590611;873.8907636545432;887.1217494680252;908.7046709819269;909.168111178271;909.963601455941;646.935306469354;653.4478365780955;674.3896773420063;678.1184460201719;679.1612358736955;14382.065165764192;14666;14672.266386680623;14683.853161468356;14686.779698304534;14151.433942642281;14156.36296366666;14308.785368219485;14344.784828227585;14633.268336583129;10133.898661013443;10221.437820919846;11015.953484418967;11760.529578816837;11949.402529873558;3172.333809899924;3528.700060494861;3747.981260093682;3872.380419132925;3903.082775547744;85644.57177714072;107154;119348.25265144912;120558;121761;10353.327033742782;10522.789544209072;10592.258541901992;10616.840747388795;10771.353718776898;22713.88643056774;22743.886280568822;22768.975396107217;22803.429914252276;23263.7673623265;7865.213478652089;7942.205779422012;7968.721094761656;8110.270075693216;8199.95900020496;4384.418679709004;4392.824287028483;4415.315626077963;4500.842470513582;4547.726636541774;3519.137811236212;3613.8915832525067;3622.094476380905;3648.4527320901548;3805.6194380561724;698.8392669685899;705.6365971524668;706.8126946359218;710.4848984486206;712.5653351455608;586.2700937333047;665.4244078871741;670.2560158224367;671.9092922455503;678.9966050169817;8185.263326300662;8891.866622000674;9397.342186046912;9434.481103539234;9475.573599188054;632.4940047961612;646.7639311651213;651.8761435327367;671.1375881991618;671.2113266911359;4457.509673935864;4580.908381832344;4582.19811532987;4584.381108550303;4619.422572178478;126096;128152;132829;139484.6051539492;163807.80018098166;80406.59796701097;80869.59565202254;100997.49501252446;109415.90584094057;126602;98613;109286.90713092768;119341.40329298298;126763;130684;9651.855222171533;9808.852867206855;9988.800223995624;10093.142082923034;10110.241731870286;50450.990980180926;60734.39265607287;65216.67391663011;65228.67385663041;66910.66544667246;92318.53840730754;98242.52636210606;101115.49442252742;103387.44918826374;105832;13747.725045498833;13945;13949.860501395262;14082.577522674335;14312.928435357757;14862.702745945237;15038.172900490577;15260;15295;15354.846451535788;13716.725665486434;13721.794173087212;13842.86157138456;14059.156450612996;14396.280185990869;13719.931400342934;13964.511242106477;14020.298985050502;14290.677360438305;14472.855271447572;13047.804282935575;13050.194208245273;13318;13474.393652285868;13486.932565337504;9436.339456237998;11188.608398706006;11195.720106997065;11310.660680179608;11478.598249061242;8560.14398560139;11040.282381645167;11276.548938042386;11477.196596238184;11520.94239528797 07:40:37 INFO - PROCESS | 1659 | __end_tp_report 07:40:37 INFO - PROCESS | 1659 | __start_cc_report 07:40:37 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,381 07:40:37 INFO - PROCESS | 1659 | __end_cc_report 07:40:37 INFO - PROCESS | 1659 | __startTimestamp1473691237763__endTimestamp 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | ------- Summary: start ------- 07:40:37 INFO - PROCESS | 1659 | Number of tests: 6 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#0] dojo.html Cycles:165 Average:5818.77 Median:6049.49 stddev:642.99 (10.6%) stddev-sans-first:612.08 07:40:37 INFO - PROCESS | 1659 | Values: 3231.3 3324.7 3442.9 3468.8 3477.1 5778.0 5896.4 5909.4 5981.6 6035.0 5406.2 5671.9 5853.7 5877.4 5995.8 5286.1 5308.8 5369.8 5431.0 5481.9 5422.2 5451.0 5456.2 5496.9 5520.4 5253.2 5282.5 5287.4 5303.5 5376.1 5303.2 5316.3 5367.5 5416.6 5464.6 5275.2 5277.6 5296.9 5327.1 5383.7 5762.5 5826.2 5880.1 5982.3 5984.9 6108.4 6125.4 6154.9 6164.1 6168.7 6215.0 6236.8 6301.2 6305.2 6317.7 6002.0 6004.2 6024.3 6028.4 6028.9 6314.0 6396.0 6432.4 6453.7 6461.8 6205.2 6210.3 6225.9 6232.9 6235.5 6135.6 6148.9 6172.9 6270.1 6291.6 6172.9 6197.7 6222.4 6240.8 6250.4 6196.9 6208.1 6208.8 6210.2 6228.5 4295.2 4973.7 5024.0 5063.4 5117.8 3888.8 4098.3 4529.3 4533.3 4608.0 5747.4 5923.1 5960.6 5974.0 5978.1 6173.9 6232.3 6299.2 6312.6 6351.0 5850.6 5874.9 5919.2 5943.1 5986.2 5526.8 5569.0 5570.4 5584.8 5589.0 5737.9 5815.5 6212.2 6243.8 6349.7 6145.1 6182.7 6187.4 6228.7 6337.3 6463.0 6487.7 6516.2 6518.1 6567.0 6122.3 6146.7 6149.9 6149.9 6243.2 6177.4 6230.3 6321.8 6348.0 6348.1 6091.7 6171.7 6175.5 6184.1 6195.0 5789.8 6093.7 6174.9 6194.9 6213.0 5227.7 5928.1 5984.5 6049.5 6123.1 6127.6 6128.2 6151.9 6175.7 6181.0 6023.2 6129.3 6134.4 6134.9 6135.8 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#1] ext.html Cycles:180 Average:69266.11 Median:39331.80 stddev:156252.95 (397.3%) stddev-sans-first:156605.89 07:40:37 INFO - PROCESS | 1659 | Values: 466.5 522.0 522.3 536.1 564.0 39761.6 41520.0 41595.2 41647.8 41685.0 35949.6 42538.4 42763.8 42858.0 42901.8 9566.5 9633.3 9735.5 9745.7 9761.4 9498.7 9513.6 9605.2 9611.0 9632.0 9601.9 9619.9 9642.3 9645.0 9663.0 9571.7 9574.9 9600.2 9606.4 9616.6 8176.0 9567.1 9631.8 9644.4 9647.6 39329.0 40153.8 40426.0 40459.0 40544.4 3230.9 3264.3 3276.9 3296.0 3370.4 26726.5 30601.7 30603.9 30711.8 32188.7 3168.1 3206.2 3231.6 3231.7 3312.4 382002.2 382168.1 385673.0 386110.0 387090.0 57658.4 58044.4 58137.1 58171.0 58258.7 31870.0 33793.3 34955.0 61243.0 61550.7 15368.5 15370.8 15503.9 16649.3 16716.4 12710.9 12812.9 12912.5 12931.3 12933.9 18515.4 18888.4 18947.9 18967.9 18983.1 6365.7 7792.3 7804.8 7839.0 7904.3 845.4 848.3 947.5 962.1 1058.2 29395.4 29633.9 29717.4 30060.8 30297.0 978.0 1025.9 1033.9 1071.6 1093.4 14920.4 15368.6 15530.5 15683.4 15903.5 688652.0 689579.0 693780.0 695534.0 696047.0 57052.4 57250.7 57483.0 57602.0 57853.7 1308.7 1325.4 1338.0 1342.0 1346.8 28529.9 29250.4 29389.7 29794.0 29953.1 48467.0 49241.5 49263.3 49317.0 49516.8 621842.0 623504.0 624750.0 625572.0 625773.0 25286.5 25538.2 25637.4 25872.2 25985.1 46258.8 46904.3 47157.0 47244.8 47592.8 37573.4 38271.0 38844.8 39052.2 39452.2 33766.3 34534.5 39334.6 39624.4 40518.0 27304.0 33336.3 35041.0 35908.9 36683.5 33075.7 33976.7 34233.1 34280.5 34692.7 23041.9 25239.6 25474.2 25522.5 25686.0 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#2] jquery.html Cycles:180 Average:34412.81 Median:11582.35 stddev:98291.21 (848.6%) stddev-sans-first:98533.60 07:40:37 INFO - PROCESS | 1659 | Values: 313.7 367.4 368.4 386.6 396.5 17813.8 18377.2 18400.9 18652.5 19782.0 20056.0 20322.0 20330.6 20353.9 20433.0 5763.4 5765.7 5970.8 6032.8 6046.5 5849.9 5965.5 5986.6 5990.3 6018.0 5723.8 5900.8 5932.2 5948.8 5992.7 5870.2 5895.6 5912.6 5920.9 5922.1 4997.4 5151.2 5153.9 5272.4 5341.9 12895.0 13118.0 13134.9 13589.5 24095.9 2061.6 2090.1 2138.1 2160.0 2822.9 9553.6 9559.2 9747.6 10183.5 10196.6 1793.4 1859.3 1867.4 1886.4 1913.4 94797.1 97641.0 97732.0 111410.0 121441.0 9173.6 11279.1 11354.4 11401.4 11473.8 11990.3 14340.9 14381.0 14395.6 14419.3 6960.9 8111.3 8335.9 8353.6 8447.5 8301.0 8339.6 8351.8 8493.7 8495.7 4379.5 4394.0 4424.4 4430.8 4486.1 1666.9 1894.4 1942.5 1943.7 1954.8 824.5 883.9 889.5 909.1 991.9 19817.5 20665.3 20945.0 20953.4 21026.6 896.4 931.4 949.0 958.4 965.6 10894.2 11096.1 11124.9 11149.3 11215.3 577455.0 583182.0 592356.0 592394.0 594401.1 134446.3 138309.3 139852.0 140009.3 142856.0 946.9 964.2 969.7 991.5 992.6 15795.8 18054.1 18271.4 18522.0 18581.6 47845.3 52493.2 52513.5 53412.7 53822.7 70499.6 75596.6 75791.0 79474.2 87727.0 10674.3 11234.8 11766.4 11941.9 12126.6 13138.6 13247.4 13374.7 13534.5 13752.7 11213.4 11352.8 11440.8 11624.5 11669.7 4420.9 7333.1 8113.4 11578.2 11642.7 11220.4 11598.0 11841.6 11849.2 11906.9 11761.8 11783.2 12017.3 12085.5 12108.4 10052.3 11349.6 11456.0 11582.5 11611.4 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#3] mootools.html Cycles:180 Average:5869.90 Median:4618.73 stddev:3348.60 (72.5%) stddev-sans-first:3342.01 07:40:37 INFO - PROCESS | 1659 | Values: 1516.2 1541.9 1718.8 1746.8 1834.8 8168.6 8322.0 8792.0 9314.3 9343.2 7371.7 9041.5 9042.5 9100.7 9161.6 2378.6 2426.5 2429.7 2430.7 2431.4 2333.5 2405.7 2415.4 2415.6 2428.0 2406.1 2408.1 2411.0 2422.8 2425.9 2026.8 2045.2 2370.9 2389.3 2394.7 2347.9 2350.6 2354.1 2360.1 2376.2 8381.7 9160.2 9222.5 9243.3 9275.4 5176.1 5430.3 5481.8 5482.9 5489.1 3609.9 3655.5 3664.3 3724.8 3738.6 1078.4 1102.3 1106.0 1115.1 1121.8 12024.9 12026.6 12181.9 12241.6 12266.0 4324.6 4561.7 4647.1 4830.3 4847.7 10256.1 10394.8 10407.4 10414.7 10426.6 5376.0 5396.9 5402.0 5412.1 5412.3 4426.5 4590.3 4794.5 4939.6 4939.8 3646.8 3950.7 4004.5 4016.6 4164.5 1293.0 1314.0 1321.1 1322.3 1322.6 811.6 896.7 901.3 920.7 937.0 4754.0 5813.3 6093.0 6098.9 6265.5 886.8 920.7 933.7 942.8 1011.9 4018.9 4031.2 4087.8 4169.2 4182.9 11109.7 11283.2 11314.9 11328.3 11354.8 11288.5 11316.4 11320.0 11325.4 11327.2 7976.4 8425.8 8549.5 8566.3 8579.0 5936.1 6066.9 6124.8 6168.8 6233.6 10135.1 10501.9 10548.6 10634.2 10649.4 10066.7 10295.5 10404.9 10537.6 10737.4 7201.9 7415.7 7644.6 7710.9 7945.5 7863.4 8275.6 8678.0 8807.9 8816.6 7450.6 7484.1 7606.7 7818.0 7849.1 7430.7 7529.0 7575.6 7608.3 7723.1 7136.3 7249.5 7311.4 7312.7 7315.5 4215.3 4971.0 5074.3 5169.8 5300.0 5083.6 5151.4 5193.8 5203.4 5314.0 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#4] prototype.html Cycles:180 Average:4792.89 Median:5435.52 stddev:1543.09 (28.4%) stddev-sans-first:1510.06 07:40:37 INFO - PROCESS | 1659 | Values: 296.2 302.0 310.5 312.8 314.5 4761.7 5403.9 5424.4 5817.4 5843.6 5446.6 5601.7 5628.4 5668.0 5720.9 3776.3 3802.4 3809.6 4230.4 4331.2 3869.9 4226.7 4303.9 4320.8 4371.9 4301.7 4302.8 4304.9 4312.5 4319.3 3612.9 4273.4 4304.8 4320.4 4323.3 3294.7 3456.5 3672.8 3762.8 3783.7 5264.2 5512.2 5572.8 5579.1 5599.4 4748.7 4965.6 4968.4 5005.8 5018.3 5053.1 5063.4 5317.0 6014.8 6121.4 3460.9 3482.4 3502.8 3515.8 4005.6 5676.6 6109.6 6383.3 6419.4 6478.8 3721.2 3868.1 3873.4 3899.5 4105.9 3408.6 3837.4 4077.8 4084.3 4097.3 4026.4 4422.5 4456.2 4833.5 4849.7 5517.6 5784.8 5985.6 6001.1 6005.8 1253.6 1315.6 1384.4 1401.3 1414.5 785.5 819.5 886.1 907.6 946.2 5542.8 5567.9 5582.5 5588.2 5655.4 6158.9 6213.6 6249.7 6256.3 6298.7 4864.6 5155.6 5197.5 5554.4 5568.9 2434.7 2562.6 2780.4 2792.9 2817.7 4750.4 5074.4 5083.1 5346.9 5415.5 5392.7 5395.6 5638.8 5875.7 6492.6 5673.1 6499.5 6524.9 6525.2 6564.8 5468.7 5787.7 5867.1 6224.6 6342.8 5668.4 6105.2 6135.5 6284.1 6289.9 6173.7 6554.4 6583.3 6600.5 6627.6 4023.6 4052.1 4060.9 4079.9 4088.9 5012.6 5542.9 6298.2 6310.7 6310.8 5292.3 5895.9 6035.6 6039.8 6117.8 5104.8 5317.3 6072.8 6147.7 6148.3 5503.9 5591.4 5846.8 5951.3 6060.7 5497.0 5560.6 6090.5 6129.8 6164.6 5776.7 5840.6 5937.4 5949.0 6003.8 07:40:37 INFO - PROCESS | 1659 | 07:40:37 INFO - PROCESS | 1659 | [#5] yui.html Cycles:180 Average:24246.30 Median:3523.92 stddev:37999.47 (1078.3%) stddev-sans-first:38064.62 07:40:37 INFO - PROCESS | 1659 | Values: 606.6 696.3 719.9 720.8 723.5 15096.8 15268.0 15531.5 15629.9 15736.9 14141.8 14231.1 14279.1 14304.6 14327.9 677.5 693.2 860.5 864.3 866.4 691.1 854.9 873.0 884.8 887.4 895.4 909.9 912.8 918.1 918.7 873.9 887.1 908.7 909.2 910.0 646.9 653.4 674.4 678.1 679.2 14382.1 14666.0 14672.3 14683.9 14686.8 14151.4 14156.4 14308.8 14344.8 14633.3 10133.9 10221.4 11016.0 11760.5 11949.4 3172.3 3528.7 3748.0 3872.4 3903.1 85644.6 107154.0 119348.3 120558.0 121761.0 10353.3 10522.8 10592.3 10616.8 10771.4 22713.9 22743.9 22769.0 22803.4 23263.8 7865.2 7942.2 7968.7 8110.3 8200.0 4384.4 4392.8 4415.3 4500.8 4547.7 3519.1 3613.9 3622.1 3648.5 3805.6 698.8 705.6 706.8 710.5 712.6 586.3 665.4 670.3 671.9 679.0 8185.3 8891.9 9397.3 9434.5 9475.6 632.5 646.8 651.9 671.1 671.2 4457.5 4580.9 4582.2 4584.4 4619.4 126096.0 128152.0 132829.0 139484.6 163807.8 80406.6 80869.6 100997.5 109415.9 126602.0 98613.0 109286.9 119341.4 126763.0 130684.0 9651.9 9808.9 9988.8 10093.1 10110.2 50451.0 60734.4 65216.7 65228.7 66910.7 92318.5 98242.5 101115.5 103387.4 105832.0 13747.7 13945.0 13949.9 14082.6 14312.9 14862.7 15038.2 15260.0 15295.0 15354.8 13716.7 13721.8 13842.9 14059.2 14396.3 13719.9 13964.5 14020.3 14290.7 14472.9 13047.8 13050.2 13318.0 13474.4 13486.9 9436.3 11188.6 11195.7 11310.7 11478.6 8560.1 11040.3 11276.5 11477.2 11520.9 07:40:37 INFO - PROCESS | 1659 | -------- Summary: end -------- 07:40:37 INFO - PROCESS | 1659 | 07:40:38 INFO - TEST-INFO | 1659: exit 0 07:40:38 INFO - TEST-OK | dromaeo_css | took 1114202ms 07:40:38 INFO - TEST-START | kraken 07:40:38 INFO - operating with platform_type : mac_ 07:40:38 INFO - Initialising browser for kraken test... 07:40:38 INFO - TEST-INFO | started process 1669 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpDee3PF/profile http://localhost:49271/getInfo.html) 07:40:40 INFO - PROCESS | 1669 | __metrics Screen width/height:1600/1200 07:40:40 INFO - PROCESS | 1669 | colorDepth:24 07:40:40 INFO - PROCESS | 1669 | Browser inner width/height: 1024/689 07:40:40 INFO - PROCESS | 1669 | __metrics 07:40:41 ERROR - PROCESS | 1669 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3000: TypeError: this.tabs is undefined 07:40:41 INFO - TEST-INFO | 1669: exit 0 07:40:41 INFO - Browser initialized. 07:40:41 INFO - Running cycle 1/1 for kraken test... 07:40:41 INFO - TEST-INFO | started process 1671 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/tmpDee3PF/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 07:40:53 INFO - PROCESS | 1671 | RSS: Main: 244125696 07:40:53 INFO - PROCESS | 1671 | 07:40:56 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection) 07:40:57 INFO - PROCESS | 1671 | RSS: Main: 257073152 07:40:57 INFO - PROCESS | 1671 | 07:40:59 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-dft) 07:41:00 INFO - PROCESS | 1671 | RSS: Main: 265736192 07:41:00 INFO - PROCESS | 1671 | 07:41:04 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-fft) 07:41:04 INFO - PROCESS | 1671 | RSS: Main: 264216576 07:41:04 INFO - PROCESS | 1671 | 07:41:06 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator) 07:41:07 INFO - PROCESS | 1671 | RSS: Main: 266514432 07:41:07 INFO - PROCESS | 1671 | 07:41:09 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur) 07:41:10 INFO - PROCESS | 1671 | RSS: Main: 267202560 07:41:10 INFO - PROCESS | 1671 | 07:41:13 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom) 07:41:13 INFO - PROCESS | 1671 | RSS: Main: 267567104 07:41:13 INFO - PROCESS | 1671 | 07:41:16 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate) 07:41:17 INFO - PROCESS | 1671 | RSS: Main: 267849728 07:41:17 INFO - PROCESS | 1671 | 07:41:20 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial) 07:41:21 INFO - PROCESS | 1671 | RSS: Main: 267878400 07:41:21 INFO - PROCESS | 1671 | 07:41:22 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox) 07:41:23 INFO - PROCESS | 1671 | RSS: Main: 267849728 07:41:23 INFO - PROCESS | 1671 | 07:41:25 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes) 07:41:25 INFO - PROCESS | 1671 | RSS: Main: 268308480 07:41:25 INFO - PROCESS | 1671 | 07:41:28 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm) 07:41:28 INFO - PROCESS | 1671 | RSS: Main: 268214272 07:41:28 INFO - PROCESS | 1671 | 07:41:31 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 07:41:32 INFO - PROCESS | 1671 | RSS: Main: 268288000 07:41:32 INFO - PROCESS | 1671 | 07:41:35 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 07:41:36 INFO - PROCESS | 1671 | RSS: Main: 268201984 07:41:36 INFO - PROCESS | 1671 | 07:41:37 INFO - PROCESS | 1671 | Cycle 1(1): loaded http://localhost:49271/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49271/tests/kraken/driver.html?testName=ai-astar) 07:41:38 INFO - PROCESS | 1671 | RSS: Main: 269811712 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | __start_tp_report 07:41:38 INFO - PROCESS | 1671 | _x_x_mozilla_page_load 07:41:38 INFO - PROCESS | 1671 | _x_x_mozilla_page_load_details 07:41:38 INFO - PROCESS | 1671 | |i|pagename|runs| 07:41:38 INFO - PROCESS | 1671 | |0;ai-astar;117;111;120;117;121;101;115;117;97;115 07:41:38 INFO - PROCESS | 1671 | |1;audio-beat-detection;147;114;138;168;116;144;132;103;164;134 07:41:38 INFO - PROCESS | 1671 | |2;audio-dft;199;201;206;219;255;273;219;200;196;195 07:41:38 INFO - PROCESS | 1671 | |3;audio-fft;69;81;78;142;82;79;61;70;134;52 07:41:38 INFO - PROCESS | 1671 | |4;audio-oscillator;76;103;145;72;72;76;109;100;99;142 07:41:38 INFO - PROCESS | 1671 | |5;imaging-gaussian-blur;93;121;90;89;92;89;91;87;131;88 07:41:38 INFO - PROCESS | 1671 | |6;imaging-darkroom;122;109;92;145;149;97;95;122;102;152 07:41:38 INFO - PROCESS | 1671 | |7;imaging-desaturate;85;96;91;91;92;92;91;95;79;145 07:41:38 INFO - PROCESS | 1671 | |8;json-parse-financial;55;61;59;58;63;58;61;58;57;59 07:41:38 INFO - PROCESS | 1671 | |9;json-stringify-tinderbox;64;68;71;64;99;106;102;99;76;68 07:41:38 INFO - PROCESS | 1671 | |10;stanford-crypto-aes;84;88;90;88;87;90;88;90;91;88 07:41:38 INFO - PROCESS | 1671 | |11;stanford-crypto-ccm;164;150;159;158;151;149;141;143;235;131 07:41:38 INFO - PROCESS | 1671 | |12;stanford-crypto-pbkdf2;170;176;174;180;172;171;173;169;217;195 07:41:38 INFO - PROCESS | 1671 | |13;stanford-crypto-sha256-iterative;69;69;71;68;69;70;65;68;69;67 07:41:38 INFO - PROCESS | 1671 | __end_tp_report 07:41:38 INFO - PROCESS | 1671 | __start_cc_report 07:41:38 INFO - PROCESS | 1671 | _x_x_mozilla_cycle_collect,980 07:41:38 INFO - PROCESS | 1671 | __end_cc_report 07:41:38 INFO - PROCESS | 1671 | __startTimestamp1473691298028__endTimestamp 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | ------- Summary: start ------- 07:41:38 INFO - PROCESS | 1671 | Number of tests: 14 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#0] ai-astar Cycles:10 Average:113.10 Median:117.00 stddev:7.98 (6.8%) stddev-sans-first:8.34 07:41:38 INFO - PROCESS | 1671 | Values: 117.0 111.0 120.0 117.0 121.0 101.0 115.0 117.0 97.0 115.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#1] audio-beat-detection Cycles:10 Average:136.00 Median:141.00 stddev:21.06 (14.9%) stddev-sans-first:21.95 07:41:38 INFO - PROCESS | 1671 | Values: 147.0 114.0 138.0 168.0 116.0 144.0 132.0 103.0 164.0 134.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#2] audio-dft Cycles:10 Average:216.30 Median:212.50 stddev:26.87 (12.6%) stddev-sans-first:27.76 07:41:38 INFO - PROCESS | 1671 | Values: 199.0 201.0 206.0 219.0 255.0 273.0 219.0 200.0 196.0 195.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#3] audio-fft Cycles:10 Average:84.80 Median:74.00 stddev:29.64 (40.1%) stddev-sans-first:30.88 07:41:38 INFO - PROCESS | 1671 | Values: 69.0 81.0 78.0 142.0 82.0 79.0 61.0 70.0 134.0 52.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#4] audio-oscillator Cycles:10 Average:99.40 Median:72.00 stddev:27.07 (37.6%) stddev-sans-first:27.36 07:41:38 INFO - PROCESS | 1671 | Values: 76.0 103.0 145.0 72.0 72.0 76.0 109.0 100.0 99.0 142.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#5] imaging-gaussian-blur Cycles:10 Average:97.10 Median:89.50 stddev:15.52 (17.3%) stddev-sans-first:16.39 07:41:38 INFO - PROCESS | 1671 | Values: 93.0 121.0 90.0 89.0 92.0 89.0 91.0 87.0 131.0 88.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#6] imaging-darkroom Cycles:10 Average:118.50 Median:150.50 stddev:23.23 (15.4%) stddev-sans-first:24.61 07:41:38 INFO - PROCESS | 1671 | Values: 122.0 109.0 92.0 145.0 149.0 97.0 95.0 122.0 102.0 152.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#7] imaging-desaturate Cycles:10 Average:95.70 Median:91.50 stddev:18.01 (19.7%) stddev-sans-first:18.68 07:41:38 INFO - PROCESS | 1671 | Values: 85.0 96.0 91.0 91.0 92.0 92.0 91.0 95.0 79.0 145.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#8] json-parse-financial Cycles:10 Average:58.90 Median:59.00 stddev:2.28 (3.9%) stddev-sans-first:1.94 07:41:38 INFO - PROCESS | 1671 | Values: 55.0 61.0 59.0 58.0 63.0 58.0 61.0 58.0 57.0 59.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#9] json-stringify-tinderbox Cycles:10 Average:81.70 Median:69.50 stddev:17.48 (25.2%) stddev-sans-first:17.33 07:41:38 INFO - PROCESS | 1671 | Values: 64.0 68.0 71.0 64.0 99.0 106.0 102.0 99.0 76.0 68.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#10] stanford-crypto-aes Cycles:10 Average:88.40 Median:89.00 stddev:2.01 (2.3%) stddev-sans-first:1.36 07:41:38 INFO - PROCESS | 1671 | Values: 84.0 88.0 90.0 88.0 87.0 90.0 88.0 90.0 91.0 88.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:154.50 stddev:28.68 (18.6%) stddev-sans-first:30.34 07:41:38 INFO - PROCESS | 1671 | Values: 164.0 150.0 159.0 158.0 151.0 149.0 141.0 143.0 235.0 131.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.70 Median:175.00 stddev:15.13 (8.6%) stddev-sans-first:15.63 07:41:38 INFO - PROCESS | 1671 | Values: 170.0 176.0 174.0 180.0 172.0 171.0 173.0 169.0 217.0 195.0 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - PROCESS | 1671 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.50 Median:69.00 stddev:1.65 (2.4%) stddev-sans-first:1.74 07:41:38 INFO - PROCESS | 1671 | Values: 69.0 69.0 71.0 68.0 69.0 70.0 65.0 68.0 69.0 67.0 07:41:38 INFO - PROCESS | 1671 | -------- Summary: end -------- 07:41:38 INFO - PROCESS | 1671 | 07:41:38 INFO - TEST-INFO | 1671: exit 0 07:41:38 INFO - TEST-OK | kraken | took 60481ms 07:41:38 INFO - SUITE-END | took 1174s 07:41:39 INFO - Completed test suite (00:19:35) 07:41:39 INFO - javascript benchmark 07:41:39 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7424.318529475134, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3231.2729635831943, 3324.6675332466757, 3442.9311413771725, 3468.7745296555704, 3477.0611934777603, 5777.971110144454, 5896.439838215373, 5909.379515150912, 5981.641101533911, 6034.969825150868, 5406.216098665701, 5671.886562268752, 5853.7365818538165, 5877.382874798138, 5995.820125396245, 5286.074936886049, 5308.761105750251, 5369.812056578021, 5431.0, 5481.890362192754, 5422.213779002042, 5451.0, 5456.181572764097, 5496.945030549683, 5520.392756796749, 5253.238280449352, 5282.498162674559, 5287.41838397776, 5303.549198318147, 5376.1398176292, 5303.204519322094, 5316.282301889234, 5367.543758780469, 5416.647917885325, 5464.617476776627, 5275.235090911835, 5277.551408130314, 5296.92054619181, 5327.147656374961, 5383.67697938125, 5762.481376676077, 5826.213461182729, 5880.117982302646, 5982.312034116088, 5984.880302393971, 6108.402780883668, 6125.388069147331, 6154.907676384858, 6164.106204600353, 6168.6915654217555, 6214.968925155391, 6236.812895613093, 6301.243850737941, 6305.211848518935, 6317.684115794192, 6001.969990150109, 6004.219451471325, 6024.337322894522, 6028.367021462771, 6028.939710602838, 6314.02132669437, 6395.97664373698, 6432.388923052303, 6453.709583068773, 6461.773837915651, 6205.162303089115, 6210.31686514483, 6225.937740622627, 6232.875342493125, 6235.501159907197, 6135.600157732601, 6148.862460444832, 6172.938270617326, 6270.122182894398, 6291.622502649856, 6172.9074063889075, 6197.721102550396, 6222.408871157233, 6240.843978900564, 6250.3749625037135, 6196.907046394308, 6208.099825525273, 6208.751649933952, 6210.192674952227, 6228.47058000066, 4295.226859165317, 4973.676711013773, 5023.974880125649, 5063.3923929128005, 5117.797317630354, 3888.8055597220027, 4098.282800509899, 4529.343245229489, 4533.320001999727, 4608.0, 5747.425257474303, 5923.08192230205, 5960.612560183574, 5974.044152935509, 5978.103284507359, 6173.876522469525, 6232.25212974446, 6299.244090709144, 6312.5581209314905, 6351.0, 5850.5904586678525, 5874.882502350015, 5919.230500034968, 5943.078822782476, 5986.161937328778, 5526.834194974157, 5568.972155139199, 5570.41510641385, 5584.776608935597, 5589.021921163861, 5737.85655358611, 5815.476607105381, 6212.223472065992, 6243.750249989949, 6349.68251587428, 6145.139680444652, 6182.7217775201125, 6187.3503282154725, 6228.657423841732, 6337.271213810471, 6463.0, 6487.740490380332, 6516.152900122954, 6518.054882041983, 6567.014947757683, 6122.265328160471, 6146.739918316824, 6149.907751383822, 6149.938500614937, 6243.188385510036, 6177.444030037318, 6230.314665386894, 6321.810345689637, 6348.047792831112, 6348.143000694847, 6091.664958427329, 6171.722272497658, 6175.475084617858, 6184.103305020657, 6195.039768835838, 5789.768409263583, 6093.664848433379, 6174.9073763892675, 6194.907076393948, 6212.968935155295, 5227.738613069408, 5928.110783382527, 5984.461398474158, 6049.485793707584, 6123.142760013513, 6127.571070025056, 6128.1726966858905, 6151.907721384272, 6175.69121543912, 6181.041938499539, 6023.186869772525, 6129.264488261437, 6134.4172303631085, 6134.938650613615, 6135.754569817157], "value": 5777.308735331927, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.496184121149, 521.9765110570024, 522.2870781383411, 536.0966770990881, 563.9566801417378, 39761.602383976155, 41520.0, 41595.16809663805, 41647.791761041226, 41684.957876053115, 35949.64050359496, 42538.36192457108, 42763.78618106913, 42858.0, 42901.78549107258, 9566.47384393858, 9633.325667203264, 9735.464549449778, 9745.707628771148, 9761.414315141079, 9498.667546635868, 9513.571889264966, 9605.231581473501, 9610.951945240264, 9632.0, 9601.90398096017, 9619.855702164474, 9642.2768292378, 9644.951775241114, 9662.9516852416, 9571.712848614483, 9574.904250957507, 9600.183984361372, 9606.375585586915, 9616.567254473566, 8176.0, 9567.091126342986, 9631.807363852684, 9644.373115747454, 9647.565859536267, 39329.0, 40153.79923100395, 40426.0, 40458.98852528681, 40544.391834122514, 3230.935381292361, 3264.281857991238, 3276.8689252429876, 3296.04414719731, 3370.427027405347, 26726.465470690477, 30601.693983060108, 30603.928862489927, 30711.846440767655, 32188.6781132188, 3168.1409747895777, 3206.150370151912, 3231.5960504936884, 3231.7091461768555, 3312.38720836646, 382002.1799781994, 382168.0891595552, 385673.0, 386110.0, 387090.0, 57658.423415765305, 58044.4195558039, 58137.12794308004, 58171.0, 58258.70870645619, 31870.0, 33793.324133517686, 34955.0, 61243.0, 61550.69224653848, 15368.462103826312, 15370.770338372904, 15503.922480387526, 16649.25078371452, 16716.414925477795, 12710.936445317715, 12812.871871281355, 12912.548060818015, 12931.28877911705, 12933.870661293455, 18515.444536663923, 18888.433346999613, 18947.905260473886, 18967.90516047411, 18983.050847457573, 6365.7135428904885, 7792.337651299703, 7804.84390312202, 7839.0, 7904.288614024766, 845.4208866926175, 848.2789628815458, 947.4504787223532, 962.0908241711655, 1058.2010582010532, 29395.412091758044, 29633.85183074114, 29717.405651887275, 30060.84969575182, 30297.0, 978.0415193110791, 1025.933314334566, 1033.9069483746498, 1071.6088627651006, 1093.415022962727, 14920.40318387274, 15368.615784605474, 15530.534083977498, 15683.372665093268, 15903.52289431319, 688652.0, 689579.0, 693780.0, 695534.0, 696047.0, 57052.42947570554, 57250.713746431, 57483.0, 57602.0, 57853.71073144607, 1308.718625495523, 1325.3638253638308, 1337.9866201338057, 1341.9865801342057, 1346.7845144776788, 28529.857350713115, 29250.41499169962, 29389.70610293955, 29794.0, 29953.101406957827, 48467.0, 49241.50758492369, 49263.26105108498, 49317.02731890684, 49516.75241623769, 621842.0, 623504.0, 624750.0, 625572.0, 625773.0, 25286.49427011486, 25538.233852984442, 25637.3590660235, 25872.223833285032, 25985.090521831644, 46258.768706156254, 46904.29643555281, 47157.0, 47244.76377618228, 47592.7620361896, 37573.4363984535, 38271.0, 38844.80577597094, 39052.2189556213, 39452.21095578015, 33766.3246735059, 34534.48198276978, 39334.60665393309, 39624.40563391494, 40517.98705032398, 27304.0, 33336.33327333488, 35041.0, 35908.922732318075, 36683.53265869335, 33075.66924330726, 33976.66023339735, 34233.14417139492, 34280.48579271263, 34692.653073469955, 23041.88479057594, 25239.62140567856, 25474.23577292684, 25522.489550209262, 25686.0], "value": 19522.58917384123, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [313.7380287459971, 367.3754617150846, 368.4031868373234, 386.59407621996894, 396.54001358424233, 17813.84210026349, 18377.173027213776, 18400.895946243218, 18652.53368665784, 19782.0, 20056.0, 20322.0, 20330.593388132227, 20353.898230508865, 20432.97835108246, 5763.423657634245, 5765.682887441189, 5970.820875373745, 6032.788852390167, 6046.546509011841, 5849.912251316234, 5965.46310832025, 5986.551008674367, 5990.251218597676, 6018.037114061729, 5723.828285151431, 5900.763969441217, 5932.199153114339, 5948.821535353924, 5992.670403127826, 5870.178175055496, 5895.587308888379, 5912.615679980788, 5920.940790592103, 5922.082077278029, 4997.400311962551, 5151.227315902607, 5153.922691159635, 5272.420033796279, 5341.851501927104, 12895.0, 13118.0, 13134.949204063652, 13589.524366647218, 24095.87952060246, 2061.6082944240547, 2090.1325949730845, 2138.1233694185466, 2159.98920005399, 2822.9131784263, 9553.61785528578, 9559.235261179088, 9747.56135973883, 10183.54174062169, 10196.643117490927, 1793.3543924187281, 1859.2934684819681, 1867.374429566097, 1886.3869242496107, 1913.3590247267182, 94797.10405791845, 97641.0, 97732.0, 111410.0, 121441.0, 9173.58718857653, 11279.097672186206, 11354.388490647198, 11401.372924489067, 11473.827892581618, 11990.280583165038, 14340.928295358457, 14381.0, 14395.568132956028, 14419.279036048156, 6960.895586566206, 8111.310538604284, 8335.874961875577, 8353.643163291086, 8447.45091569046, 8300.958495207486, 8339.583020848933, 8351.8329633407, 8493.745187644381, 8495.683169108797, 4379.496357918816, 4393.978030109829, 4424.424824772824, 4430.800613972379, 4486.12520558488, 1666.899986000844, 1894.4222012286384, 1942.494951312669, 1943.679292916681, 1954.8436125109956, 824.5382585751998, 883.8850949376668, 889.515214208258, 909.1408618855119, 991.9318913032776, 19817.504562386057, 20665.276715314892, 20945.0, 20953.37139885806, 21026.57946841085, 896.3994123936848, 931.3666706639415, 948.9525523723786, 958.4441024206084, 965.6384497857987, 10894.237403381845, 11096.1123110151, 11124.944375278072, 11149.331040137618, 11215.327080375204, 577455.0, 583182.0, 592356.0, 592394.0, 594401.1399715042, 134446.32776836053, 138309.30845345708, 139852.0, 140009.29995349958, 142856.0, 946.9337146399686, 964.2189826240767, 969.6654654144309, 991.4745185051934, 992.5682328187262, 15795.763063553826, 18054.09729513493, 18271.36050238235, 18521.981291028584, 18581.628367432844, 47845.28232076591, 52493.21260181024, 52513.474865252385, 53412.73293633662, 53822.730886345315, 70499.64750176216, 75596.62201688957, 75791.0, 79474.20525794668, 87727.0, 10674.306170098918, 11234.775304494027, 11766.352850593299, 11941.94029029884, 12126.575569855013, 13138.605841824761, 13247.40386682621, 13374.665633359244, 13534.526291579747, 13752.65618359549, 11213.383263920563, 11352.772944540899, 11440.828387574027, 11624.5350185995, 11669.708257293636, 4420.9115817684105, 7333.056739963808, 8113.432059755996, 11578.189526733047, 11642.6507204784, 11220.382878941737, 11598.0, 11841.644750657493, 11849.170558060852, 11906.880931190577, 11761.764764704487, 11783.17517773782, 12017.278963262232, 12085.456154473246, 12108.394580271128, 10052.296339256181, 11349.602763903224, 11456.0, 11582.536698531965, 11611.361375124448], "value": 9458.229157964468, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1516.2115699836086, 1541.9360641157602, 1718.7937447506301, 1746.8340507651776, 1834.7889992650848, 8168.63241154148, 8322.0, 8791.988921274047, 9314.301427392938, 9343.205827504662, 7371.668274927628, 9041.54792260386, 9042.502662353569, 9100.726978190649, 9161.587728552215, 2378.6313121466155, 2426.4904370082304, 2429.6841410616594, 2430.732619411863, 2431.4286142756528, 2333.4516388648653, 2405.6872606561124, 2415.4444477770126, 2415.5651982643117, 2427.9635805462844, 2406.109739396426, 2408.1451084864884, 2410.9753354824215, 2422.794062504689, 2425.9029638814427, 2026.8479864010185, 2045.2491399068228, 2370.857748535076, 2389.3429306940643, 2394.652775347565, 2347.8943447544902, 2350.576896158691, 2354.0701422937846, 2360.138549429463, 2376.156464455111, 8381.748547543584, 9160.175584197388, 9222.492762898039, 9243.26053915685, 9275.350725449221, 5176.145935920568, 5430.266913966603, 5481.780728770845, 5482.91775623366, 5489.094299440588, 3609.9097522561883, 3655.451682247684, 3664.285464334456, 3724.832382542765, 3738.5887552369218, 1078.4230436716398, 1102.2780079048157, 1105.9778804423865, 1115.1134847796022, 1121.831725241212, 12024.939875300655, 12026.639200823902, 12181.939090304491, 12241.571544995883, 12266.0, 4324.632406245441, 4561.749103799257, 4647.140279048387, 4830.3237546742785, 4847.660663753503, 10256.07695307426, 10394.844077338847, 10407.42759148239, 10414.739631509121, 10426.5829366826, 5375.973120134374, 5396.946030539723, 5402.0, 5412.134058550691, 5412.323459567554, 4426.490953540384, 4590.311453281967, 4794.544518270759, 4939.604831613462, 4939.753012349368, 3646.8176591170336, 3950.743201691881, 4004.519457665099, 4016.5581786003518, 4164.479440069991, 1292.9741405171844, 1313.9802902956271, 1321.1478596305521, 1322.2529270961875, 1322.636275024376, 811.6307080278577, 896.6951236579595, 901.3104974694364, 920.6961702638114, 936.9845591303846, 4753.952460475351, 5813.331466881278, 6092.994655881818, 6098.878022439526, 6265.464961084564, 886.778305423644, 920.6777627830206, 933.7432206143365, 942.8114377124602, 1011.858339832424, 4018.8794336169963, 4031.23406552754, 4087.7751723655483, 4169.207850508394, 4182.853600123981, 11109.722256943642, 11283.210175287652, 11314.886851131549, 11328.32030078198, 11354.772904541864, 11288.54845806175, 11316.37759923212, 11320.0, 11325.433728313552, 11327.150463715254, 7976.3618910488185, 8425.831483370299, 8549.529775862387, 8566.3146948244, 8578.957105214435, 5936.139259807217, 6066.908996365147, 6124.755009799736, 6168.753249869955, 6233.563650544418, 10135.138513226164, 10501.9474902625, 10548.578056877639, 10634.202434817265, 10649.361038337725, 10066.748331291776, 10295.53670084863, 10404.907484713996, 10537.63118290856, 10737.355758654505, 7201.891971620535, 7415.703371865281, 7644.617769111634, 7710.922890771245, 7945.48354356965, 7863.41024423182, 8275.593149164537, 8678.045415004219, 8807.899012623422, 8816.559172041501, 7450.590217538087, 7484.139323977813, 7606.733764318223, 7818.0, 7849.136594974434, 7430.7399241026305, 7529.0, 7575.621218939141, 7608.277213664693, 7723.111842138226, 7136.250693677089, 7249.492535522463, 7311.3785328247695, 7312.707491700486, 7315.451341149329, 4215.283401821759, 4970.975145124251, 5074.314967479343, 5169.793208271777, 5299.966506531189, 5083.618728595444, 5151.433342332415, 5193.779856489001, 5203.375594928632, 5314.016906872241], "value": 4691.901507486654, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [296.1810593708398, 301.96225471816024, 310.4876953027504, 312.76073803540294, 314.4983750917289, 4761.690490118149, 5403.891922161554, 5424.430434804338, 5817.447342502466, 5843.561732870031, 5446.618736688433, 5601.691906945116, 5628.352739434966, 5668.008098582742, 5720.914186287209, 3776.300664700882, 3802.429635554661, 3809.6475751108374, 4230.365445183216, 4331.180904020303, 3869.9032524186837, 4226.704130710851, 4303.945533344319, 4320.768580954428, 4371.890702732426, 4301.677374196947, 4302.784860756965, 4304.935425968613, 4312.541851144849, 4319.287317592609, 3612.873549425784, 4273.376117075507, 4304.827806887721, 4320.438343015419, 4323.286657701476, 3294.7199488043543, 3456.5160877477174, 3672.779633222015, 3762.774233545996, 3783.6730410797163, 5264.236685680593, 5512.228288039678, 5572.804951826707, 5579.051561234559, 5599.440055994409, 4748.71507709535, 4965.627577931669, 4968.378952630921, 5005.849824505271, 5018.2974383586325, 5053.115704751691, 5063.443021267658, 5317.016351974897, 6014.7594096236535, 6121.418465245794, 3460.8961731148106, 3482.3557641836087, 3502.842372093262, 3515.753897227195, 4005.61946615074, 5676.602637815355, 6109.63342199465, 6383.265924418705, 6419.35806419359, 6478.805635830932, 3721.2371463849845, 3868.052327179859, 3873.4100091391792, 3899.498693003214, 4105.938410923838, 3408.6080100788727, 3837.4052021936645, 4077.7757223353, 4084.326086195804, 4097.282975479279, 4026.4362989181536, 4422.535633758409, 4456.197884380777, 4833.540813622701, 4849.660523763373, 5517.5585953124455, 5784.82645520635, 5985.581009329305, 6001.12983617373, 6005.849853753691, 1253.6113804720567, 1315.62504686164, 1384.4254634326642, 1401.3203596255807, 1414.4907833180048, 785.5208322923013, 819.5369616166847, 886.0607755778894, 907.582512044467, 946.1626460582435, 5542.7782888684005, 5567.9142567199815, 5582.525485333711, 5588.245586845724, 5655.406182350876, 6158.891399548034, 6213.627182369072, 6249.6562689052535, 6256.343083976208, 6298.748050077946, 4864.635152363587, 5155.61332900034, 5197.506236907488, 5554.361248456479, 5568.888622227614, 2434.6974368712654, 2562.57717476618, 2780.415163356867, 2792.8913670178886, 2817.7182281771657, 4750.3824502815105, 5074.3657042869645, 5083.135866902636, 5346.91979620306, 5415.512603865671, 5392.676439413569, 5395.62230643859, 5638.802641907513, 5875.706214689249, 6492.577982431127, 5673.120666296731, 6499.480041596756, 6524.902126468107, 6525.216973962965, 6564.835879102965, 5468.72656367188, 5787.701010424018, 5867.066466766617, 6224.564280500503, 6342.778002769881, 5668.449197860897, 6105.229483449927, 6135.54578168736, 6284.051943897406, 6289.9056514153235, 6173.660448675187, 6554.442872355712, 6583.275839657538, 6600.471962242913, 6627.586792801614, 4023.5574086851007, 4052.1287923096675, 4060.939085913773, 4079.938800918048, 4088.8773336799945, 5012.573931215742, 5542.889142217214, 6298.1812364392335, 6310.684465776784, 6310.842228944313, 5292.311999440049, 5895.9115613266695, 6035.6076855004285, 6039.849003774941, 6117.816465506042, 5104.795808167781, 5317.308749862493, 6072.813787036575, 6147.692615369303, 6148.292946311052, 5503.926734286776, 5591.356993945586, 5846.795362162304, 5951.315598706205, 6060.727267273071, 5496.983058134258, 5560.5829562783765, 6090.482309003784, 6129.816105516842, 6164.568480206522, 5776.682282474504, 5840.620359676607, 5937.407868239826, 5949.007082627369, 6003.847979810635], "value": 4287.730975216601, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [606.5784279925452, 696.2723953468626, 719.8704233238016, 720.8486217894242, 723.4899395925869, 15096.77354839677, 15268.0, 15531.53405397837, 15629.921850390761, 15736.921315393407, 14141.787873181886, 14231.146131232133, 14279.143251404923, 14304.642383940381, 14327.92836035821, 677.474956908395, 693.1785833786953, 860.5310105992218, 864.3171894203603, 866.3588944181292, 691.1257259566652, 854.9316054715639, 872.9519876406795, 884.7788052986754, 887.3522328700053, 895.3732387328863, 909.8544232922701, 912.7854954085784, 918.0910898210757, 918.7106061590611, 873.8907636545432, 887.1217494680252, 908.7046709819269, 909.168111178271, 909.963601455941, 646.935306469354, 653.4478365780955, 674.3896773420063, 678.1184460201719, 679.1612358736955, 14382.065165764192, 14666.0, 14672.266386680623, 14683.853161468356, 14686.779698304534, 14151.433942642281, 14156.36296366666, 14308.785368219485, 14344.784828227585, 14633.268336583129, 10133.898661013443, 10221.437820919846, 11015.953484418967, 11760.529578816837, 11949.402529873558, 3172.333809899924, 3528.700060494861, 3747.981260093682, 3872.380419132925, 3903.082775547744, 85644.57177714072, 107154.0, 119348.25265144912, 120558.0, 121761.0, 10353.327033742782, 10522.789544209072, 10592.258541901992, 10616.840747388795, 10771.353718776898, 22713.88643056774, 22743.886280568822, 22768.975396107217, 22803.429914252276, 23263.7673623265, 7865.213478652089, 7942.205779422012, 7968.721094761656, 8110.270075693216, 8199.95900020496, 4384.418679709004, 4392.824287028483, 4415.315626077963, 4500.842470513582, 4547.726636541774, 3519.137811236212, 3613.8915832525067, 3622.094476380905, 3648.4527320901548, 3805.6194380561724, 698.8392669685899, 705.6365971524668, 706.8126946359218, 710.4848984486206, 712.5653351455608, 586.2700937333047, 665.4244078871741, 670.2560158224367, 671.9092922455503, 678.9966050169817, 8185.263326300662, 8891.866622000674, 9397.342186046912, 9434.481103539234, 9475.573599188054, 632.4940047961612, 646.7639311651213, 651.8761435327367, 671.1375881991618, 671.2113266911359, 4457.509673935864, 4580.908381832344, 4582.19811532987, 4584.381108550303, 4619.422572178478, 126096.0, 128152.0, 132829.0, 139484.6051539492, 163807.80018098166, 80406.59796701097, 80869.59565202254, 100997.49501252446, 109415.90584094057, 126602.0, 98613.0, 109286.90713092768, 119341.40329298298, 126763.0, 130684.0, 9651.855222171533, 9808.852867206855, 9988.800223995624, 10093.142082923034, 10110.241731870286, 50450.990980180926, 60734.39265607287, 65216.67391663011, 65228.67385663041, 66910.66544667246, 92318.53840730754, 98242.52636210606, 101115.49442252742, 103387.44918826374, 105832.0, 13747.725045498833, 13945.0, 13949.860501395262, 14082.577522674335, 14312.928435357757, 14862.702745945237, 15038.172900490577, 15260.0, 15295.0, 15354.846451535788, 13716.725665486434, 13721.794173087212, 13842.86157138456, 14059.156450612996, 14396.280185990869, 13719.931400342934, 13964.511242106477, 14020.298985050502, 14290.677360438305, 14472.855271447572, 13047.804282935575, 13050.194208245273, 13318.0, 13474.393652285868, 13486.932565337504, 9436.339456237998, 11188.608398706006, 11195.720106997065, 11310.660680179608, 11478.598249061242, 8560.14398560139, 11040.282381645167, 11276.548938042386, 11477.196596238184, 11520.94239528797], "value": 7802.764198819754, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1596.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [117.0, 111.0, 120.0, 117.0, 121.0, 101.0, 115.0, 117.0, 97.0, 115.0], "value": 113.1, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 114.0, 138.0, 168.0, 116.0, 144.0, 132.0, 103.0, 164.0, 134.0], "value": 136.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [199.0, 201.0, 206.0, 219.0, 255.0, 273.0, 219.0, 200.0, 196.0, 195.0], "value": 216.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 81.0, 78.0, 142.0, 82.0, 79.0, 61.0, 70.0, 134.0, 52.0], "value": 84.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 103.0, 145.0, 72.0, 72.0, 76.0, 109.0, 100.0, 99.0, 142.0], "value": 99.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 121.0, 90.0, 89.0, 92.0, 89.0, 91.0, 87.0, 131.0, 88.0], "value": 97.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 109.0, 92.0, 145.0, 149.0, 97.0, 95.0, 122.0, 102.0, 152.0], "value": 118.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 96.0, 91.0, 91.0, 92.0, 92.0, 91.0, 95.0, 79.0, 145.0], "value": 95.7, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 61.0, 59.0, 58.0, 63.0, 58.0, 61.0, 58.0, 57.0, 59.0], "value": 58.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 68.0, 71.0, 64.0, 99.0, 106.0, 102.0, 99.0, 76.0, 68.0], "value": 81.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 88.0, 90.0, 88.0, 87.0, 90.0, 88.0, 90.0, 91.0, 88.0], "value": 88.4, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 150.0, 159.0, 158.0, 151.0, 149.0, 141.0, 143.0, 235.0, 131.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [170.0, 176.0, 174.0, 180.0, 172.0, 171.0, 173.0, 169.0, 217.0, 195.0], "value": 179.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 69.0, 71.0, 68.0, 69.0, 70.0, 65.0, 68.0, 69.0, 67.0], "value": 68.5, "unit": "score"}]}]} 07:41:39 INFO - Return code: 0 07:41:39 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 07:41:39 ERROR - # TBPL SUCCESS # 07:41:39 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 07:41:39 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 07:41:39 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 07:41:39 INFO - 2016-09-12 07:41:39.362 screenresolution[1676:10500] starting screenresolution argv=screenresolution get 07:41:39 INFO - 2016-09-12 07:41:39.364 screenresolution[1676:10500] Display 0: 1600x1200x32@60 07:41:39 INFO - 2016-09-12 07:41:39.370 screenresolution[1677:10503] starting screenresolution argv=screenresolution list 07:41:39 INFO - Available Modes on Display 0 07:41:39 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 07:41:39 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 07:41:39 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 07:41:39 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 07:41:39 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 07:41:39 INFO - Intel Iris: 07:41:39 INFO - Chipset Model: Intel Iris 07:41:39 INFO - Type: GPU 07:41:39 INFO - Bus: Built-In 07:41:39 INFO - VRAM (Dynamic, Max): 1536 MB 07:41:39 INFO - Vendor: Intel (0x8086) 07:41:39 INFO - Device ID: 0x0a2e 07:41:39 INFO - Revision ID: 0x0009 07:41:39 INFO - Displays: 07:41:39 INFO - Display: 07:41:39 INFO - Resolution: 1600 x 1200 @ 60 Hz 07:41:39 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 07:41:39 INFO - Display Serial Number: 1600x1200 60 07:41:39 INFO - Main Display: Yes 07:41:39 INFO - Mirror: Off 07:41:39 INFO - Online: Yes 07:41:39 INFO - Rotation: Supported 07:41:39 INFO - Return code: 0 07:41:39 INFO - Running post-action listener: _resource_record_post_action 07:41:39 INFO - [mozharness: 2016-09-12 14:41:39.604923Z] Finished run-tests step (success) 07:41:39 INFO - Running post-run listener: _resource_record_post_run 07:41:39 INFO - Total resource usage - Wall time: 1210s; CPU: 54.0%; Read bytes: 38253568; Write bytes: 706706432; Read time: 397; Write time: 2181 07:41:39 INFO - TinderboxPrint: CPU usage
54.4% 07:41:39 INFO - TinderboxPrint: I/O read bytes / time
38,253,568 / 397 07:41:39 INFO - TinderboxPrint: I/O write bytes / time
706,706,432 / 2,181 07:41:39 INFO - TinderboxPrint: CPU idle
2,207.2 (45.6%) 07:41:39 INFO - TinderboxPrint: CPU system
978.2 (20.2%) 07:41:39 INFO - TinderboxPrint: CPU user
1,656.4 (34.2%) 07:41:39 INFO - TinderboxPrint: Swap in / out
443,723,776 / 0 07:41:39 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 397625856; Write bytes: 386754560; Read time: 29092; Write time: 667 07:41:39 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 32062464; Write bytes: 297894912; Read time: 309; Write time: 1468 07:41:40 INFO - Running post-run listener: _upload_blobber_files 07:41:40 INFO - Blob upload gear active. 07:41:40 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 07:41:40 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 07:41:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 07:41:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 07:41:40 INFO - (blobuploader) - INFO - Open directory for files ... 07:41:40 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 07:41:40 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:40 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 07:41:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:43 INFO - (blobuploader) - INFO - Done attempting. 07:41:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 07:41:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:43 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 07:41:43 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:43 INFO - (blobuploader) - INFO - Done attempting. 07:41:43 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 07:41:43 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 07:41:43 INFO - (blobuploader) - INFO - Uploading, attempt #1. 07:41:46 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 07:41:46 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 07:41:46 INFO - (blobuploader) - INFO - Done attempting. 07:41:46 INFO - (blobuploader) - INFO - Iteration through files over. 07:41:46 INFO - Return code: 0 07:41:46 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 07:41:46 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 07:41:46 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebf5b099603e9640980a08fafa651a28813b959f751d54218b3d32d28a4ce2d4bbf4730aeb6ed868ea4644e5746798e2e743919fe9ba0efce3ce46c51bbfde11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b398168a522bde76927590072712c43c74f4fcfb2ade2778530e8d921bcdcc32f74d4adebb9df0dc90db08308fa89b4d55e23f062e41f50b6ae0d5f27a5e6910", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cba7e45ce6b29079b10a7bb41530990e9c9582303118615a111a08d6d6e0a26e900a40cd476d9178b1c1a823d435101f447a7750006e7790ba06a393fb09ccc0"} 07:41:46 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 07:41:46 INFO - Writing to file /builds/slave/test/properties/blobber_files 07:41:46 INFO - Contents: 07:41:46 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebf5b099603e9640980a08fafa651a28813b959f751d54218b3d32d28a4ce2d4bbf4730aeb6ed868ea4644e5746798e2e743919fe9ba0efce3ce46c51bbfde11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b398168a522bde76927590072712c43c74f4fcfb2ade2778530e8d921bcdcc32f74d4adebb9df0dc90db08308fa89b4d55e23f062e41f50b6ae0d5f27a5e6910", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cba7e45ce6b29079b10a7bb41530990e9c9582303118615a111a08d6d6e0a26e900a40cd476d9178b1c1a823d435101f447a7750006e7790ba06a393fb09ccc0"} 07:41:46 INFO - Running post-run listener: copy_logs_to_upload_dir 07:41:46 INFO - Copying logs to upload dir... 07:41:46 INFO - mkdir: /builds/slave/test/build/upload/logs 07:41:46 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.183880 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2016-09-12 07:41:46.204228) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 07:41:46.210119) ========= 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.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebf5b099603e9640980a08fafa651a28813b959f751d54218b3d32d28a4ce2d4bbf4730aeb6ed868ea4644e5746798e2e743919fe9ba0efce3ce46c51bbfde11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b398168a522bde76927590072712c43c74f4fcfb2ade2778530e8d921bcdcc32f74d4adebb9df0dc90db08308fa89b4d55e23f062e41f50b6ae0d5f27a5e6910", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cba7e45ce6b29079b10a7bb41530990e9c9582303118615a111a08d6d6e0a26e900a40cd476d9178b1c1a823d435101f447a7750006e7790ba06a393fb09ccc0"} build_url:https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011058 build_url: 'https://queue.taskcluster.net/v1/task/Pa55ziSDRR6mLWVO8pv_yw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/ebf5b099603e9640980a08fafa651a28813b959f751d54218b3d32d28a4ce2d4bbf4730aeb6ed868ea4644e5746798e2e743919fe9ba0efce3ce46c51bbfde11", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/b398168a522bde76927590072712c43c74f4fcfb2ade2778530e8d921bcdcc32f74d4adebb9df0dc90db08308fa89b4d55e23f062e41f50b6ae0d5f27a5e6910", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/cba7e45ce6b29079b10a7bb41530990e9c9582303118615a111a08d6d6e0a26e900a40cd476d9178b1c1a823d435101f447a7750006e7790ba06a393fb09ccc0"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-12 07:41:46.247288) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:41:46.248040) ========= 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.j09B9cARvm/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.1u7Vn6MVLw/Listeners TMPDIR=/var/folders/q3/qr1dz7h156sc9qr571g_8l4r00000w/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.005343 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-12 07:41:46.298634) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 07:41:46.298978) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-12 07:41:46.299313) ========= ========= Total master_lag: 0.18 =========