builder: fx-team_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0120 starttime: 1475078515.1 results: success (0) buildid: 20160928065720 builduid: 11d7cf22a4874f53a8b1f47670c2d6c9 revision: a15b46a9e10281a0e762e8e62843cb08de839b60 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.098638) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.099218) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.099553) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.124645) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.125031) ========= 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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-28 09:01:55-- 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% 15.3M=0.001s 2016-09-28 09:01:55 (15.3 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.201256 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.349014) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.349431) ========= 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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.027103 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.398867) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:55.399399) ========= bash -c 'python archiver_client.py mozharness --repo integration/fx-team --rev a15b46a9e10281a0e762e8e62843cb08de839b60 --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 a15b46a9e10281a0e762e8e62843cb08de839b60 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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-28 09:01:55,475 truncating revision to first 12 chars 2016-09-28 09:01:55,475 Setting DEBUG logging. 2016-09-28 09:01:55,475 attempt 1/10 2016-09-28 09:01:55,475 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/fx-team/a15b46a9e102?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-28 09:01:56,149 unpacking tar archive at: fx-team-a15b46a9e102/testing/mozharness/ program finished with exit code 0 elapsedTime=0.953715 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:56.376073) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:56.376421) ========= script_repo_revision: a15b46a9e10281a0e762e8e62843cb08de839b60 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:56.376801) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:56.377165) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-28 09:01:56.395086) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 09:01:56.395396) ========= /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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 09:01:56 INFO - MultiFileLogger online at 20160928 09:01:56 in /builds/slave/test 09:01:56 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Fx-Team --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Fx-Team 09:01:56 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 09:01:56 INFO - {'append_to_log': False, 09:01:56 INFO - 'base_work_dir': '/builds/slave/test', 09:01:56 INFO - 'blob_upload_branch': 'Fx-Team', 09:01:56 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 09:01:56 INFO - 'branch': 'Fx-Team', 09:01:56 INFO - 'buildbot_json_path': 'buildprops.json', 09:01:56 INFO - 'config_files': ('talos/mac_config.py',), 09:01:56 INFO - 'default_actions': ('clobber', 09:01:56 INFO - 'read-buildbot-config', 09:01:56 INFO - 'download-and-extract', 09:01:56 INFO - 'populate-webroot', 09:01:56 INFO - 'create-virtualenv', 09:01:56 INFO - 'install', 09:01:56 INFO - 'run-tests'), 09:01:56 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 09:01:56 INFO - 'download_minidump_stackwalk': True, 09:01:56 INFO - 'download_symbols': 'ondemand', 09:01:56 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 09:01:56 INFO - 'tooltool.py': '/tools/tooltool.py', 09:01:56 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 09:01:56 INFO - '/tools/misc-python/virtualenv.py')}, 09:01:56 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 09:01:56 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 09:01:56 INFO - 'installer_path': 'installer.exe', 09:01:56 INFO - 'log_level': 'info', 09:01:56 INFO - 'log_name': 'talos', 09:01:56 INFO - 'log_to_console': True, 09:01:56 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 09:01:56 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 09:01:56 INFO - 'opt_config_files': (), 09:01:56 INFO - 'pip_index': False, 09:01:56 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:56 INFO - 'cmd': ('bash', 09:01:56 INFO - '-c', 09:01:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:56 INFO - 'enabled': True, 09:01:56 INFO - 'halt_on_failure': False, 09:01:56 INFO - 'name': 'check_screen_resolution'},), 09:01:56 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 09:01:56 INFO - 'cmd': ('bash', 09:01:56 INFO - '-c', 09:01:56 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 09:01:56 INFO - 'enabled': True, 09:01:56 INFO - 'halt_on_failure': False, 09:01:56 INFO - 'name': 'check_screen_resolution'},), 09:01:56 INFO - 'run_cmd_checks_enabled': True, 09:01:56 INFO - 'sps_profile': False, 09:01:56 INFO - 'sps_profile_interval': 0, 09:01:56 INFO - 'suite': 'dromaeojs-e10s', 09:01:56 INFO - 'system_bits': '32', 09:01:56 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 09:01:56 INFO - 'title': 't-yosemite-r7-0120', 09:01:56 INFO - 'tooltool_cache': '/builds/tooltool_cache', 09:01:56 INFO - 'use_talos_json': True, 09:01:56 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 09:01:56 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 09:01:56 INFO - 'work_dir': 'build'} 09:01:56 INFO - [mozharness: 2016-09-28 16:01:56.599155Z] Running clobber step. 09:01:56 INFO - Running pre-action listener: _resource_record_pre_action 09:01:56 INFO - Running main action method: clobber 09:01:56 INFO - rmtree: /builds/slave/test/build 09:01:56 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - [mozharness: 2016-09-28 16:01:58.680174Z] Finished clobber step (success) 09:01:58 INFO - [mozharness: 2016-09-28 16:01:58.680297Z] Running read-buildbot-config step. 09:01:58 INFO - Running pre-action listener: _resource_record_pre_action 09:01:58 INFO - Running main action method: read_buildbot_config 09:01:58 INFO - Using buildbot properties: 09:01:58 INFO - { 09:01:58 INFO - "project": "", 09:01:58 INFO - "product": "firefox", 09:01:58 INFO - "script_repo_revision": "production", 09:01:58 INFO - "builddir": "fx-team_yosemite_r7_test-dromaeojs-e10s", 09:01:58 INFO - "repository": "", 09:01:58 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 fx-team talos dromaeojs-e10s", 09:01:58 INFO - "buildid": "20160928065720", 09:01:58 INFO - "pgo_build": "False", 09:01:58 INFO - "basedir": "/builds/slave/test", 09:01:58 INFO - "buildnumber": 1, 09:01:58 INFO - "slavename": "t-yosemite-r7-0120", 09:01:58 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 09:01:58 INFO - "platform": "yosemite_r7", 09:01:58 INFO - "scheduler": "tests-fx-team-macosx64-talos-8-3600", 09:01:58 INFO - "branch": "fx-team", 09:01:58 INFO - "repo_path": "integration/fx-team", 09:01:58 INFO - "revision": "a15b46a9e10281a0e762e8e62843cb08de839b60", 09:01:58 INFO - "stage_platform": "macosx64", 09:01:58 INFO - "builduid": "11d7cf22a4874f53a8b1f47670c2d6c9", 09:01:58 INFO - "slavebuilddir": "test" 09:01:58 INFO - } 09:01:58 INFO - Found installer url https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 09:01:58 INFO - Running post-action listener: _resource_record_post_action 09:01:58 INFO - [mozharness: 2016-09-28 16:01:58.683574Z] Finished read-buildbot-config step (success) 09:01:58 INFO - [mozharness: 2016-09-28 16:01:58.683705Z] Running download-and-extract step. 09:01:58 INFO - Running pre-action listener: _resource_record_pre_action 09:01:58 INFO - Running main action method: download_and_extract 09:01:58 INFO - mkdir: /builds/slave/test/build/tests 09:01:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:01:58 INFO - trying https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:58 INFO - Downloading https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:58 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 09:01:59 INFO - Downloaded 1199 bytes. 09:01:59 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 09:01:59 INFO - Using the following test package requirements: 09:01:59 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:59 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 09:01:59 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 09:01:59 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'jsshell-mac.zip'], 09:01:59 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 09:01:59 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 09:01:59 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 09:01:59 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 09:01:59 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 09:01:59 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 09:01:59 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 09:01:59 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 09:01:59 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:01:59 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:01:59 INFO - Fetch https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:02:00 INFO - Expected file size: 16470415 09:02:07 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 09:02:07 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 09:02:07 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 09:02:07 INFO - Fetch https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 09:02:08 INFO - Expected file size: 16470415 09:02:15 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 09:02:15 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 09:02:15 INFO - Fetch https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 09:02:16 INFO - Expected file size: 14414062 09:02:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:17 INFO - trying https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:17 INFO - Downloading https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 09:02:17 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 09:02:23 INFO - Downloaded 111545531 bytes. 09:02:23 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:23 INFO - mkdir: /builds/slave/test/properties 09:02:23 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 09:02:23 INFO - Writing to file /builds/slave/test/properties/build_url 09:02:23 INFO - Contents: 09:02:23 INFO - build_url:https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 09:02:24 INFO - Running post-action listener: _resource_record_post_action 09:02:24 INFO - Running post-action listener: set_extra_try_arguments 09:02:24 INFO - [mozharness: 2016-09-28 16:02:24.355391Z] Finished download-and-extract step (success) 09:02:24 INFO - [mozharness: 2016-09-28 16:02:24.355518Z] Running populate-webroot step. 09:02:24 INFO - Running pre-action listener: _resource_record_pre_action 09:02:24 INFO - Running main action method: populate_webroot 09:02:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 09:02:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 09:02:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 09:02:24 INFO - '--tppagecycles', 09:02:24 INFO - '7'], 09:02:24 INFO - 'tests': ['tsvgm']}}, 09:02:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 09:02:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 09:02:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 09:02:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 09:02:24 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:24 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 09:02:24 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['damp', 'tps']}, 09:02:24 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'tests': ['damp', 'tps']}, 09:02:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['dromaeo_dom']}, 09:02:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 09:02:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['basic_compositor_video']}, 09:02:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 09:02:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'other-e10s': {'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['a11yr', 09:02:24 INFO - 'ts_paint', 09:02:24 INFO - 'tpaint', 09:02:24 INFO - 'sessionrestore', 09:02:24 INFO - 'sessionrestore_no_auto_restore', 09:02:24 INFO - 'tabpaint']}, 09:02:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['tsvgx', 09:02:24 INFO - 'tsvgr_opacity', 09:02:24 INFO - 'tart', 09:02:24 INFO - 'tscrollx', 09:02:24 INFO - 'cart']}, 09:02:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 09:02:24 INFO - 'tsvgr_opacity', 09:02:24 INFO - 'tart', 09:02:24 INFO - 'tscrollx', 09:02:24 INFO - 'cart']}, 09:02:24 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'talos_options': ['--disable-e10s'], 09:02:24 INFO - 'tests': ['tp5o']}, 09:02:24 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'tests': ['tp5o']}, 09:02:24 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'talos_options': ['--disable-e10s', 09:02:24 INFO - '--xperf_path', 09:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:24 INFO - 'tests': ['tp5n']}, 09:02:24 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 09:02:24 INFO - 'talos_options': ['--xperf_path', 09:02:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 09:02:24 INFO - 'tests': ['tp5n']}}, 09:02:24 INFO - 'talos.zip': {'path': '', 09:02:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 09:02:24 INFO - Running post-action listener: _resource_record_post_action 09:02:24 INFO - [mozharness: 2016-09-28 16:02:24.374332Z] Finished populate-webroot step (success) 09:02:24 INFO - [mozharness: 2016-09-28 16:02:24.374471Z] Running create-virtualenv step. 09:02:24 INFO - Running pre-action listener: _resource_record_pre_action 09:02:24 INFO - Running main action method: create_virtualenv 09:02:24 INFO - Creating virtualenv /builds/slave/test/build/venv 09:02:24 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 09:02:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 09:02:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 09:02:24 INFO - Using real prefix '/tools/python27' 09:02:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 09:02:25 INFO - Installing distribute.............................................................................................................................................................................................done. 09:02:28 INFO - Installing pip.................done. 09:02:28 INFO - Return code: 0 09:02:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 09:02:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 09:02:28 INFO - Reading from file tmpfile_stdout 09:02:28 INFO - Output received: 09:02:28 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 09:02:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02: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', 'pip>=1.5'] in /builds/slave/test/build 09:02: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 pip>=1.5 09:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 09:02:28 INFO - Cleaning up... 09:02:28 INFO - Return code: 0 09:02:28 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 09:02: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')]} 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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')]} 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02: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', '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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02: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', 'psutil>=3.1.1'] in /builds/slave/test/build 09:02: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 psutil>=3.1.1 09:02:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:28 INFO - 'HOME': '/Users/cltbld', 09:02:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:28 INFO - 'LOGNAME': 'cltbld', 09:02:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:28 INFO - 'MOZ_NO_REMOTE': '1', 09:02:28 INFO - 'NO_EM_RESTART': '1', 09:02:28 INFO - 'PAGER': '/bin/cat', 09:02:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:28 INFO - 'PWD': '/builds/slave/test', 09:02:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:28 INFO - 'SHELL': '/bin/bash', 09:02:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:28 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:28 INFO - 'USER': 'cltbld', 09:02:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:28 INFO - 'XPC_FLAGS': '0x0', 09:02:28 INFO - 'XPC_SERVICE_NAME': '0', 09:02:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:28 INFO - Downloading/unpacking psutil>=3.1.1 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:28 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:28 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:31 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 09:02:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:31 INFO - Installing collected packages: psutil 09:02:31 INFO - Running setup.py install for psutil 09:02:31 INFO - building 'psutil._psutil_osx' extension 09:02:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 09:02:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 09:02:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 09:02:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 09:02:32 INFO - building 'psutil._psutil_posix' extension 09:02:32 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 09:02:32 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 09:02:32 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 09:02:32 INFO - ^ 09:02:32 INFO - 1 warning generated. 09:02:32 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 09:02:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 09:02:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 09:02:33 INFO - Successfully installed psutil 09:02:33 INFO - Cleaning up... 09:02:33 INFO - Return code: 0 09:02:33 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 09:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:33 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 09:02:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 09:02:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:33 INFO - 'HOME': '/Users/cltbld', 09:02:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:33 INFO - 'LOGNAME': 'cltbld', 09:02:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:33 INFO - 'MOZ_NO_REMOTE': '1', 09:02:33 INFO - 'NO_EM_RESTART': '1', 09:02:33 INFO - 'PAGER': '/bin/cat', 09:02:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:33 INFO - 'PWD': '/builds/slave/test', 09:02:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:33 INFO - 'SHELL': '/bin/bash', 09:02:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:33 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:33 INFO - 'USER': 'cltbld', 09:02:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:33 INFO - 'XPC_FLAGS': '0x0', 09:02:33 INFO - 'XPC_SERVICE_NAME': '0', 09:02:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:33 INFO - Downloading/unpacking mozsystemmonitor==0.3 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:33 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:33 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:33 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:33 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:35 INFO - Downloading mozsystemmonitor-0.3.tar.gz 09:02:35 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 09:02:35 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 09:02:35 INFO - Installing collected packages: mozsystemmonitor 09:02:35 INFO - Running setup.py install for mozsystemmonitor 09:02:36 INFO - Successfully installed mozsystemmonitor 09:02:36 INFO - Cleaning up... 09:02:36 INFO - Return code: 0 09:02:36 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 09:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:36 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:36 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:36 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:36 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:36 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:36 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 09:02:36 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 09:02:36 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:36 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:36 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:36 INFO - 'HOME': '/Users/cltbld', 09:02:36 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:36 INFO - 'LOGNAME': 'cltbld', 09:02:36 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:36 INFO - 'MOZ_NO_REMOTE': '1', 09:02:36 INFO - 'NO_EM_RESTART': '1', 09:02:36 INFO - 'PAGER': '/bin/cat', 09:02:36 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:36 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:36 INFO - 'PWD': '/builds/slave/test', 09:02:36 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:36 INFO - 'SHELL': '/bin/bash', 09:02:36 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:36 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:36 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:36 INFO - 'USER': 'cltbld', 09:02:36 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:36 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:36 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:36 INFO - 'XPC_FLAGS': '0x0', 09:02:36 INFO - 'XPC_SERVICE_NAME': '0', 09:02:36 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:36 INFO - Downloading/unpacking jsonschema==2.5.1 09:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 09:02:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 09:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:39 INFO - Downloading functools32-3.2.3-2.tar.gz 09:02:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 09:02:39 INFO - warning: no files found matching '*.txt' 09:02:39 INFO - no previously-included directories found matching 'build' 09:02:39 INFO - no previously-included directories found matching 'dist' 09:02:39 INFO - no previously-included directories found matching '.git*' 09:02:39 INFO - Installing collected packages: jsonschema, functools32 09:02:39 INFO - Running setup.py install for functools32 09:02:39 INFO - warning: no files found matching '*.txt' 09:02:39 INFO - no previously-included directories found matching 'build' 09:02:39 INFO - no previously-included directories found matching 'dist' 09:02:39 INFO - no previously-included directories found matching '.git*' 09:02:39 INFO - Successfully installed jsonschema functools32 09:02:39 INFO - Cleaning up... 09:02:39 INFO - Return code: 0 09:02:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 09:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 09:02:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 09:02:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:39 INFO - 'HOME': '/Users/cltbld', 09:02:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:39 INFO - 'LOGNAME': 'cltbld', 09:02:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:39 INFO - 'MOZ_NO_REMOTE': '1', 09:02:39 INFO - 'NO_EM_RESTART': '1', 09:02:39 INFO - 'PAGER': '/bin/cat', 09:02:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:39 INFO - 'PWD': '/builds/slave/test', 09:02:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:39 INFO - 'SHELL': '/bin/bash', 09:02:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:39 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:39 INFO - 'USER': 'cltbld', 09:02:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:39 INFO - 'XPC_FLAGS': '0x0', 09:02:39 INFO - 'XPC_SERVICE_NAME': '0', 09:02:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:40 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 09:02:40 INFO - Cleaning up... 09:02:40 INFO - Return code: 0 09:02:40 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 09:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:40 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:40 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 09:02:40 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 09:02:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:40 INFO - 'HOME': '/Users/cltbld', 09:02:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:40 INFO - 'LOGNAME': 'cltbld', 09:02:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:40 INFO - 'MOZ_NO_REMOTE': '1', 09:02:40 INFO - 'NO_EM_RESTART': '1', 09:02:40 INFO - 'PAGER': '/bin/cat', 09:02:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:40 INFO - 'PWD': '/builds/slave/test', 09:02:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:40 INFO - 'SHELL': '/bin/bash', 09:02:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:40 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:40 INFO - 'USER': 'cltbld', 09:02:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:40 INFO - 'XPC_FLAGS': '0x0', 09:02:40 INFO - 'XPC_SERVICE_NAME': '0', 09:02:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:40 INFO - Downloading/unpacking blobuploader==1.2.4 09:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:40 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:40 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:40 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:40 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:42 INFO - Downloading blobuploader-1.2.4.tar.gz 09:02:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 09:02:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 09:02:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 09:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:43 INFO - Downloading docopt-0.6.1.tar.gz 09:02:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 09:02:44 INFO - Installing collected packages: blobuploader, requests, docopt 09:02:44 INFO - Running setup.py install for blobuploader 09:02:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 09:02:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 09:02:44 INFO - Running setup.py install for requests 09:02:44 INFO - Running setup.py install for docopt 09:02:44 INFO - Successfully installed blobuploader requests docopt 09:02:44 INFO - Cleaning up... 09:02:45 INFO - Return code: 0 09:02:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:45 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:45 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:45 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:45 INFO - 'HOME': '/Users/cltbld', 09:02:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:45 INFO - 'LOGNAME': 'cltbld', 09:02:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:45 INFO - 'MOZ_NO_REMOTE': '1', 09:02:45 INFO - 'NO_EM_RESTART': '1', 09:02:45 INFO - 'PAGER': '/bin/cat', 09:02:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:45 INFO - 'PWD': '/builds/slave/test', 09:02:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:45 INFO - 'SHELL': '/bin/bash', 09:02:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:45 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:45 INFO - 'USER': 'cltbld', 09:02:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:45 INFO - 'XPC_FLAGS': '0x0', 09:02:45 INFO - 'XPC_SERVICE_NAME': '0', 09:02:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-5xCc6M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-tiWSL6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-1USeUr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vvZjOH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-gsOu8j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-22BxRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-YlINYB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:45 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-jtToUi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-LZzPDo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-lTqYWp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-V_ESlW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-YoAL0A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2NNV5i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-IfglFu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-93LDLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-CjMxNl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:46 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-jVBhxH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 09:02:47 INFO - Running setup.py install for manifestparser 09:02:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 09:02:47 INFO - Running setup.py install for mozcrash 09:02:47 INFO - Running setup.py install for mozdebug 09:02:47 INFO - Running setup.py install for mozdevice 09:02:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 09:02:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 09:02:47 INFO - Running setup.py install for mozfile 09:02:47 INFO - Running setup.py install for mozhttpd 09:02:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 09:02:47 INFO - Running setup.py install for mozinfo 09:02:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Running setup.py install for mozInstall 09:02:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Running setup.py install for mozleak 09:02:48 INFO - Running setup.py install for mozlog 09:02:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Running setup.py install for moznetwork 09:02:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Running setup.py install for mozprocess 09:02:48 INFO - Running setup.py install for mozprofile 09:02:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 09:02:48 INFO - Running setup.py install for mozrunner 09:02:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 09:02:49 INFO - Running setup.py install for mozscreenshot 09:02:49 INFO - Running setup.py install for moztest 09:02:49 INFO - Running setup.py install for mozversion 09:02:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 09:02:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 09:02:49 INFO - Cleaning up... 09:02:49 INFO - Return code: 0 09:02:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:49 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 09:02:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:49 INFO - 'HOME': '/Users/cltbld', 09:02:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:49 INFO - 'LOGNAME': 'cltbld', 09:02:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:49 INFO - 'MOZ_NO_REMOTE': '1', 09:02:49 INFO - 'NO_EM_RESTART': '1', 09:02:49 INFO - 'PAGER': '/bin/cat', 09:02:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:49 INFO - 'PWD': '/builds/slave/test', 09:02:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:49 INFO - 'SHELL': '/bin/bash', 09:02:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:49 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:49 INFO - 'USER': 'cltbld', 09:02:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:49 INFO - 'XPC_FLAGS': '0x0', 09:02:49 INFO - 'XPC_SERVICE_NAME': '0', 09:02:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 09:02:49 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-xx1W9f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 09:02:49 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 09:02:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 09:02:49 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2nNRUG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-U9aWsb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-HIgf5Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-81ayzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-gCHVEk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-J7V3p8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-I4DBxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-hoJOCN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-UCapcU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 09:02:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 09:02:50 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-KOI7jq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-QKmK6o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-3LhSKm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-DaxeZZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-S5_7cX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-2nHUpI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 09:02:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 09:02:51 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-132dyd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 09:02:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 09:02:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 09:02:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 09:02:54 INFO - Downloading blessings-1.6.tar.gz 09:02:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 09:02:54 INFO - Installing collected packages: blessings 09:02:54 INFO - Running setup.py install for blessings 09:02:54 INFO - Successfully installed blessings 09:02:54 INFO - Cleaning up... 09:02:55 INFO - Return code: 0 09:02:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 09:02:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:55 INFO - Reading from file tmpfile_stdout 09:02:55 INFO - Current package versions: 09:02:55 INFO - blessings == 1.6 09:02:55 INFO - blobuploader == 1.2.4 09:02:55 INFO - docopt == 0.6.1 09:02:55 INFO - functools32 == 3.2.3-2 09:02:55 INFO - jsonschema == 2.5.1 09:02:55 INFO - manifestparser == 1.1 09:02:55 INFO - mozInstall == 1.12 09:02:55 INFO - mozcrash == 0.17 09:02:55 INFO - mozdebug == 0.1 09:02:55 INFO - mozdevice == 0.48 09:02:55 INFO - mozfile == 1.2 09:02:55 INFO - mozhttpd == 0.7 09:02:55 INFO - mozinfo == 0.9 09:02:55 INFO - mozleak == 0.1 09:02:55 INFO - mozlog == 3.4 09:02:55 INFO - moznetwork == 0.27 09:02:55 INFO - mozprocess == 0.23 09:02:55 INFO - mozprofile == 0.28 09:02:55 INFO - mozrunner == 6.12 09:02:55 INFO - mozscreenshot == 0.1 09:02:55 INFO - mozsystemmonitor == 0.3 09:02:55 INFO - moztest == 0.7 09:02:55 INFO - mozversion == 1.4 09:02:55 INFO - psutil == 3.1.1 09:02:55 INFO - requests == 1.2.3 09:02:55 INFO - wsgiref == 0.1.2 09:02:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 09:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:55 INFO - 'HOME': '/Users/cltbld', 09:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:55 INFO - 'LOGNAME': 'cltbld', 09:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:55 INFO - 'MOZ_NO_REMOTE': '1', 09:02:55 INFO - 'NO_EM_RESTART': '1', 09:02:55 INFO - 'PAGER': '/bin/cat', 09:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:55 INFO - 'PWD': '/builds/slave/test', 09:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:55 INFO - 'SHELL': '/bin/bash', 09:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:55 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:55 INFO - 'USER': 'cltbld', 09:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:55 INFO - 'XPC_FLAGS': '0x0', 09:02:55 INFO - 'XPC_SERVICE_NAME': '0', 09:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 09:02:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 09:02:55 INFO - Cleaning up... 09:02:55 INFO - Return code: 0 09:02:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:02:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 09:02:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 09:02:55 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 0x10f11e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f094570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7feebb59eae0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, '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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 09:02:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 09:02:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 09:02:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:02:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:02:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:02:55 INFO - 'HOME': '/Users/cltbld', 09:02:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:02:55 INFO - 'LOGNAME': 'cltbld', 09:02:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:02:55 INFO - 'MOZ_NO_REMOTE': '1', 09:02:55 INFO - 'NO_EM_RESTART': '1', 09:02:55 INFO - 'PAGER': '/bin/cat', 09:02:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:02:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:02:55 INFO - 'PWD': '/builds/slave/test', 09:02:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:02:55 INFO - 'SHELL': '/bin/bash', 09:02:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:02:55 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:02:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:02:55 INFO - 'USER': 'cltbld', 09:02:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:02:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:02:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:02:55 INFO - 'XPC_FLAGS': '0x0', 09:02:55 INFO - 'XPC_SERVICE_NAME': '0', 09:02:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:02:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 09:02:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 09:02:56 INFO - Cleaning up... 09:02:56 INFO - Return code: 0 09:02:56 INFO - Running post-action listener: _resource_record_post_action 09:02:56 INFO - Running post-action listener: _start_resource_monitoring 09:02:56 INFO - Starting resource monitoring. 09:02:56 INFO - [mozharness: 2016-09-28 16:02:56.216957Z] Finished create-virtualenv step (success) 09:02:56 INFO - [mozharness: 2016-09-28 16:02:56.217916Z] Running install step. 09:02:56 INFO - Running pre-action listener: _resource_record_pre_action 09:02:56 INFO - Running main action method: install 09:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 09:02:56 INFO - Reading from file tmpfile_stdout 09:02:56 INFO - Detecting whether we're running mozinstall >=1.0... 09:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 09:02:56 INFO - Reading from file tmpfile_stdout 09:02:56 INFO - Output received: 09:02:56 INFO - Usage: mozinstall [options] installer 09:02:56 INFO - Options: 09:02:56 INFO - -h, --help show this help message and exit 09:02:56 INFO - -d DEST, --destination=DEST 09:02:56 INFO - Directory to install application into. [default: 09:02:56 INFO - "/builds/slave/test"] 09:02:56 INFO - --app=APP Application being installed. [default: firefox] 09:02:56 INFO - mkdir: /builds/slave/test/build/application 09:02:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 09:02:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 09:03:30 INFO - Reading from file tmpfile_stdout 09:03:30 INFO - Output received: 09:03:30 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 09:03:30 INFO - Running post-action listener: _resource_record_post_action 09:03:30 INFO - [mozharness: 2016-09-28 16:03:30.965894Z] Finished install step (success) 09:03:30 INFO - [mozharness: 2016-09-28 16:03:30.966019Z] Running run-tests step. 09:03:30 INFO - Running pre-action listener: _resource_record_pre_action 09:03:30 INFO - Running main action method: run_tests 09:03:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:03:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:03:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:03:30 INFO - 2016-09-28 09:03:30.978 screenresolution[1638:7585] starting screenresolution argv=screenresolution get 09:03:30 INFO - 2016-09-28 09:03:30.980 screenresolution[1638:7585] Display 0: 1600x1200x32@60 09:03:30 INFO - 2016-09-28 09:03:30.985 screenresolution[1639:7587] starting screenresolution argv=screenresolution list 09:03:30 INFO - Available Modes on Display 0 09:03:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:03:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:03:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:03:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:03:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:03:31 INFO - Intel Iris: 09:03:31 INFO - Chipset Model: Intel Iris 09:03:31 INFO - Type: GPU 09:03:31 INFO - Bus: Built-In 09:03:31 INFO - VRAM (Dynamic, Max): 1536 MB 09:03:31 INFO - Vendor: Intel (0x8086) 09:03:31 INFO - Device ID: 0x0a2e 09:03:31 INFO - Revision ID: 0x0009 09:03:31 INFO - Displays: 09:03:31 INFO - Display: 09:03:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:03:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:03:31 INFO - Display Serial Number: 1600x1200 60 09:03:31 INFO - Main Display: Yes 09:03:31 INFO - Mirror: Off 09:03:31 INFO - Online: Yes 09:03:31 INFO - Rotation: Supported 09:03:31 INFO - Return code: 0 09:03:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 09:03:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 09:03:31 INFO - Python 2.7.3 09:03:31 INFO - Return code: 0 09:03:31 INFO - grabbing minidump binary from tooltool 09:03:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 09:03:31 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 0x10ef32d30>, '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 0x10ed58db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10ef2ab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 09:03:31 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 09:03:31 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 09:03:31 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 09:03:31 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 09:03:31 INFO - Return code: 0 09:03:31 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 09:03:31 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 09:03:31 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 09:03:31 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 09:03:31 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 09:03:31 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/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 09:03:31 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/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 09:03:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ek2uRrbO4r/Render', 09:03:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 09:03:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 09:03:31 INFO - 'HOME': '/Users/cltbld', 09:03:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 09:03:31 INFO - 'LOGNAME': 'cltbld', 09:03:31 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 09:03:31 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 09:03:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 09:03:31 INFO - 'MOZ_NO_REMOTE': '1', 09:03:31 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 09:03:31 INFO - 'NO_EM_RESTART': '1', 09:03:31 INFO - 'PAGER': '/bin/cat', 09:03:31 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 09:03:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 09:03:31 INFO - 'PWD': '/builds/slave/test', 09:03:31 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 09:03:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 09:03:31 INFO - 'SHELL': '/bin/bash', 09:03:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.umK3SWJRgJ/Listeners', 09:03:31 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 09:03:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 09:03:31 INFO - 'USER': 'cltbld', 09:03:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 09:03:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 09:03:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 09:03:31 INFO - 'XPC_FLAGS': '0x0', 09:03:31 INFO - 'XPC_SERVICE_NAME': '0', 09:03:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 09:03:31 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/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 09:03:31 INFO - mozversion application_buildid: 20160928065720 09:03:31 INFO - mozversion application_changeset: a15b46a9e10281a0e762e8e62843cb08de839b60 09:03:31 INFO - mozversion application_display_name: Nightly 09:03:31 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 09:03:31 INFO - mozversion application_name: Firefox 09:03:31 INFO - mozversion application_remotingname: firefox 09:03:31 INFO - mozversion application_repository: https://hg.mozilla.org/integration/fx-team 09:03:31 INFO - mozversion application_vendor: Mozilla 09:03:31 INFO - mozversion application_version: 52.0a1 09:03:31 INFO - mozversion platform_buildid: 20160928065720 09:03:31 INFO - mozversion platform_changeset: a15b46a9e10281a0e762e8e62843cb08de839b60 09:03:31 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/fx-team 09:03:31 INFO - mozversion platform_version: 52.0a1 09:03:31 INFO - using testdate: 1475078611 09:03:31 INFO - actual date: 1475078611 09:03:31 INFO - starting webserver on 'localhost:49274' 09:03:31 INFO - SUITE-START | Running 2 tests 09:03:31 INFO - TEST-START | dromaeo_css 09:03:31 INFO - operating with platform_type : mac_ 09:03:31 INFO - Initialising browser for dromaeo_css test... 09:03:31 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpJ9UVMN/profile http://localhost:49274/getInfo.html) 09:03:34 INFO - PROCESS | 1649 | 2016-09-28 09:03:34.442 plugin-container[1651:7748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 09:03:34 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:34 INFO - PROCESS | 1649 | 2016-09-28 09:03:34.445 plugin-container[1651:7748] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b53, name = 'com.apple.CFPasteboardClient' 09:03:34 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:34 INFO - PROCESS | 1649 | 2016-09-28 09:03:34.445 plugin-container[1651:7748] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:34 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 09:03:34 INFO - PROCESS | 1649 | colorDepth:24 09:03:34 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 09:03:34 INFO - PROCESS | 1649 | __metrics 09:03:34 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 09:03:35 INFO - TEST-INFO | 1649: exit 0 09:03:35 INFO - Browser initialized. 09:03:35 INFO - Running cycle 1/1 for dromaeo_css test... 09:03:35 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpJ9UVMN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:03:37 INFO - PROCESS | 1652 | 2016-09-28 09:03:37.558 plugin-container[1653:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8923, name = 'com.apple.tsm.portname' 09:03:37 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:37 INFO - PROCESS | 1652 | 2016-09-28 09:03:37.560 plugin-container[1653:7868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f03, name = 'com.apple.CFPasteboardClient' 09:03:37 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:03:37 INFO - PROCESS | 1652 | 2016-09-28 09:03:37.560 plugin-container[1653:7868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:03:47 INFO - PROCESS | 1652 | RSS: Main: 239976448 09:03:47 INFO - PROCESS | 1652 | 09:04:36 INFO - PROCESS | 1652 | 1475078676919 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 09:06:48 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 09:06:49 INFO - PROCESS | 1652 | RSS: Main: 253370368 09:06:49 INFO - PROCESS | 1652 | 09:09:51 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 09:09:52 INFO - PROCESS | 1652 | RSS: Main: 265609216 09:09:52 INFO - PROCESS | 1652 | 09:12:54 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 09:12:55 INFO - PROCESS | 1652 | RSS: Main: 270286848 09:12:55 INFO - PROCESS | 1652 | 09:15:57 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 09:15:57 INFO - PROCESS | 1652 | RSS: Main: 269713408 09:15:57 INFO - PROCESS | 1652 | 09:19:00 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 09:19:01 INFO - PROCESS | 1652 | RSS: Main: 275103744 09:19:01 INFO - PROCESS | 1652 | 09:22:03 INFO - PROCESS | 1652 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 09:22:04 INFO - PROCESS | 1652 | RSS: Main: 278003712 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | __start_tp_report 09:22:04 INFO - PROCESS | 1652 | _x_x_mozilla_page_load 09:22:04 INFO - PROCESS | 1652 | _x_x_mozilla_page_load_details 09:22:04 INFO - PROCESS | 1652 | |i|pagename|runs| 09:22:04 INFO - PROCESS | 1652 | |0;dojo.html;2925.517289647208;3347.246869454373;3391.9152021199484;3424.7260219182467;3434.3818112739705;4693.296005599158;5550.111982082867;5603.467670571299;5606.103023516238;5650.29371328584;5558.583106267026;5613;5649.604527683064;5657.2928383952;5723.828285151441;4265.253580623386;5136.203888397305;5173.9482605174035;5272.209168624718;5289.259503669489;5090;5275.36695596529;5298.6555873868265;5320.787168513274;5398.78404863805;5099.71951542665;5137.101007323703;5170.793168273283;5182.533571978503;5240.764165612538;5155.381354237516;5193.740312984373;5220.8433746987275;5229.084910140748;5252.474752524755;4192;5068.4171320298265;5150.768215430315;5166.405863325728;5205.115130427836;4160.979195104035;5474.945250547484;5527.889442211173;5615.719214039282;5634.746436410372;6138.817583427356;6145.893739126956;6171.290301615327;6237.719302631393;6252.093446450284;6132.540059495511;6147.877042459126;6160.106784516265;6161.691915404257;6240.4071613196675;5705.030144875381;5768.105943578752;5860.501857342131;5871.3541510433815;5911.290645122613;6108.6456319605295;6130.917941862977;6145.5698101133385;6194.318624951252;6206.844828879314;6097.754818199934;6105.908411373833;6108.450239478379;6112.572119951561;6113.266408031065;6038.969805150946;6054.152418661391;6189.381061893775;6197.211105863674;6235.657038862815;6023.69881505923;6145.955187618116;6183;6220.284667263322;6223.190985171898;6156.045812899008;6203.0075187970615;6214.098955651405;6236.750529978841;6269.871423143878;4745.1695953208055;4856.757162141878;4866.659333846596;4867.075255701406;4890.364252647133;4304.074623955865;4318.913621727548;4326.870193894189;4333.544977777351;4481.865544033684;5752.597318187687;5792.536597072224;5854.151148083589;5855.59010869235;5872.853178670482;6206.621459912228;6275.6862156891975;6282.811515654537;6300;6315.684215789192;5742.942570574324;5775.711214439261;5814.331351894586;5859.20900678403;5906.055031194988;5378.408375078739;5393.191021346751;5395.946040539544;5396.83809485716;5458.9454105458435;5887.558433117533;6133.44798968095;6295.685215739195;6299.275583307795;6301.558890877594;6153.2000839890525;6175.752969881154;6184.567080304336;6212.192414986023;6268.717907694256;5058.468860769566;5897.616654917417;6275.152854364603;6285.842853928689;6325.810225693236;6037;6122.938770612237;6166.075088736726;6209.627422354673;6231.096491008869;6117.480014198665;6151.96924015377;6154.7690461906905;6190.597611155261;6203.410675985774;6117.051856962178;6126.969365153146;6135.907961380493;6138.1713468681155;6145.692715364304;4587.72473651582;4615.676902616785;5076.822311219237;5585.245991791056;6134.263888333428;4739.502352252964;5467.589930755289;5755.482006619424;5839.270091238595;5918.053111502139;6086;6094.939050609614;6109.708332504907;6119.452186160943;6165.198524191826;4960.950390496048;5156.355455568054;5727.7422515986045;5871.823845284648;5932.5550583705535 09:22:04 INFO - PROCESS | 1652 | |1;ext.html;505.8988202359529;511.6316252298345;527.8047122564649;537.7391964897024;541.2044294886518;34932;40055.39916901249;40109.19235653505;40141.197176056536;40192.59807401925;40945;41310.967225819295;41367.79316103423;41373.586264137346;41460.79269603655;9624.80750384992;9797.461139637317;9808.754781130458;9833.409995400263;9834.852477212848;9711.951440242825;9815.607375704998;9834.114929656329;9842.803143937117;9854.01459854016;9705.563249653747;9806.754831129207;9811.558479868387;9834.901650983506;9837.262205334593;9658.275629327793;9665.178459830922;9740.561674724619;9835.409875407498;9849.458279794608;9707.61169553217;9739.220862331067;9750.658726944523;9763.853542196874;9783.119519243302;39835.80082099571;39928;39993.200475966696;40245.79877100596;40270.99322516931;3065.249013991564;3152.3537674776617;3447.172678557128;3519.894403167909;3914.5106861642294;30335;30694.232644183852;31189.84405077983;31201.063968080995;31212.37575248505;3137.592113025315;3314.5028245763087;3377.155711072232;3379.0538649178025;3379.459286514146;383472;385541.07229463954;385904.1409585896;386983.0650846756;387800;56673;57060.71469642708;57098.714506427204;57227;57363.42636573581;51971;59065.70467147637;59315.110273345934;59517.80964380688;59910;15923.761143582855;16034.19829008545;16102.919485402497;16105.355785768672;16123;12613.117081804368;12619.558315458915;12660.683482913;12691.619251422471;12715.745685086247;12100.878991209975;15058.184754553915;15255.847441525664;15271.160086195367;15471.071735695894;3179.570757947694;3281.5898012748407;3288.523164141186;6517.706703198368;6647.5346725729705;855.2644725536034;885.6811547842772;904.2494729374604;937.161240689579;978.6427953796813;27046;29976.100716978526;30109;30198.54702179469;30696.693033069383;982.9852552211722;1000.7140824041218;1024.7950409917896;1039.6309310194772;1041.2138835179542;14576.854231457763;14675.266236688123;14795.704085918222;14849.851501485064;14963.850361496463;650107;668025;669104;669127;669372;53371.466285337425;53744.73127634415;53808;53818.46181538213;53873.461265387625;1231.8728363547432;1258.8552316483722;1351.6418149190472;1389.1317926296065;1394.0590101681528;23305.650415244123;25062.62406063947;27018.32454188661;27168.456630866876;28474;46320.07359852717;46455;46840.297395538415;47050.52949470461;47197.76401117972;519048;587231;612866;616738;687714;37763;42922.57077429186;44642.55357446384;44813.87965300894;45452.77273613611;38965.82831877667;44291;48819.26771098508;49196.26205606991;50370.74814625904;33492;38162.42756358712;40565;40767.59232407638;41513.58486415097;34703.13242168966;38030.61969380381;40283.59716402798;41252.96867578335;41427.171456571305;38733;39422;39854.60145398509;40097.59902400939;40137;34375.82812085924;35025;35684.46473302851;35986.820065899505;36031;26140.085097021514;26248.34379140537;26525;26604.600930985664;26806.463870722866 09:22:04 INFO - PROCESS | 1652 | |2;jquery.html;373.5797228118365;379.81009495252374;383.23544528665315;386.2313995149653;406.3478117621218;18312.725309120357;18726.97001664908;19447.708284375745;20510.6923396149;20647.793522064774;20381.796182038175;20432.69350959733;20733.792662073374;21095.578088438222;21147.47131321718;4779.641526885497;5830.562707796922;5972.6715030673295;6057.848553786146;6102.26772787264;5870.823875283749;5880.470757631812;5900.793472228473;5976.073708575178;6064.363241859607;5522.723863806814;5884.235049443588;5927.466528012478;5952.553558483131;5972.0444728843395;5673.262475878109;5912.408759124096;5948.791792287292;5955.464008239258;5970.984932561453;5009.373828271466;5096.51583099605;5119.0273847968765;5136.998285334361;5143;24721.505569888683;24847.5030499389;25027.62458563123;25508.74491255082;25614;2194.594000109969;2210.7568167501563;2218.73375194976;2357.481354102095;2462.409021834747;6874.175098988104;7429.294217049426;11596.652100437;15677.74578033766;16064.678706425922;1812.3203798575535;1836.3572749537689;1901.3250296144809;1951.8438524917972;1953.2870502266571;109367;111134.33298500528;111214;111479;113781.43109284401;11204.60783872577;11290.661280161608;11363.943180284046;11545.422728863523;11644.88355116455;14161;14179;14184;14184.290785460686;14205.076670016417;8302.335813134934;8329.5418751968;8374.748757537283;8425.325973922072;8435.283000944866;8395.580220988926;8403.873941890877;8414.537200454033;8498.150184981576;8538.103499132625;4426.22541055314;4453.817629361654;4479.238529450001;4499.797509112098;4521.864344069618;1633.8529532342147;1897.6868816645099;1925.2299080367966;1940.8544359173118;1943.7959014303578;830.9459885107449;839.0183485322187;924.5007695844168;924.8104138651561;977.7066879935991;16288.104154271392;17547.21037553313;17715.379961701536;20152.89923550373;21307.14771409126;779.6647441600053;814.5845618734488;918.8621706744042;942.8114377124602;975.9121679048919;10852.186086043579;10924.563017479371;11231.26996745209;11263.211575189658;11283.94358028221;528392;531039;533645;533707;559105;122872;129278.35360823135;130637;131987;133735;956.8277710012264;963.4219468318953;991.0386924683046;991.7223177510309;992.2558081438921;18071.638567228318;18572;18693.15880785395;18736.81263187405;18740.437786866416;53807.73096134494;54000.729996349764;54270.7286463581;54390;54414.727926360116;75950.62024689841;76757;79244.60377698306;79872.8019079726;80119.59940200261;7879.763607091796;7944.44388892772;8004.719834805754;8043.19568043191;8220.83558328842;11714.414279285831;12237.388130593612;12258.754824903273;12507.874921250672;12551.372431378211;10664;10903.509342079784;10912.345259284468;10918.672439826818;11015.228933974866;10689.30519516229;10999.780004399707;11084.44577771092;11110.11119110453;11127.83308250393;10798.838017429587;10872.782544349227;11177.217594768288;11185.217034807485;11276.267042642203;7616.276453736886;7935.166807485131;7947.5231486111015;8047.0745864226365;8070.152633973348;9515.429074255568;10403.791924161626;10439.269251152346;10729.89270107289;10977.615783447542 09:22:04 INFO - PROCESS | 1652 | |3;mootools.html;1568.5451219146448;1590.1969505399775;1780.4925596205085;1838.76096107506;1858.386732378316;7636.9618151909235;8132.75601731949;8421.831563368729;9251.6299348026;9269.582868770913;8666.350023748228;9004.234640055603;9023.233025192867;9126.224270936962;9148.39903016971;2031.6139933412605;2386.2960426674085;2395.568797616428;2415.130553000927;2416.803682177325;2382.761723827612;2403.098837935774;2416;2425.8059355251626;2431.0640403444713;2384.8671880856627;2402.3994001499627;2412.517496500698;2419.322589674894;2432.914847980321;2100.369889033284;2102.4954011037394;2260.0507786729613;2363.326451961191;2376.2158487699103;2359.28041947206;2367.988160059189;2369.9644505332435;2372.406898275431;2372.7034120734907;9094.590743416562;9207.677731279373;9214.216791572724;9273.304502162297;9292.488913109777;5018.222175562793;5073.0107629012355;5182.704323919021;5491.533219676332;5492.286002819608;3426.554547908781;3671.1372827385544;3726.217494326195;3764.4917936078555;3770.4155855842387;1093.240198062347;1098.5111625326733;1100.2628239079836;1108.0526150141593;1117.1788735279563;10434.791304173874;11960.521579136737;12120.212186207957;12217.389130543437;12300.938495307555;4722.184059194176;4743.573078422958;4789.353437285922;4846.418429788378;4881.365422495124;8633.95683021581;10130.594776208869;10236.48817559134;10295.330803497749;10306.969303069633;5428.158635411518;5429.049916264638;5432.456754324536;5440.319960004999;5447.665321996136;4496.842610508616;4613.0543238636;4821.349117869043;4957.603391728653;4992.301077849104;3864.903377415531;3901.453796468497;3974.6025397460603;4001.2197621463533;4032.2741906457118;1281.333706472629;1290.3096943004693;1295.6372215779597;1300.876416740408;1302.2316833068535;778.9454738168387;813.341193633159;852.884860543831;896.394933419939;906.0848542971646;5696.287964004499;5909.497692696083;5953.047512397996;5999.400059993965;6039.456448919618;873.908239634842;909.0409617853096;909.3543584055262;949.6438835436711;953.2278854128178;3637.2361804020925;3933.9575012621676;3980.582038885875;4000.439938408625;4036.2936486114813;10755.25740936072;11259.211855170222;11277;11277.436128193722;11291.983721465107;11181.049610783011;11183.105351571854;11183.944080279709;11227.607033753779;11251.774964500664;7895.486793358414;8310.958445207856;8411.654135338316;8424.4945303282;8459.196376344238;5193.532582067632;5240.659357141773;5309.973450132725;5770.769169233184;5941.554383421347;7677.8464430709955;8252.752417427488;8310.376721746014;9852.310338276251;10082.495875206358;8473.49159050459;8561.1866872647;9808.264380171373;10301.227407944285;10421.322614030063;6968.825779355557;7134.429245660231;8251.546164960984;8324.377040044545;8418.779277004678;7171.856562868609;8149.877751833608;8386.329093672613;8401;8408.117147699404;7556.962215188889;7663.118741344817;7665.961670191613;7732.1494635588915;7781.805454863674;6168.599041062496;7347.485676002629;7524.5861477619255;7591.937128801862;7638.885416718643;7159.498835081703;7189.820254493679;7250.673719682522;7256.346928776436;7258.528195667264;4888.877778055577;4958.504149585013;4986.0277245936695;5141.562967147835;5214.661047031931;4877.463479017376;4981.900361992813;5039.3448851649055;5050.74746262678;5124.180131178993 09:22:04 INFO - PROCESS | 1652 | |4;prototype.html;285.92565932857457;302.780484148992;313.77094720853785;317.16269049708757;319.9520071989201;5101.54086132248;5239.873427213149;5469.398366179727;5569.860753481165;5631.211630371751;5190.896182076356;5244.767479642281;5324.7071411072475;5453.918191227135;5467;3373.987803658905;3791.938257287964;4205.621494065534;4221.7255878367805;4223.838444427776;3712.3688972874675;3870.1679138985114;4110.753354798707;4116.506019277691;4221.058313175943;4121.91756164878;4124.1957818225455;4135.890695745176;4137.648299894513;4149.211649786531;4059.9188016239805;4107;4122.546519882811;4126.669866410695;4145.854895078658;3391.0335554367134;3567.30437564675;3597.2625611749527;3611.9097022574383;3614.6927511161584;5239.685618862881;5319.510537049594;5339.786408543654;5355.758990845421;5426.077566813651;4663.463701674317;4694.967107236403;4729.929051064237;4739.194336962724;4743.905121897577;5460.31746031746;5781.768729250825;5819.039858423355;5846.766129354864;5871.1780350750505;3174.349258402022;3364.4785058316;3850.979490435037;3868.5744568097766;3908.1206728485863;5287.629865909388;5688.857778555528;6063.969680151571;6084.178635884144;6141.263048434216;3674.173311005056;3701.9629803702155;3976.1451287973223;3981.880543583697;3991.1818077294083;3270.394976929276;3854.3062248794927;3995.580464051291;3996.460477835455;4010.19796040793;3735.1782607826235;4622.884427889329;4651.651126165551;4670.509596492388;4670.7664616769025;5153.3558305211845;5766.365699773021;5780;5822.912656310159;5834;1290.728946921157;1300.4524615707278;1325.4565628092437;1365.9180449173082;1384.9930750346384;744.9553026818409;758.6965213914478;825.8802473641408;837.3594200436671;846.3018010141658;4040.616141466556;4100.774457404812;4421.828215522889;4554.203014472454;4793.041391721669;4579.702319349299;4586.078902538348;4954.9752251238515;5028.371453568304;5101.132807422747;4115;4187.72779769314;4212.4523811904255;4501.954980450219;4591.9540804591525;2310.5956457620186;2593.351662084479;2600.8201140641363;2672.5723884178437;2710.783137349007;5107.361579802525;5123.641345105807;5198.6880787152895;5228.82351470916;5307.575393968474;4873.902521949542;4920.827771028068;5211.635185536976;5241.711705856138;5300.284461597712;4762.737699592735;5784.71076446176;6234.438900498977;6237.719302631393;6329.493640508656;5906.497947674324;6113.5414843887775;6149.661768602591;6172.938270617236;6219.47134493555;4820.721310165363;5127.4872512748425;5469.589780766379;5663.801766938137;6086.786962456293;5644.689542075225;6334.651594162366;6346.619202847844;6362.632034112688;6418.967905160444;3424.9315013700084;3704.2961837250837;3776.509053823095;3995.8632473508155;4034.7982600870428;5143.5627971622835;5487.777450132703;5597.860053498695;5641.802536911188;5698.54411647059;4831.73425461603;4919.508049195052;5367.651102678314;5653.862106530914;5932.317783454958;5132.743362831919;5906.586538942232;5954.434328738763;5965.433283838202;5985.354027642433;4889.315495830658;5478.8904221914545;5717.855804073472;5792.073268277056;5798.652080875161;5466.289382380265;5879.882402351844;5886.293644762656;5894.705264736832;5920.911186332122;5090.567301779391;5353.411124776194;5558.998760446155;5743.655380677173;5820 09:22:04 INFO - PROCESS | 1652 | |5;yui.html;656.5076192855358;706.6254884910999;753.5930597477362;771.0824119298035;823.5388182617733;14984.850151498482;15355.155466449341;15768.290426930802;15857;16303.510894673149;14469.421223151061;14709.264536773173;14736.263186840642;14756.926215368934;14794.778078328807;747.9738209162667;856.4261944497171;857.0187135729586;858.6565373850478;860.3633311349619;681.4820736240443;703.6903762344552;829.4152622401193;849.9235068843802;867.6225841758855;859.2095272349422;908.1372695938851;908.6910450446846;908.7410088124885;910.4218821048648;899.370440691512;899.545729406652;900.2033200617468;900.8513595256775;902.1880307723036;633.4235845380728;640.2956747577674;645.1838424393146;669.8660267946382;670.1355251725269;10905.890941090645;14177.007609467342;14724.779128313085;14982.325795339237;15066;11204.93553112453;11597.304161750322;14748.631284217872;14883.47907823221;14892.478763243344;11620.360880151587;11703.414829258503;11742;11813.763724725542;12106.910378066017;3809.3904975204114;3821.159344944108;3822.445745366934;3863.3239183142837;3960.989947563353;114577;118017;119056;122236;122458.77541224562;10957.726056848483;11087.50106245221;11122.777544449227;11218.382988935688;11229.3262404256;22739.431514212276;24256.878715606308;24307.63538546923;24336.756632433448;24384.146554870847;6835.453163746888;8188;8340.582970851434;8457.746267611981;8565.871511927326;4364.759938203366;4427.424434823517;4455.420795296591;4461.420015397977;4482.327650852333;3609.3503169429755;3699.9445008324897;3715.7956312403076;3725.2549490102087;3812.675922546559;683.1870074611197;685.746273878668;687.189116842131;687.2680595166132;692.5636848785334;581.6103210848657;590.5452801342942;614.4746241963146;628.9119523266746;649.337675570915;8988.831451911341;9033.864492032624;9419.199368053654;9594.950866821118;9758.365706229073;573.3492486028388;574.8792753521807;662.3145044878528;666.9229194850282;672.67711498481;4170.249355116048;4685.765711714469;4702.059588082398;4792.496787837227;4835.323054772335;99350;164721;167095.31618735116;168083;168920;112211;134858;135390;135576;140087;112093;117205.8279417212;118206.40896795462;119005;120662;7440.516366436164;7891.921080789349;9111.908880911105;9131.223845973167;9196.770080748034;56112.719436402556;58482;61370.386296138255;62476.75046499135;63811;91072.63391049007;97714.51142744525;97854;98189;99796.50101749445;11776.293422394683;12050.397480125783;12147.804619643868;12248.142630015813;12493;12842.486300547873;12917.160384575349;12956.093073485143;13325.866741332851;13627.386767595284;12031.27812331263;12224.511019559119;12371.566995155126;12511.374431278218;12665.176763510342;11985.760284794429;12179.573714919936;12209.69475763113;12376.195547289397;12624.242545447303;11850.881491184979;12127.211731237441;12208.81686774717;12323.383830808603;12591.24452532851;9464.621415143592;10010.749731256778;10042.096211341015;10307.742306442398;10369.896301036893;6708.8993665094085;7871.577412168925;9701.854472182782;9989.800203996025;10176.379987082264 09:22:04 INFO - PROCESS | 1652 | __end_tp_report 09:22:04 INFO - PROCESS | 1652 | __start_cc_report 09:22:04 INFO - PROCESS | 1652 | _x_x_mozilla_cycle_collect,364 09:22:04 INFO - PROCESS | 1652 | __end_cc_report 09:22:04 INFO - PROCESS | 1652 | __startTimestamp1475079724143__endTimestamp 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | ------- Summary: start ------- 09:22:04 INFO - PROCESS | 1652 | Number of tests: 6 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#0] dojo.html Cycles:165 Average:5635.27 Median:5860.50 stddev:687.78 (11.7%) stddev-sans-first:656.22 09:22:04 INFO - PROCESS | 1652 | Values: 2925.5 3347.2 3391.9 3424.7 3434.4 4693.3 5550.1 5603.5 5606.1 5650.3 5558.6 5613.0 5649.6 5657.3 5723.8 4265.3 5136.2 5173.9 5272.2 5289.3 5090.0 5275.4 5298.7 5320.8 5398.8 5099.7 5137.1 5170.8 5182.5 5240.8 5155.4 5193.7 5220.8 5229.1 5252.5 4192.0 5068.4 5150.8 5166.4 5205.1 4161.0 5474.9 5527.9 5615.7 5634.7 6138.8 6145.9 6171.3 6237.7 6252.1 6132.5 6147.9 6160.1 6161.7 6240.4 5705.0 5768.1 5860.5 5871.4 5911.3 6108.6 6130.9 6145.6 6194.3 6206.8 6097.8 6105.9 6108.5 6112.6 6113.3 6039.0 6054.2 6189.4 6197.2 6235.7 6023.7 6146.0 6183.0 6220.3 6223.2 6156.0 6203.0 6214.1 6236.8 6269.9 4745.2 4856.8 4866.7 4867.1 4890.4 4304.1 4318.9 4326.9 4333.5 4481.9 5752.6 5792.5 5854.2 5855.6 5872.9 6206.6 6275.7 6282.8 6300.0 6315.7 5742.9 5775.7 5814.3 5859.2 5906.1 5378.4 5393.2 5395.9 5396.8 5458.9 5887.6 6133.4 6295.7 6299.3 6301.6 6153.2 6175.8 6184.6 6212.2 6268.7 5058.5 5897.6 6275.2 6285.8 6325.8 6037.0 6122.9 6166.1 6209.6 6231.1 6117.5 6152.0 6154.8 6190.6 6203.4 6117.1 6127.0 6135.9 6138.2 6145.7 4587.7 4615.7 5076.8 5585.2 6134.3 4739.5 5467.6 5755.5 5839.3 5918.1 6086.0 6094.9 6109.7 6119.5 6165.2 4961.0 5156.4 5727.7 5871.8 5932.6 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#1] ext.html Cycles:180 Average:68601.28 Median:40219.20 stddev:151501.73 (376.7%) stddev-sans-first:151840.46 09:22:04 INFO - PROCESS | 1652 | Values: 505.9 511.6 527.8 537.7 541.2 34932.0 40055.4 40109.2 40141.2 40192.6 40945.0 41311.0 41367.8 41373.6 41460.8 9624.8 9797.5 9808.8 9833.4 9834.9 9712.0 9815.6 9834.1 9842.8 9854.0 9705.6 9806.8 9811.6 9834.9 9837.3 9658.3 9665.2 9740.6 9835.4 9849.5 9707.6 9739.2 9750.7 9763.9 9783.1 39835.8 39928.0 39993.2 40245.8 40271.0 3065.2 3152.4 3447.2 3519.9 3914.5 30335.0 30694.2 31189.8 31201.1 31212.4 3137.6 3314.5 3377.2 3379.1 3379.5 383472.0 385541.1 385904.1 386983.1 387800.0 56673.0 57060.7 57098.7 57227.0 57363.4 51971.0 59065.7 59315.1 59517.8 59910.0 15923.8 16034.2 16102.9 16105.4 16123.0 12613.1 12619.6 12660.7 12691.6 12715.7 12100.9 15058.2 15255.8 15271.2 15471.1 3179.6 3281.6 3288.5 6517.7 6647.5 855.3 885.7 904.2 937.2 978.6 27046.0 29976.1 30109.0 30198.5 30696.7 983.0 1000.7 1024.8 1039.6 1041.2 14576.9 14675.3 14795.7 14849.9 14963.9 650107.0 668025.0 669104.0 669127.0 669372.0 53371.5 53744.7 53808.0 53818.5 53873.5 1231.9 1258.9 1351.6 1389.1 1394.1 23305.7 25062.6 27018.3 27168.5 28474.0 46320.1 46455.0 46840.3 47050.5 47197.8 519048.0 587231.0 612866.0 616738.0 687714.0 37763.0 42922.6 44642.6 44813.9 45452.8 38965.8 44291.0 48819.3 49196.3 50370.7 33492.0 38162.4 40565.0 40767.6 41513.6 34703.1 38030.6 40283.6 41253.0 41427.2 38733.0 39422.0 39854.6 40097.6 40137.0 34375.8 35025.0 35684.5 35986.8 36031.0 26140.1 26248.3 26525.0 26604.6 26806.5 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#2] jquery.html Cycles:180 Average:33118.20 Median:25561.37 stddev:90213.82 (352.9%) stddev-sans-first:90433.39 09:22:04 INFO - PROCESS | 1652 | Values: 373.6 379.8 383.2 386.2 406.3 18312.7 18727.0 19447.7 20510.7 20647.8 20381.8 20432.7 20733.8 21095.6 21147.5 4779.6 5830.6 5972.7 6057.8 6102.3 5870.8 5880.5 5900.8 5976.1 6064.4 5522.7 5884.2 5927.5 5952.6 5972.0 5673.3 5912.4 5948.8 5955.5 5971.0 5009.4 5096.5 5119.0 5137.0 5143.0 24721.5 24847.5 25027.6 25508.7 25614.0 2194.6 2210.8 2218.7 2357.5 2462.4 6874.2 7429.3 11596.7 15677.7 16064.7 1812.3 1836.4 1901.3 1951.8 1953.3 109367.0 111134.3 111214.0 111479.0 113781.4 11204.6 11290.7 11363.9 11545.4 11644.9 14161.0 14179.0 14184.0 14184.3 14205.1 8302.3 8329.5 8374.7 8425.3 8435.3 8395.6 8403.9 8414.5 8498.2 8538.1 4426.2 4453.8 4479.2 4499.8 4521.9 1633.9 1897.7 1925.2 1940.9 1943.8 830.9 839.0 924.5 924.8 977.7 16288.1 17547.2 17715.4 20152.9 21307.1 779.7 814.6 918.9 942.8 975.9 10852.2 10924.6 11231.3 11263.2 11283.9 528392.0 531039.0 533645.0 533707.0 559105.0 122872.0 129278.4 130637.0 131987.0 133735.0 956.8 963.4 991.0 991.7 992.3 18071.6 18572.0 18693.2 18736.8 18740.4 53807.7 54000.7 54270.7 54390.0 54414.7 75950.6 76757.0 79244.6 79872.8 80119.6 7879.8 7944.4 8004.7 8043.2 8220.8 11714.4 12237.4 12258.8 12507.9 12551.4 10664.0 10903.5 10912.3 10918.7 11015.2 10689.3 10999.8 11084.4 11110.1 11127.8 10798.8 10872.8 11177.2 11185.2 11276.3 7616.3 7935.2 7947.5 8047.1 8070.2 9515.4 10403.8 10439.3 10729.9 10977.6 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#3] mootools.html Cycles:180 Average:5736.33 Median:4989.16 stddev:3227.85 (64.7%) stddev-sans-first:3221.71 09:22:04 INFO - PROCESS | 1652 | Values: 1568.5 1590.2 1780.5 1838.8 1858.4 7637.0 8132.8 8421.8 9251.6 9269.6 8666.4 9004.2 9023.2 9126.2 9148.4 2031.6 2386.3 2395.6 2415.1 2416.8 2382.8 2403.1 2416.0 2425.8 2431.1 2384.9 2402.4 2412.5 2419.3 2432.9 2100.4 2102.5 2260.1 2363.3 2376.2 2359.3 2368.0 2370.0 2372.4 2372.7 9094.6 9207.7 9214.2 9273.3 9292.5 5018.2 5073.0 5182.7 5491.5 5492.3 3426.6 3671.1 3726.2 3764.5 3770.4 1093.2 1098.5 1100.3 1108.1 1117.2 10434.8 11960.5 12120.2 12217.4 12300.9 4722.2 4743.6 4789.4 4846.4 4881.4 8634.0 10130.6 10236.5 10295.3 10307.0 5428.2 5429.0 5432.5 5440.3 5447.7 4496.8 4613.1 4821.3 4957.6 4992.3 3864.9 3901.5 3974.6 4001.2 4032.3 1281.3 1290.3 1295.6 1300.9 1302.2 778.9 813.3 852.9 896.4 906.1 5696.3 5909.5 5953.0 5999.4 6039.5 873.9 909.0 909.4 949.6 953.2 3637.2 3934.0 3980.6 4000.4 4036.3 10755.3 11259.2 11277.0 11277.4 11292.0 11181.0 11183.1 11183.9 11227.6 11251.8 7895.5 8311.0 8411.7 8424.5 8459.2 5193.5 5240.7 5310.0 5770.8 5941.6 7677.8 8252.8 8310.4 9852.3 10082.5 8473.5 8561.2 9808.3 10301.2 10421.3 6968.8 7134.4 8251.5 8324.4 8418.8 7171.9 8149.9 8386.3 8401.0 8408.1 7557.0 7663.1 7666.0 7732.1 7781.8 6168.6 7347.5 7524.6 7591.9 7638.9 7159.5 7189.8 7250.7 7256.3 7258.5 4888.9 4958.5 4986.0 5141.6 5214.7 4877.5 4981.9 5039.3 5050.7 5124.2 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#4] prototype.html Cycles:180 Average:4540.03 Median:5059.47 stddev:1442.54 (28.5%) stddev-sans-first:1410.81 09:22:04 INFO - PROCESS | 1652 | Values: 285.9 302.8 313.8 317.2 320.0 5101.5 5239.9 5469.4 5569.9 5631.2 5190.9 5244.8 5324.7 5453.9 5467.0 3374.0 3791.9 4205.6 4221.7 4223.8 3712.4 3870.2 4110.8 4116.5 4221.1 4121.9 4124.2 4135.9 4137.6 4149.2 4059.9 4107.0 4122.5 4126.7 4145.9 3391.0 3567.3 3597.3 3611.9 3614.7 5239.7 5319.5 5339.8 5355.8 5426.1 4663.5 4695.0 4729.9 4739.2 4743.9 5460.3 5781.8 5819.0 5846.8 5871.2 3174.3 3364.5 3851.0 3868.6 3908.1 5287.6 5688.9 6064.0 6084.2 6141.3 3674.2 3702.0 3976.1 3981.9 3991.2 3270.4 3854.3 3995.6 3996.5 4010.2 3735.2 4622.9 4651.7 4670.5 4670.8 5153.4 5766.4 5780.0 5822.9 5834.0 1290.7 1300.5 1325.5 1365.9 1385.0 745.0 758.7 825.9 837.4 846.3 4040.6 4100.8 4421.8 4554.2 4793.0 4579.7 4586.1 4955.0 5028.4 5101.1 4115.0 4187.7 4212.5 4502.0 4592.0 2310.6 2593.4 2600.8 2672.6 2710.8 5107.4 5123.6 5198.7 5228.8 5307.6 4873.9 4920.8 5211.6 5241.7 5300.3 4762.7 5784.7 6234.4 6237.7 6329.5 5906.5 6113.5 6149.7 6172.9 6219.5 4820.7 5127.5 5469.6 5663.8 6086.8 5644.7 6334.7 6346.6 6362.6 6419.0 3424.9 3704.3 3776.5 3995.9 4034.8 5143.6 5487.8 5597.9 5641.8 5698.5 4831.7 4919.5 5367.7 5653.9 5932.3 5132.7 5906.6 5954.4 5965.4 5985.4 4889.3 5478.9 5717.9 5792.1 5798.7 5466.3 5879.9 5886.3 5894.7 5920.9 5090.6 5353.4 5559.0 5743.7 5820.0 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - PROCESS | 1652 | [#5] yui.html Cycles:180 Average:25402.48 Median:3821.80 stddev:41807.13 (1093.9%) stddev-sans-first:41883.13 09:22:04 INFO - PROCESS | 1652 | Values: 656.5 706.6 753.6 771.1 823.5 14984.9 15355.2 15768.3 15857.0 16303.5 14469.4 14709.3 14736.3 14756.9 14794.8 748.0 856.4 857.0 858.7 860.4 681.5 703.7 829.4 849.9 867.6 859.2 908.1 908.7 908.7 910.4 899.4 899.5 900.2 900.9 902.2 633.4 640.3 645.2 669.9 670.1 10905.9 14177.0 14724.8 14982.3 15066.0 11204.9 11597.3 14748.6 14883.5 14892.5 11620.4 11703.4 11742.0 11813.8 12106.9 3809.4 3821.2 3822.4 3863.3 3961.0 114577.0 118017.0 119056.0 122236.0 122458.8 10957.7 11087.5 11122.8 11218.4 11229.3 22739.4 24256.9 24307.6 24336.8 24384.1 6835.5 8188.0 8340.6 8457.7 8565.9 4364.8 4427.4 4455.4 4461.4 4482.3 3609.4 3699.9 3715.8 3725.3 3812.7 683.2 685.7 687.2 687.3 692.6 581.6 590.5 614.5 628.9 649.3 8988.8 9033.9 9419.2 9595.0 9758.4 573.3 574.9 662.3 666.9 672.7 4170.2 4685.8 4702.1 4792.5 4835.3 99350.0 164721.0 167095.3 168083.0 168920.0 112211.0 134858.0 135390.0 135576.0 140087.0 112093.0 117205.8 118206.4 119005.0 120662.0 7440.5 7891.9 9111.9 9131.2 9196.8 56112.7 58482.0 61370.4 62476.8 63811.0 91072.6 97714.5 97854.0 98189.0 99796.5 11776.3 12050.4 12147.8 12248.1 12493.0 12842.5 12917.2 12956.1 13325.9 13627.4 12031.3 12224.5 12371.6 12511.4 12665.2 11985.8 12179.6 12209.7 12376.2 12624.2 11850.9 12127.2 12208.8 12323.4 12591.2 9464.6 10010.7 10042.1 10307.7 10369.9 6708.9 7871.6 9701.9 9989.8 10176.4 09:22:04 INFO - PROCESS | 1652 | -------- Summary: end -------- 09:22:04 INFO - PROCESS | 1652 | 09:22:04 INFO - TEST-INFO | 1652: exit 0 09:22:04 INFO - TEST-OK | dromaeo_css | took 1113203ms 09:22:04 INFO - TEST-START | kraken 09:22:04 INFO - operating with platform_type : mac_ 09:22:04 INFO - Initialising browser for kraken test... 09:22:04 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpiIFFTR/profile http://localhost:49274/getInfo.html) 09:22:07 INFO - PROCESS | 1664 | 2016-09-28 09:22:07.339 plugin-container[1665:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x461b, name = 'com.apple.tsm.portname' 09:22:07 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:07 INFO - PROCESS | 1664 | 2016-09-28 09:22:07.343 plugin-container[1665:10288] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c1b, name = 'com.apple.CFPasteboardClient' 09:22:07 INFO - PROCESS | 1664 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:07 INFO - PROCESS | 1664 | 2016-09-28 09:22:07.343 plugin-container[1665:10288] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:07 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 09:22:07 INFO - PROCESS | 1664 | colorDepth:24 09:22:07 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 09:22:07 INFO - PROCESS | 1664 | __metrics 09:22:07 ERROR - PROCESS | 1664 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3067: TypeError: this.tabs is undefined 09:22:08 INFO - TEST-INFO | 1664: exit 0 09:22:08 INFO - Browser initialized. 09:22:08 INFO - Running cycle 1/1 for kraken test... 09:22:08 INFO - TEST-INFO | started process 1666 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpiIFFTR/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:22:10 INFO - PROCESS | 1666 | 2016-09-28 09:22:10.813 plugin-container[1667:10403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8723, name = 'com.apple.tsm.portname' 09:22:10 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:10 INFO - PROCESS | 1666 | 2016-09-28 09:22:10.818 plugin-container[1667:10403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e03, name = 'com.apple.CFPasteboardClient' 09:22:10 INFO - PROCESS | 1666 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:22:10 INFO - PROCESS | 1666 | 2016-09-28 09:22:10.818 plugin-container[1667:10403] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:22:20 INFO - PROCESS | 1666 | RSS: Main: 240078848 09:22:20 INFO - PROCESS | 1666 | 09:22:23 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 09:22:24 INFO - PROCESS | 1666 | RSS: Main: 253521920 09:22:24 INFO - PROCESS | 1666 | 09:22:27 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 09:22:27 INFO - PROCESS | 1666 | RSS: Main: 262098944 09:22:27 INFO - PROCESS | 1666 | 09:22:31 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 09:22:31 INFO - PROCESS | 1666 | RSS: Main: 262148096 09:22:31 INFO - PROCESS | 1666 | 09:22:33 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 09:22:34 INFO - PROCESS | 1666 | RSS: Main: 264261632 09:22:34 INFO - PROCESS | 1666 | 09:22:36 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:22:36 INFO - PROCESS | 1666 | RSS: Main: 264622080 09:22:36 INFO - PROCESS | 1666 | 09:22:39 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 09:22:40 INFO - PROCESS | 1666 | RSS: Main: 265248768 09:22:40 INFO - PROCESS | 1666 | 09:22:43 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 09:22:44 INFO - PROCESS | 1666 | RSS: Main: 265392128 09:22:44 INFO - PROCESS | 1666 | 09:22:47 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 09:22:47 INFO - PROCESS | 1666 | RSS: Main: 265773056 09:22:47 INFO - PROCESS | 1666 | 09:22:49 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:22:50 INFO - PROCESS | 1666 | RSS: Main: 265863168 09:22:50 INFO - PROCESS | 1666 | 09:22:51 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:22:52 INFO - PROCESS | 1666 | RSS: Main: 265371648 09:22:52 INFO - PROCESS | 1666 | 09:22:54 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:22:55 INFO - PROCESS | 1666 | RSS: Main: 265519104 09:22:55 INFO - PROCESS | 1666 | 09:22:58 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:22:59 INFO - PROCESS | 1666 | RSS: Main: 266055680 09:22:59 INFO - PROCESS | 1666 | 09:23:02 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:23:02 INFO - PROCESS | 1666 | RSS: Main: 265617408 09:23:02 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 09:23:04 INFO - PROCESS | 1666 | RSS: Main: 266940416 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | __start_tp_report 09:23:04 INFO - PROCESS | 1666 | _x_x_mozilla_page_load 09:23:04 INFO - PROCESS | 1666 | _x_x_mozilla_page_load_details 09:23:04 INFO - PROCESS | 1666 | |i|pagename|runs| 09:23:04 INFO - PROCESS | 1666 | |0;ai-astar;114;118;125;172;164;116;116;117;118;116 09:23:04 INFO - PROCESS | 1666 | |1;audio-beat-detection;139;113;113;139;134;102;143;142;178;119 09:23:04 INFO - PROCESS | 1666 | |2;audio-dft;210;237;196;198;199;202;198;197;196;196 09:23:04 INFO - PROCESS | 1666 | |3;audio-fft;82;58;60;74;76;81;86;81;97;59 09:23:04 INFO - PROCESS | 1666 | |4;audio-oscillator;86;71;107;73;72;71;88;69;72;86 09:23:04 INFO - PROCESS | 1666 | |5;imaging-gaussian-blur;89;88;90;87;90;88;98;119;88;89 09:23:04 INFO - PROCESS | 1666 | |6;imaging-darkroom;91;100;107;102;129;107;93;153;151;91 09:23:04 INFO - PROCESS | 1666 | |7;imaging-desaturate;145;140;101;149;138;89;150;142;94;91 09:23:04 INFO - PROCESS | 1666 | |8;json-parse-financial;56;55;57;55;57;56;55;56;54;58 09:23:04 INFO - PROCESS | 1666 | |9;json-stringify-tinderbox;68;79;76;79;73;68;72;70;93;59 09:23:04 INFO - PROCESS | 1666 | |10;stanford-crypto-aes;91;99;114;109;110;103;84;88;88;86 09:23:04 INFO - PROCESS | 1666 | |11;stanford-crypto-ccm;145;172;142;136;137;155;132;159;206;157 09:23:04 INFO - PROCESS | 1666 | |12;stanford-crypto-pbkdf2;174;174;170;216;204;173;173;171;167;191 09:23:04 INFO - PROCESS | 1666 | |13;stanford-crypto-sha256-iterative;66;70;69;71;90;68;68;67;67;69 09:23:04 INFO - PROCESS | 1666 | __end_tp_report 09:23:04 INFO - PROCESS | 1666 | __start_cc_report 09:23:04 INFO - PROCESS | 1666 | _x_x_mozilla_cycle_collect,928 09:23:04 INFO - PROCESS | 1666 | __end_cc_report 09:23:04 INFO - PROCESS | 1666 | __startTimestamp1475079784839__endTimestamp 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | ------- Summary: start ------- 09:23:04 INFO - PROCESS | 1666 | Number of tests: 14 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#0] ai-astar Cycles:10 Average:127.60 Median:118.00 stddev:21.57 (18.3%) stddev-sans-first:22.31 09:23:04 INFO - PROCESS | 1666 | Values: 114.0 118.0 125.0 172.0 164.0 116.0 116.0 117.0 118.0 116.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#1] audio-beat-detection Cycles:10 Average:132.20 Median:139.00 stddev:21.68 (15.6%) stddev-sans-first:22.85 09:23:04 INFO - PROCESS | 1666 | Values: 139.0 113.0 113.0 139.0 134.0 102.0 143.0 142.0 178.0 119.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#2] audio-dft Cycles:10 Average:202.90 Median:198.50 stddev:12.71 (6.4%) stddev-sans-first:13.22 09:23:04 INFO - PROCESS | 1666 | Values: 210.0 237.0 196.0 198.0 199.0 202.0 198.0 197.0 196.0 196.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#3] audio-fft Cycles:10 Average:75.40 Median:81.00 stddev:12.89 (15.9%) stddev-sans-first:13.45 09:23:04 INFO - PROCESS | 1666 | Values: 82.0 58.0 60.0 74.0 76.0 81.0 86.0 81.0 97.0 59.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#4] audio-oscillator Cycles:10 Average:79.50 Median:72.50 stddev:12.12 (16.7%) stddev-sans-first:12.63 09:23:04 INFO - PROCESS | 1666 | Values: 86.0 71.0 107.0 73.0 72.0 71.0 88.0 69.0 72.0 86.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#5] imaging-gaussian-blur Cycles:10 Average:92.60 Median:89.00 stddev:9.78 (11.0%) stddev-sans-first:10.28 09:23:04 INFO - PROCESS | 1666 | Values: 89.0 88.0 90.0 87.0 90.0 88.0 98.0 119.0 88.0 89.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#6] imaging-darkroom Cycles:10 Average:112.40 Median:152.00 stddev:23.63 (15.5%) stddev-sans-first:23.76 09:23:04 INFO - PROCESS | 1666 | Values: 91.0 100.0 107.0 102.0 129.0 107.0 93.0 153.0 151.0 91.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#7] imaging-desaturate Cycles:10 Average:123.90 Median:149.50 stddev:26.38 (17.6%) stddev-sans-first:26.85 09:23:04 INFO - PROCESS | 1666 | Values: 145.0 140.0 101.0 149.0 138.0 89.0 150.0 142.0 94.0 91.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#8] json-parse-financial Cycles:10 Average:55.90 Median:56.00 stddev:1.20 (2.1%) stddev-sans-first:1.27 09:23:04 INFO - PROCESS | 1666 | Values: 56.0 55.0 57.0 55.0 57.0 56.0 55.0 56.0 54.0 58.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#9] json-stringify-tinderbox Cycles:10 Average:73.70 Median:74.50 stddev:9.02 (12.1%) stddev-sans-first:9.33 09:23:04 INFO - PROCESS | 1666 | Values: 68.0 79.0 76.0 79.0 73.0 68.0 72.0 70.0 93.0 59.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:87.00 stddev:11.20 (12.9%) stddev-sans-first:11.66 09:23:04 INFO - PROCESS | 1666 | Values: 91.0 99.0 114.0 109.0 110.0 103.0 84.0 88.0 88.0 86.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#11] stanford-crypto-ccm Cycles:10 Average:154.10 Median:156.00 stddev:22.07 (14.1%) stddev-sans-first:23.16 09:23:04 INFO - PROCESS | 1666 | Values: 145.0 172.0 142.0 136.0 137.0 155.0 132.0 159.0 206.0 157.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.30 Median:174.00 stddev:16.65 (9.6%) stddev-sans-first:17.45 09:23:04 INFO - PROCESS | 1666 | Values: 174.0 174.0 170.0 216.0 204.0 173.0 173.0 171.0 167.0 191.0 09:23:04 INFO - PROCESS | 1666 | 09:23:04 INFO - PROCESS | 1666 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:70.50 Median:69.00 stddev:7.01 (10.2%) stddev-sans-first:7.25 09:23:04 INFO - PROCESS | 1666 | Values: 66.0 70.0 69.0 71.0 90.0 68.0 68.0 67.0 67.0 69.0 09:23:04 INFO - PROCESS | 1666 | -------- Summary: end -------- 09:23:04 INFO - PROCESS | 1666 | 09:23:05 INFO - TEST-INFO | 1666: exit 0 09:23:05 INFO - TEST-OK | kraken | took 60895ms 09:23:05 INFO - SUITE-END | took 1174s 09:23:06 INFO - Completed test suite (00:19:34) 09:23:06 INFO - javascript benchmark 09:23:06 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7280.321679581342, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2925.517289647208, 3347.246869454373, 3391.9152021199484, 3424.7260219182467, 3434.3818112739705, 4693.296005599158, 5550.111982082867, 5603.467670571299, 5606.103023516238, 5650.29371328584, 5558.583106267026, 5613.0, 5649.604527683064, 5657.2928383952, 5723.828285151441, 4265.253580623386, 5136.203888397305, 5173.9482605174035, 5272.209168624718, 5289.259503669489, 5090.0, 5275.36695596529, 5298.6555873868265, 5320.787168513274, 5398.78404863805, 5099.71951542665, 5137.101007323703, 5170.793168273283, 5182.533571978503, 5240.764165612538, 5155.381354237516, 5193.740312984373, 5220.8433746987275, 5229.084910140748, 5252.474752524755, 4192.0, 5068.4171320298265, 5150.768215430315, 5166.405863325728, 5205.115130427836, 4160.979195104035, 5474.945250547484, 5527.889442211173, 5615.719214039282, 5634.746436410372, 6138.817583427356, 6145.893739126956, 6171.290301615327, 6237.719302631393, 6252.093446450284, 6132.540059495511, 6147.877042459126, 6160.106784516265, 6161.691915404257, 6240.4071613196675, 5705.030144875381, 5768.105943578752, 5860.501857342131, 5871.3541510433815, 5911.290645122613, 6108.6456319605295, 6130.917941862977, 6145.5698101133385, 6194.318624951252, 6206.844828879314, 6097.754818199934, 6105.908411373833, 6108.450239478379, 6112.572119951561, 6113.266408031065, 6038.969805150946, 6054.152418661391, 6189.381061893775, 6197.211105863674, 6235.657038862815, 6023.69881505923, 6145.955187618116, 6183.0, 6220.284667263322, 6223.190985171898, 6156.045812899008, 6203.0075187970615, 6214.098955651405, 6236.750529978841, 6269.871423143878, 4745.1695953208055, 4856.757162141878, 4866.659333846596, 4867.075255701406, 4890.364252647133, 4304.074623955865, 4318.913621727548, 4326.870193894189, 4333.544977777351, 4481.865544033684, 5752.597318187687, 5792.536597072224, 5854.151148083589, 5855.59010869235, 5872.853178670482, 6206.621459912228, 6275.6862156891975, 6282.811515654537, 6300.0, 6315.684215789192, 5742.942570574324, 5775.711214439261, 5814.331351894586, 5859.20900678403, 5906.055031194988, 5378.408375078739, 5393.191021346751, 5395.946040539544, 5396.83809485716, 5458.9454105458435, 5887.558433117533, 6133.44798968095, 6295.685215739195, 6299.275583307795, 6301.558890877594, 6153.2000839890525, 6175.752969881154, 6184.567080304336, 6212.192414986023, 6268.717907694256, 5058.468860769566, 5897.616654917417, 6275.152854364603, 6285.842853928689, 6325.810225693236, 6037.0, 6122.938770612237, 6166.075088736726, 6209.627422354673, 6231.096491008869, 6117.480014198665, 6151.96924015377, 6154.7690461906905, 6190.597611155261, 6203.410675985774, 6117.051856962178, 6126.969365153146, 6135.907961380493, 6138.1713468681155, 6145.692715364304, 4587.72473651582, 4615.676902616785, 5076.822311219237, 5585.245991791056, 6134.263888333428, 4739.502352252964, 5467.589930755289, 5755.482006619424, 5839.270091238595, 5918.053111502139, 6086.0, 6094.939050609614, 6109.708332504907, 6119.452186160943, 6165.198524191826, 4960.950390496048, 5156.355455568054, 5727.7422515986045, 5871.823845284648, 5932.5550583705535], "value": 5592.0075912742805, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [505.8988202359529, 511.6316252298345, 527.8047122564649, 537.7391964897024, 541.2044294886518, 34932.0, 40055.39916901249, 40109.19235653505, 40141.197176056536, 40192.59807401925, 40945.0, 41310.967225819295, 41367.79316103423, 41373.586264137346, 41460.79269603655, 9624.80750384992, 9797.461139637317, 9808.754781130458, 9833.409995400263, 9834.852477212848, 9711.951440242825, 9815.607375704998, 9834.114929656329, 9842.803143937117, 9854.01459854016, 9705.563249653747, 9806.754831129207, 9811.558479868387, 9834.901650983506, 9837.262205334593, 9658.275629327793, 9665.178459830922, 9740.561674724619, 9835.409875407498, 9849.458279794608, 9707.61169553217, 9739.220862331067, 9750.658726944523, 9763.853542196874, 9783.119519243302, 39835.80082099571, 39928.0, 39993.200475966696, 40245.79877100596, 40270.99322516931, 3065.249013991564, 3152.3537674776617, 3447.172678557128, 3519.894403167909, 3914.5106861642294, 30335.0, 30694.232644183852, 31189.84405077983, 31201.063968080995, 31212.37575248505, 3137.592113025315, 3314.5028245763087, 3377.155711072232, 3379.0538649178025, 3379.459286514146, 383472.0, 385541.07229463954, 385904.1409585896, 386983.0650846756, 387800.0, 56673.0, 57060.71469642708, 57098.714506427204, 57227.0, 57363.42636573581, 51971.0, 59065.70467147637, 59315.110273345934, 59517.80964380688, 59910.0, 15923.761143582855, 16034.19829008545, 16102.919485402497, 16105.355785768672, 16123.0, 12613.117081804368, 12619.558315458915, 12660.683482913, 12691.619251422471, 12715.745685086247, 12100.878991209975, 15058.184754553915, 15255.847441525664, 15271.160086195367, 15471.071735695894, 3179.570757947694, 3281.5898012748407, 3288.523164141186, 6517.706703198368, 6647.5346725729705, 855.2644725536034, 885.6811547842772, 904.2494729374604, 937.161240689579, 978.6427953796813, 27046.0, 29976.100716978526, 30109.0, 30198.54702179469, 30696.693033069383, 982.9852552211722, 1000.7140824041218, 1024.7950409917896, 1039.6309310194772, 1041.2138835179542, 14576.854231457763, 14675.266236688123, 14795.704085918222, 14849.851501485064, 14963.850361496463, 650107.0, 668025.0, 669104.0, 669127.0, 669372.0, 53371.466285337425, 53744.73127634415, 53808.0, 53818.46181538213, 53873.461265387625, 1231.8728363547432, 1258.8552316483722, 1351.6418149190472, 1389.1317926296065, 1394.0590101681528, 23305.650415244123, 25062.62406063947, 27018.32454188661, 27168.456630866876, 28474.0, 46320.07359852717, 46455.0, 46840.297395538415, 47050.52949470461, 47197.76401117972, 519048.0, 587231.0, 612866.0, 616738.0, 687714.0, 37763.0, 42922.57077429186, 44642.55357446384, 44813.87965300894, 45452.77273613611, 38965.82831877667, 44291.0, 48819.26771098508, 49196.26205606991, 50370.74814625904, 33492.0, 38162.42756358712, 40565.0, 40767.59232407638, 41513.58486415097, 34703.13242168966, 38030.61969380381, 40283.59716402798, 41252.96867578335, 41427.171456571305, 38733.0, 39422.0, 39854.60145398509, 40097.59902400939, 40137.0, 34375.82812085924, 35025.0, 35684.46473302851, 35986.820065899505, 36031.0, 26140.085097021514, 26248.34379140537, 26525.0, 26604.600930985664, 26806.463870722866], "value": 19544.53104065828, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [373.5797228118365, 379.81009495252374, 383.23544528665315, 386.2313995149653, 406.3478117621218, 18312.725309120357, 18726.97001664908, 19447.708284375745, 20510.6923396149, 20647.793522064774, 20381.796182038175, 20432.69350959733, 20733.792662073374, 21095.578088438222, 21147.47131321718, 4779.641526885497, 5830.562707796922, 5972.6715030673295, 6057.848553786146, 6102.26772787264, 5870.823875283749, 5880.470757631812, 5900.793472228473, 5976.073708575178, 6064.363241859607, 5522.723863806814, 5884.235049443588, 5927.466528012478, 5952.553558483131, 5972.0444728843395, 5673.262475878109, 5912.408759124096, 5948.791792287292, 5955.464008239258, 5970.984932561453, 5009.373828271466, 5096.51583099605, 5119.0273847968765, 5136.998285334361, 5143.0, 24721.505569888683, 24847.5030499389, 25027.62458563123, 25508.74491255082, 25614.0, 2194.594000109969, 2210.7568167501563, 2218.73375194976, 2357.481354102095, 2462.409021834747, 6874.175098988104, 7429.294217049426, 11596.652100437, 15677.74578033766, 16064.678706425922, 1812.3203798575535, 1836.3572749537689, 1901.3250296144809, 1951.8438524917972, 1953.2870502266571, 109367.0, 111134.33298500528, 111214.0, 111479.0, 113781.43109284401, 11204.60783872577, 11290.661280161608, 11363.943180284046, 11545.422728863523, 11644.88355116455, 14161.0, 14179.0, 14184.0, 14184.290785460686, 14205.076670016417, 8302.335813134934, 8329.5418751968, 8374.748757537283, 8425.325973922072, 8435.283000944866, 8395.580220988926, 8403.873941890877, 8414.537200454033, 8498.150184981576, 8538.103499132625, 4426.22541055314, 4453.817629361654, 4479.238529450001, 4499.797509112098, 4521.864344069618, 1633.8529532342147, 1897.6868816645099, 1925.2299080367966, 1940.8544359173118, 1943.7959014303578, 830.9459885107449, 839.0183485322187, 924.5007695844168, 924.8104138651561, 977.7066879935991, 16288.104154271392, 17547.21037553313, 17715.379961701536, 20152.89923550373, 21307.14771409126, 779.6647441600053, 814.5845618734488, 918.8621706744042, 942.8114377124602, 975.9121679048919, 10852.186086043579, 10924.563017479371, 11231.26996745209, 11263.211575189658, 11283.94358028221, 528392.0, 531039.0, 533645.0, 533707.0, 559105.0, 122872.0, 129278.35360823135, 130637.0, 131987.0, 133735.0, 956.8277710012264, 963.4219468318953, 991.0386924683046, 991.7223177510309, 992.2558081438921, 18071.638567228318, 18572.0, 18693.15880785395, 18736.81263187405, 18740.437786866416, 53807.73096134494, 54000.729996349764, 54270.7286463581, 54390.0, 54414.727926360116, 75950.62024689841, 76757.0, 79244.60377698306, 79872.8019079726, 80119.59940200261, 7879.763607091796, 7944.44388892772, 8004.719834805754, 8043.19568043191, 8220.83558328842, 11714.414279285831, 12237.388130593612, 12258.754824903273, 12507.874921250672, 12551.372431378211, 10664.0, 10903.509342079784, 10912.345259284468, 10918.672439826818, 11015.228933974866, 10689.30519516229, 10999.780004399707, 11084.44577771092, 11110.11119110453, 11127.83308250393, 10798.838017429587, 10872.782544349227, 11177.217594768288, 11185.217034807485, 11276.267042642203, 7616.276453736886, 7935.166807485131, 7947.5231486111015, 8047.0745864226365, 8070.152633973348, 9515.429074255568, 10403.791924161626, 10439.269251152346, 10729.89270107289, 10977.615783447542], "value": 9428.752134117149, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1568.5451219146448, 1590.1969505399775, 1780.4925596205085, 1838.76096107506, 1858.386732378316, 7636.9618151909235, 8132.75601731949, 8421.831563368729, 9251.6299348026, 9269.582868770913, 8666.350023748228, 9004.234640055603, 9023.233025192867, 9126.224270936962, 9148.39903016971, 2031.6139933412605, 2386.2960426674085, 2395.568797616428, 2415.130553000927, 2416.803682177325, 2382.761723827612, 2403.098837935774, 2416.0, 2425.8059355251626, 2431.0640403444713, 2384.8671880856627, 2402.3994001499627, 2412.517496500698, 2419.322589674894, 2432.914847980321, 2100.369889033284, 2102.4954011037394, 2260.0507786729613, 2363.326451961191, 2376.2158487699103, 2359.28041947206, 2367.988160059189, 2369.9644505332435, 2372.406898275431, 2372.7034120734907, 9094.590743416562, 9207.677731279373, 9214.216791572724, 9273.304502162297, 9292.488913109777, 5018.222175562793, 5073.0107629012355, 5182.704323919021, 5491.533219676332, 5492.286002819608, 3426.554547908781, 3671.1372827385544, 3726.217494326195, 3764.4917936078555, 3770.4155855842387, 1093.240198062347, 1098.5111625326733, 1100.2628239079836, 1108.0526150141593, 1117.1788735279563, 10434.791304173874, 11960.521579136737, 12120.212186207957, 12217.389130543437, 12300.938495307555, 4722.184059194176, 4743.573078422958, 4789.353437285922, 4846.418429788378, 4881.365422495124, 8633.95683021581, 10130.594776208869, 10236.48817559134, 10295.330803497749, 10306.969303069633, 5428.158635411518, 5429.049916264638, 5432.456754324536, 5440.319960004999, 5447.665321996136, 4496.842610508616, 4613.0543238636, 4821.349117869043, 4957.603391728653, 4992.301077849104, 3864.903377415531, 3901.453796468497, 3974.6025397460603, 4001.2197621463533, 4032.2741906457118, 1281.333706472629, 1290.3096943004693, 1295.6372215779597, 1300.876416740408, 1302.2316833068535, 778.9454738168387, 813.341193633159, 852.884860543831, 896.394933419939, 906.0848542971646, 5696.287964004499, 5909.497692696083, 5953.047512397996, 5999.400059993965, 6039.456448919618, 873.908239634842, 909.0409617853096, 909.3543584055262, 949.6438835436711, 953.2278854128178, 3637.2361804020925, 3933.9575012621676, 3980.582038885875, 4000.439938408625, 4036.2936486114813, 10755.25740936072, 11259.211855170222, 11277.0, 11277.436128193722, 11291.983721465107, 11181.049610783011, 11183.105351571854, 11183.944080279709, 11227.607033753779, 11251.774964500664, 7895.486793358414, 8310.958445207856, 8411.654135338316, 8424.4945303282, 8459.196376344238, 5193.532582067632, 5240.659357141773, 5309.973450132725, 5770.769169233184, 5941.554383421347, 7677.8464430709955, 8252.752417427488, 8310.376721746014, 9852.310338276251, 10082.495875206358, 8473.49159050459, 8561.1866872647, 9808.264380171373, 10301.227407944285, 10421.322614030063, 6968.825779355557, 7134.429245660231, 8251.546164960984, 8324.377040044545, 8418.779277004678, 7171.856562868609, 8149.877751833608, 8386.329093672613, 8401.0, 8408.117147699404, 7556.962215188889, 7663.118741344817, 7665.961670191613, 7732.1494635588915, 7781.805454863674, 6168.599041062496, 7347.485676002629, 7524.5861477619255, 7591.937128801862, 7638.885416718643, 7159.498835081703, 7189.820254493679, 7250.673719682522, 7256.346928776436, 7258.528195667264, 4888.877778055577, 4958.504149585013, 4986.0277245936695, 5141.562967147835, 5214.661047031931, 4877.463479017376, 4981.900361992813, 5039.3448851649055, 5050.74746262678, 5124.180131178993], "value": 4610.16198394583, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [285.92565932857457, 302.780484148992, 313.77094720853785, 317.16269049708757, 319.9520071989201, 5101.54086132248, 5239.873427213149, 5469.398366179727, 5569.860753481165, 5631.211630371751, 5190.896182076356, 5244.767479642281, 5324.7071411072475, 5453.918191227135, 5467.0, 3373.987803658905, 3791.938257287964, 4205.621494065534, 4221.7255878367805, 4223.838444427776, 3712.3688972874675, 3870.1679138985114, 4110.753354798707, 4116.506019277691, 4221.058313175943, 4121.91756164878, 4124.1957818225455, 4135.890695745176, 4137.648299894513, 4149.211649786531, 4059.9188016239805, 4107.0, 4122.546519882811, 4126.669866410695, 4145.854895078658, 3391.0335554367134, 3567.30437564675, 3597.2625611749527, 3611.9097022574383, 3614.6927511161584, 5239.685618862881, 5319.510537049594, 5339.786408543654, 5355.758990845421, 5426.077566813651, 4663.463701674317, 4694.967107236403, 4729.929051064237, 4739.194336962724, 4743.905121897577, 5460.31746031746, 5781.768729250825, 5819.039858423355, 5846.766129354864, 5871.1780350750505, 3174.349258402022, 3364.4785058316, 3850.979490435037, 3868.5744568097766, 3908.1206728485863, 5287.629865909388, 5688.857778555528, 6063.969680151571, 6084.178635884144, 6141.263048434216, 3674.173311005056, 3701.9629803702155, 3976.1451287973223, 3981.880543583697, 3991.1818077294083, 3270.394976929276, 3854.3062248794927, 3995.580464051291, 3996.460477835455, 4010.19796040793, 3735.1782607826235, 4622.884427889329, 4651.651126165551, 4670.509596492388, 4670.7664616769025, 5153.3558305211845, 5766.365699773021, 5780.0, 5822.912656310159, 5834.0, 1290.728946921157, 1300.4524615707278, 1325.4565628092437, 1365.9180449173082, 1384.9930750346384, 744.9553026818409, 758.6965213914478, 825.8802473641408, 837.3594200436671, 846.3018010141658, 4040.616141466556, 4100.774457404812, 4421.828215522889, 4554.203014472454, 4793.041391721669, 4579.702319349299, 4586.078902538348, 4954.9752251238515, 5028.371453568304, 5101.132807422747, 4115.0, 4187.72779769314, 4212.4523811904255, 4501.954980450219, 4591.9540804591525, 2310.5956457620186, 2593.351662084479, 2600.8201140641363, 2672.5723884178437, 2710.783137349007, 5107.361579802525, 5123.641345105807, 5198.6880787152895, 5228.82351470916, 5307.575393968474, 4873.902521949542, 4920.827771028068, 5211.635185536976, 5241.711705856138, 5300.284461597712, 4762.737699592735, 5784.71076446176, 6234.438900498977, 6237.719302631393, 6329.493640508656, 5906.497947674324, 6113.5414843887775, 6149.661768602591, 6172.938270617236, 6219.47134493555, 4820.721310165363, 5127.4872512748425, 5469.589780766379, 5663.801766938137, 6086.786962456293, 5644.689542075225, 6334.651594162366, 6346.619202847844, 6362.632034112688, 6418.967905160444, 3424.9315013700084, 3704.2961837250837, 3776.509053823095, 3995.8632473508155, 4034.7982600870428, 5143.5627971622835, 5487.777450132703, 5597.860053498695, 5641.802536911188, 5698.54411647059, 4831.73425461603, 4919.508049195052, 5367.651102678314, 5653.862106530914, 5932.317783454958, 5132.743362831919, 5906.586538942232, 5954.434328738763, 5965.433283838202, 5985.354027642433, 4889.315495830658, 5478.8904221914545, 5717.855804073472, 5792.073268277056, 5798.652080875161, 5466.289382380265, 5879.882402351844, 5886.293644762656, 5894.705264736832, 5920.911186332122, 5090.567301779391, 5353.411124776194, 5558.998760446155, 5743.655380677173, 5820.0], "value": 4074.9759772983452, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [656.5076192855358, 706.6254884910999, 753.5930597477362, 771.0824119298035, 823.5388182617733, 14984.850151498482, 15355.155466449341, 15768.290426930802, 15857.0, 16303.510894673149, 14469.421223151061, 14709.264536773173, 14736.263186840642, 14756.926215368934, 14794.778078328807, 747.9738209162667, 856.4261944497171, 857.0187135729586, 858.6565373850478, 860.3633311349619, 681.4820736240443, 703.6903762344552, 829.4152622401193, 849.9235068843802, 867.6225841758855, 859.2095272349422, 908.1372695938851, 908.6910450446846, 908.7410088124885, 910.4218821048648, 899.370440691512, 899.545729406652, 900.2033200617468, 900.8513595256775, 902.1880307723036, 633.4235845380728, 640.2956747577674, 645.1838424393146, 669.8660267946382, 670.1355251725269, 10905.890941090645, 14177.007609467342, 14724.779128313085, 14982.325795339237, 15066.0, 11204.93553112453, 11597.304161750322, 14748.631284217872, 14883.47907823221, 14892.478763243344, 11620.360880151587, 11703.414829258503, 11742.0, 11813.763724725542, 12106.910378066017, 3809.3904975204114, 3821.159344944108, 3822.445745366934, 3863.3239183142837, 3960.989947563353, 114577.0, 118017.0, 119056.0, 122236.0, 122458.77541224562, 10957.726056848483, 11087.50106245221, 11122.777544449227, 11218.382988935688, 11229.3262404256, 22739.431514212276, 24256.878715606308, 24307.63538546923, 24336.756632433448, 24384.146554870847, 6835.453163746888, 8188.0, 8340.582970851434, 8457.746267611981, 8565.871511927326, 4364.759938203366, 4427.424434823517, 4455.420795296591, 4461.420015397977, 4482.327650852333, 3609.3503169429755, 3699.9445008324897, 3715.7956312403076, 3725.2549490102087, 3812.675922546559, 683.1870074611197, 685.746273878668, 687.189116842131, 687.2680595166132, 692.5636848785334, 581.6103210848657, 590.5452801342942, 614.4746241963146, 628.9119523266746, 649.337675570915, 8988.831451911341, 9033.864492032624, 9419.199368053654, 9594.950866821118, 9758.365706229073, 573.3492486028388, 574.8792753521807, 662.3145044878528, 666.9229194850282, 672.67711498481, 4170.249355116048, 4685.765711714469, 4702.059588082398, 4792.496787837227, 4835.323054772335, 99350.0, 164721.0, 167095.31618735116, 168083.0, 168920.0, 112211.0, 134858.0, 135390.0, 135576.0, 140087.0, 112093.0, 117205.8279417212, 118206.40896795462, 119005.0, 120662.0, 7440.516366436164, 7891.921080789349, 9111.908880911105, 9131.223845973167, 9196.770080748034, 56112.719436402556, 58482.0, 61370.386296138255, 62476.75046499135, 63811.0, 91072.63391049007, 97714.51142744525, 97854.0, 98189.0, 99796.50101749445, 11776.293422394683, 12050.397480125783, 12147.804619643868, 12248.142630015813, 12493.0, 12842.486300547873, 12917.160384575349, 12956.093073485143, 13325.866741332851, 13627.386767595284, 12031.27812331263, 12224.511019559119, 12371.566995155126, 12511.374431278218, 12665.176763510342, 11985.760284794429, 12179.573714919936, 12209.69475763113, 12376.195547289397, 12624.242545447303, 11850.881491184979, 12127.211731237441, 12208.81686774717, 12323.383830808603, 12591.24452532851, 9464.621415143592, 10010.749731256778, 10042.096211341015, 10307.742306442398, 10369.896301036893, 6708.8993665094085, 7871.577412168925, 9701.854472182782, 9989.800203996025, 10176.379987082264], "value": 7690.767591561888, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1579.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 118.0, 125.0, 172.0, 164.0, 116.0, 116.0, 117.0, 118.0, 116.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 113.0, 113.0, 139.0, 134.0, 102.0, 143.0, 142.0, 178.0, 119.0], "value": 132.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [210.0, 237.0, 196.0, 198.0, 199.0, 202.0, 198.0, 197.0, 196.0, 196.0], "value": 202.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 74.0, 76.0, 81.0, 86.0, 81.0, 97.0, 59.0], "value": 75.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 107.0, 73.0, 72.0, 71.0, 88.0, 69.0, 72.0, 86.0], "value": 79.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 88.0, 90.0, 87.0, 90.0, 88.0, 98.0, 119.0, 88.0, 89.0], "value": 92.6, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 100.0, 107.0, 102.0, 129.0, 107.0, 93.0, 153.0, 151.0, 91.0], "value": 112.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 140.0, 101.0, 149.0, 138.0, 89.0, 150.0, 142.0, 94.0, 91.0], "value": 123.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 57.0, 55.0, 57.0, 56.0, 55.0, 56.0, 54.0, 58.0], "value": 55.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 79.0, 76.0, 79.0, 73.0, 68.0, 72.0, 70.0, 93.0, 59.0], "value": 73.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 99.0, 114.0, 109.0, 110.0, 103.0, 84.0, 88.0, 88.0, 86.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 172.0, 142.0, 136.0, 137.0, 155.0, 132.0, 159.0, 206.0, 157.0], "value": 154.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 174.0, 170.0, 216.0, 204.0, 173.0, 173.0, 171.0, 167.0, 191.0], "value": 181.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 69.0, 71.0, 90.0, 68.0, 68.0, 67.0, 67.0, 69.0], "value": 70.5, "unit": "score"}]}]} 09:23:06 INFO - Return code: 0 09:23:06 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:23:06 ERROR - # TBPL SUCCESS # 09:23:06 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:23:06 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:23:06 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:23:06 INFO - 2016-09-28 09:23:06.157 screenresolution[1671:10782] starting screenresolution argv=screenresolution get 09:23:06 INFO - 2016-09-28 09:23:06.160 screenresolution[1671:10782] Display 0: 1600x1200x32@60 09:23:06 INFO - 2016-09-28 09:23:06.166 screenresolution[1672:10785] starting screenresolution argv=screenresolution list 09:23:06 INFO - Available Modes on Display 0 09:23:06 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:23:06 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:23:06 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:23:06 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:23:06 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:23:06 INFO - Intel Iris: 09:23:06 INFO - Chipset Model: Intel Iris 09:23:06 INFO - Type: GPU 09:23:06 INFO - Bus: Built-In 09:23:06 INFO - VRAM (Dynamic, Max): 1536 MB 09:23:06 INFO - Vendor: Intel (0x8086) 09:23:06 INFO - Device ID: 0x0a2e 09:23:06 INFO - Revision ID: 0x0009 09:23:06 INFO - Displays: 09:23:06 INFO - Display: 09:23:06 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:23:06 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:23:06 INFO - Display Serial Number: 1600x1200 60 09:23:06 INFO - Main Display: Yes 09:23:06 INFO - Mirror: Off 09:23:06 INFO - Online: Yes 09:23:06 INFO - Rotation: Supported 09:23:06 INFO - Return code: 0 09:23:06 INFO - Running post-action listener: _resource_record_post_action 09:23:06 INFO - [mozharness: 2016-09-28 16:23:06.401061Z] Finished run-tests step (success) 09:23:06 INFO - Running post-run listener: _resource_record_post_run 09:23:06 INFO - Total resource usage - Wall time: 1209s; CPU: 55.0%; Read bytes: 39115264; Write bytes: 710069760; Read time: 383; Write time: 2170 09:23:06 INFO - TinderboxPrint: CPU usage
54.6% 09:23:06 INFO - TinderboxPrint: I/O read bytes / time
39,115,264 / 383 09:23:06 INFO - TinderboxPrint: I/O write bytes / time
710,069,760 / 2,170 09:23:06 INFO - TinderboxPrint: CPU idle
2,198.4 (45.4%) 09:23:06 INFO - TinderboxPrint: CPU system
977.5 (20.2%) 09:23:06 INFO - TinderboxPrint: CPU user
1,661.8 (34.4%) 09:23:06 INFO - TinderboxPrint: Swap in / out
446,496,768 / 0 09:23:06 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 391143936; Write bytes: 384820736; Read time: 29229; Write time: 692 09:23:06 INFO - run-tests - Wall time: 1175s; CPU: 55.0%; Read bytes: 38140416; Write bytes: 301017088; Read time: 367; Write time: 1430 09:23:06 INFO - Running post-run listener: _upload_blobber_files 09:23:06 INFO - Blob upload gear active. 09:23:06 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:23:06 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:23:06 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Fx-Team', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:23:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Fx-Team -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:23:07 INFO - (blobuploader) - INFO - Open directory for files ... 09:23:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:23:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:08 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:23:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:08 INFO - (blobuploader) - INFO - Done attempting. 09:23:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:23:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:09 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:23:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:09 INFO - (blobuploader) - INFO - Done attempting. 09:23:09 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:23:09 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:23:09 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:23:12 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:23:12 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:23:12 INFO - (blobuploader) - INFO - Done attempting. 09:23:12 INFO - (blobuploader) - INFO - Iteration through files over. 09:23:12 INFO - Return code: 0 09:23:12 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:23:12 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:23:12 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f94174c012cb697795711be97fb43b103a558dc7214db9a8883eba165174c749842541f6829d6c6e7e7559fd4041d7fa9ca3de9fc690707ee1a6594a689d2549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed522a24d583b980b0f548c1d0897178df1f4573285070a00b12308f87aa8c1fc5b97e15571e3b5f7583aea95715e9c7c9e8976c6a52005d6720302f10de406", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25520414190c509c03a1065c018de7e2390c1ba2a0c1e34dc62f057e8f851b4fc2e0415e286bd2d8bbb2e04e23d0bd5906366c9a276982095a09ee0c95373418"} 09:23:12 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:23:12 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:23:12 INFO - Contents: 09:23:12 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f94174c012cb697795711be97fb43b103a558dc7214db9a8883eba165174c749842541f6829d6c6e7e7559fd4041d7fa9ca3de9fc690707ee1a6594a689d2549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed522a24d583b980b0f548c1d0897178df1f4573285070a00b12308f87aa8c1fc5b97e15571e3b5f7583aea95715e9c7c9e8976c6a52005d6720302f10de406", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25520414190c509c03a1065c018de7e2390c1ba2a0c1e34dc62f057e8f851b4fc2e0415e286bd2d8bbb2e04e23d0bd5906366c9a276982095a09ee0c95373418"} 09:23:12 INFO - Running post-run listener: copy_logs_to_upload_dir 09:23:12 INFO - Copying logs to upload dir... 09:23:12 INFO - mkdir: /builds/slave/test/build/upload/logs 09:23:12 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1275.693074 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 15 secs) (at 2016-09-28 09:23:12.131916) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:23:12.135493) ========= 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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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/f94174c012cb697795711be97fb43b103a558dc7214db9a8883eba165174c749842541f6829d6c6e7e7559fd4041d7fa9ca3de9fc690707ee1a6594a689d2549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed522a24d583b980b0f548c1d0897178df1f4573285070a00b12308f87aa8c1fc5b97e15571e3b5f7583aea95715e9c7c9e8976c6a52005d6720302f10de406", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25520414190c509c03a1065c018de7e2390c1ba2a0c1e34dc62f057e8f851b4fc2e0415e286bd2d8bbb2e04e23d0bd5906366c9a276982095a09ee0c95373418"} build_url:https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010740 build_url: 'https://queue.taskcluster.net/v1/task/CdII_X_-Rh2Ih5EAHqfXdg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/f94174c012cb697795711be97fb43b103a558dc7214db9a8883eba165174c749842541f6829d6c6e7e7559fd4041d7fa9ca3de9fc690707ee1a6594a689d2549", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/6ed522a24d583b980b0f548c1d0897178df1f4573285070a00b12308f87aa8c1fc5b97e15571e3b5f7583aea95715e9c7c9e8976c6a52005d6720302f10de406", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Fx-Team/sha512/25520414190c509c03a1065c018de7e2390c1ba2a0c1e34dc62f057e8f851b4fc2e0415e286bd2d8bbb2e04e23d0bd5906366c9a276982095a09ee0c95373418"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-28 09:23:12.167643) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:23:12.168035) ========= 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.ek2uRrbO4r/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.umK3SWJRgJ/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005212 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-28 09:23:12.217215) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:23:12.217575) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-28 09:23:12.217926) ========= ========= Total master_lag: 0.18 =========