builder: pine_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0316 starttime: 1492134645.59 results: success (0) buildid: 20170413134823 builduid: 34578bd8592d478392c58a9c3b24747f revision: 22d0169c276164d03abb48e8c330499ee6197eaf ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:45.586204) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:45.586614) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:45.586911) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:45.628203) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:45.628490) ========= 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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 --2017-04-13 18:50:45-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 17.1M=0.001s 2017-04-13 18:50:45 (17.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.152009 ========= master_lag: 0.26 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:46.039973) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:46.040287) ========= 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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.038806 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:46.105029) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 18:50:46.105647) ========= bash -c 'python archiver_client.py mozharness --repo projects/pine --rev 22d0169c276164d03abb48e8c330499ee6197eaf --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/pine --rev 22d0169c276164d03abb48e8c330499ee6197eaf --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 2017-04-13 18:50:46,210 truncating revision to first 12 chars 2017-04-13 18:50:46,210 Setting DEBUG logging. 2017-04-13 18:50:46,210 attempt 1/10 2017-04-13 18:50:46,210 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/pine/22d0169c2761?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-04-13 18:50:48,407 unpacking tar archive at: pine-22d0169c2761/testing/mozharness/ program finished with exit code 0 elapsedTime=2.474976 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 2 secs) (at 2017-04-13 18:50:48.605972) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:48.606284) ========= script_repo_revision: 22d0169c276164d03abb48e8c330499ee6197eaf ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:48.606671) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:48.606966) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-04-13 18:50:48.628422) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-13 18:50:48.629162) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Pine', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Pine'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:50:48 INFO - MultiFileLogger online at 20170413 18:50:48 in /builds/slave/test 18:50:48 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Pine --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Pine 18:50:48 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 18:50:48 INFO - {'append_to_log': False, 18:50:48 INFO - 'base_work_dir': '/builds/slave/test', 18:50:48 INFO - 'blob_upload_branch': 'Pine', 18:50:48 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 18:50:48 INFO - 'branch': 'Pine', 18:50:48 INFO - 'buildbot_json_path': 'buildprops.json', 18:50:48 INFO - 'config_files': ('talos/mac_config.py',), 18:50:48 INFO - 'default_actions': ('clobber', 18:50:48 INFO - 'read-buildbot-config', 18:50:48 INFO - 'download-and-extract', 18:50:48 INFO - 'populate-webroot', 18:50:48 INFO - 'create-virtualenv', 18:50:48 INFO - 'install', 18:50:48 INFO - 'run-tests'), 18:50:48 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 18:50:48 INFO - 'download_minidump_stackwalk': True, 18:50:48 INFO - 'download_symbols': 'ondemand', 18:50:48 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 18:50:48 INFO - 'tooltool.py': '/tools/tooltool.py', 18:50:48 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 18:50:48 INFO - '/tools/misc-python/virtualenv.py')}, 18:50:48 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 18:50:48 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 18:50:48 INFO - 'gecko_profile': False, 18:50:48 INFO - 'gecko_profile_interval': 0, 18:50:48 INFO - 'installer_path': 'installer.exe', 18:50:48 INFO - 'log_level': 'info', 18:50:48 INFO - 'log_name': 'talos', 18:50:48 INFO - 'log_to_console': True, 18:50:48 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 18:50:48 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 18:50:48 INFO - 'opt_config_files': (), 18:50:48 INFO - 'pip_index': False, 18:50:48 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:48 INFO - 'cmd': ('bash', 18:50:48 INFO - '-c', 18:50:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:48 INFO - 'enabled': True, 18:50:48 INFO - 'halt_on_failure': False, 18:50:48 INFO - 'name': 'check_screen_resolution'},), 18:50:48 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 18:50:48 INFO - 'cmd': ('bash', 18:50:48 INFO - '-c', 18:50:48 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 18:50:48 INFO - 'enabled': True, 18:50:48 INFO - 'halt_on_failure': False, 18:50:48 INFO - 'name': 'check_screen_resolution'},), 18:50:48 INFO - 'run_cmd_checks_enabled': True, 18:50:48 INFO - 'suite': 'dromaeojs-e10s', 18:50:48 INFO - 'system_bits': '32', 18:50:48 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 18:50:48 INFO - 'title': 't-yosemite-r7-0316', 18:50:48 INFO - 'tooltool_cache': '/builds/tooltool_cache', 18:50:48 INFO - 'use_talos_json': True, 18:50:48 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 18:50:48 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 18:50:48 INFO - 'work_dir': 'build'} 18:50:48 INFO - [mozharness: 2017-04-14 01:50:48.839194Z] Running clobber step. 18:50:48 INFO - Running pre-action listener: _resource_record_pre_action 18:50:48 INFO - Running main action method: clobber 18:50:48 INFO - rmtree: /builds/slave/test/build 18:50:48 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 18:50:51 INFO - Running post-action listener: _resource_record_post_action 18:50:51 INFO - [mozharness: 2017-04-14 01:50:51.262403Z] Finished clobber step (success) 18:50:51 INFO - [mozharness: 2017-04-14 01:50:51.262533Z] Running read-buildbot-config step. 18:50:51 INFO - Running pre-action listener: _resource_record_pre_action 18:50:51 INFO - Running main action method: read_buildbot_config 18:50:51 INFO - Using buildbot properties: 18:50:51 INFO - { 18:50:51 INFO - "project": "", 18:50:51 INFO - "product": "firefox", 18:50:51 INFO - "script_repo_revision": "default", 18:50:51 INFO - "builddir": "pine_yosemite_r7_test-dromaeojs-e10s", 18:50:51 INFO - "repository": "", 18:50:51 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 pine talos dromaeojs-e10s", 18:50:51 INFO - "buildid": "20170413134823", 18:50:51 INFO - "pgo_build": "False", 18:50:51 INFO - "basedir": "/builds/slave/test", 18:50:51 INFO - "buildnumber": 1, 18:50:51 INFO - "slavename": "t-yosemite-r7-0316", 18:50:51 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 18:50:51 INFO - "platform": "yosemite_r7", 18:50:51 INFO - "scheduler": "tests-pine-macosx64-talos", 18:50:51 INFO - "branch": "pine", 18:50:51 INFO - "repo_path": "projects/pine", 18:50:51 INFO - "revision": "22d0169c276164d03abb48e8c330499ee6197eaf", 18:50:51 INFO - "stage_platform": "macosx64", 18:50:51 INFO - "builduid": "34578bd8592d478392c58a9c3b24747f", 18:50:51 INFO - "slavebuilddir": "test" 18:50:51 INFO - } 18:50:51 INFO - Found installer url https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg. 18:50:51 INFO - Running post-action listener: _resource_record_post_action 18:50:51 INFO - [mozharness: 2017-04-14 01:50:51.266016Z] Finished read-buildbot-config step (success) 18:50:51 INFO - [mozharness: 2017-04-14 01:50:51.266156Z] Running download-and-extract step. 18:50:51 INFO - Running pre-action listener: _resource_record_pre_action 18:50:51 INFO - Running main action method: download_and_extract 18:50:51 INFO - mkdir: /builds/slave/test/build/tests 18:50:51 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')]} 18:50:51 INFO - trying https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json 18:50:51 INFO - Downloading https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:50:51 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json'}, attempt #1 18:50:52 INFO - Downloaded 1325 bytes. 18:50:52 INFO - Reading from file /builds/slave/test/build/firefox-55.0a1.en-US.mac.test_packages.json 18:50:52 INFO - Using the following test package requirements: 18:50:52 INFO - {u'awsy': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.awsy.tests.zip'], 18:50:52 INFO - u'common': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:50:52 INFO - u'cppunittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.cppunittest.tests.zip'], 18:50:52 INFO - u'gtest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.gtest.tests.zip'], 18:50:52 INFO - u'jittest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'jsshell-mac.zip'], 18:50:52 INFO - u'mochitest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.mochitest.tests.zip'], 18:50:52 INFO - u'mozbase': [u'firefox-55.0a1.en-US.mac.common.tests.zip'], 18:50:52 INFO - u'reftest': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.reftest.tests.zip'], 18:50:52 INFO - u'talos': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.talos.tests.zip'], 18:50:52 INFO - u'web-platform': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.web-platform.tests.zip'], 18:50:52 INFO - u'xpcshell': [u'firefox-55.0a1.en-US.mac.common.tests.zip', 18:50:52 INFO - u'firefox-55.0a1.en-US.mac.xpcshell.tests.zip']} 18:50:52 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip'] for test suite category: common 18:50:52 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:50:52 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:50:52 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:50:54 INFO - Expected file size: 52594875 18:50:54 INFO - Obtained file size: 52594875 18:51:10 INFO - Downloading packages: [u'firefox-55.0a1.en-US.mac.common.tests.zip', u'firefox-55.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 18:51:10 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip 18:51:10 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip'}, attempt #1 18:51:10 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.common.tests.zip into memory 18:51:11 INFO - Expected file size: 52594875 18:51:11 INFO - Obtained file size: 52594875 18:51:27 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip 18:51:27 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip'}, attempt #1 18:51:27 INFO - Fetch https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.talos.tests.zip into memory 18:51:28 INFO - Expected file size: 14408853 18:51:28 INFO - Obtained file size: 14408853 18:51: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')]} 18:51:28 INFO - trying https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:51:28 INFO - Downloading https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg 18:51:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg'}, attempt #1 18:51:30 INFO - Downloaded 71148382 bytes. 18:51:30 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:51:30 INFO - mkdir: /builds/slave/test/properties 18:51:30 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 18:51:30 INFO - Writing to file /builds/slave/test/properties/build_url 18:51:30 INFO - Contents: 18:51:30 INFO - build_url:https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg 18:51:31 INFO - Running post-action listener: _resource_record_post_action 18:51:31 INFO - Running post-action listener: set_extra_try_arguments 18:51:31 INFO - [mozharness: 2017-04-14 01:51:31.500857Z] Finished download-and-extract step (success) 18:51:31 INFO - [mozharness: 2017-04-14 01:51:31.500996Z] Running populate-webroot step. 18:51:31 INFO - Running pre-action listener: _resource_record_pre_action 18:51:31 INFO - Running main action method: populate_webroot 18:51:31 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 18:51:31 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 18:51:31 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 18:51:31 INFO - '--tppagecycles', 18:51:31 INFO - '7'], 18:51:31 INFO - 'tests': ['tsvgm']}}, 18:51:31 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['tresize', 'tcanvasmark']}, 18:51:31 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 18:51:31 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['dromaeo_css', 'kraken']}, 18:51:31 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 18:51:31 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:31 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 18:51:31 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['damp', 'tps']}, 18:51:31 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'tests': ['damp', 'tps']}, 18:51:31 INFO - 'g3': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['dromaeo_dom']}, 18:51:31 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 18:51:31 INFO - 'g4': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['basic_compositor_video', 'glvideo']}, 18:51:31 INFO - 'g4-e10s': {'tests': ['basic_compositor_video', 'glvideo']}, 18:51:31 INFO - 'other': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'other-e10s': {'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'other-e10s_l64': {'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['a11yr', 18:51:31 INFO - 'ts_paint', 18:51:31 INFO - 'tpaint', 18:51:31 INFO - 'sessionrestore', 18:51:31 INFO - 'sessionrestore_no_auto_restore', 18:51:31 INFO - 'tabpaint']}, 18:51:31 INFO - 'perf-reftest': {'tests': ['bloom_basic', 'bloom_basic_ref']}, 18:51:31 INFO - 'perf-reftest-e10s': {'tests': ['bloom_basic', 18:51:31 INFO - 'bloom_basic_ref']}, 18:51:31 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['tsvgx', 18:51:31 INFO - 'tsvgr_opacity', 18:51:31 INFO - 'tart', 18:51:31 INFO - 'tscrollx', 18:51:31 INFO - 'cart', 18:51:31 INFO - 'tsvg_static']}, 18:51:31 INFO - 'svgr-e10s': {'tests': ['tsvgx', 18:51:31 INFO - 'tsvgr_opacity', 18:51:31 INFO - 'tart', 18:51:31 INFO - 'tscrollx', 18:51:31 INFO - 'cart', 18:51:31 INFO - 'tsvg_static']}, 18:51:31 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'talos_options': ['--disable-e10s'], 18:51:31 INFO - 'tests': ['tp5o']}, 18:51:31 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 18:51:31 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'talos_options': ['--disable-e10s', 18:51:31 INFO - '--xperf_path', 18:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:31 INFO - 'tests': ['tp5n']}, 18:51:31 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 18:51:31 INFO - 'talos_options': ['--xperf_path', 18:51:31 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 18:51:31 INFO - 'tests': ['tp5n']}}, 18:51:31 INFO - 'talos.zip': {'path': '', 18:51:31 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 18:51:31 INFO - Running post-action listener: _resource_record_post_action 18:51:31 INFO - [mozharness: 2017-04-14 01:51:31.519682Z] Finished populate-webroot step (success) 18:51:31 INFO - [mozharness: 2017-04-14 01:51:31.519829Z] Running create-virtualenv step. 18:51:31 INFO - Running pre-action listener: _resource_record_pre_action 18:51:31 INFO - Running main action method: create_virtualenv 18:51:31 INFO - Creating virtualenv /builds/slave/test/build/venv 18:51:31 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 18:51:31 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 18:51:31 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 18:51:31 INFO - Using real prefix '/tools/python27' 18:51:31 INFO - New python executable in /builds/slave/test/build/venv/bin/python 18:51:32 INFO - Installing distribute.............................................................................................................................................................................................done. 18:51:35 INFO - Installing pip.................done. 18:51:35 INFO - Return code: 0 18:51:35 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 18:51:35 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 18:51:35 INFO - Reading from file tmpfile_stdout 18:51:35 INFO - Output received: 18:51:35 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) 18:51:35 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 18:51:35 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')]} 18:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:35 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')]} 18:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:35 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:35 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 18:51:35 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 18:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:35 INFO - 'HOME': '/Users/cltbld', 18:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:35 INFO - 'LOGNAME': 'cltbld', 18:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:35 INFO - 'MOZ_NO_REMOTE': '1', 18:51:35 INFO - 'NO_EM_RESTART': '1', 18:51:35 INFO - 'PAGER': '/bin/cat', 18:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:35 INFO - 'PWD': '/builds/slave/test', 18:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:35 INFO - 'SHELL': '/bin/bash', 18:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:35 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:35 INFO - 'USER': 'cltbld', 18:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:35 INFO - 'XPC_FLAGS': '0x0', 18:51:35 INFO - 'XPC_SERVICE_NAME': '0', 18:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:35 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 18:51:35 INFO - Cleaning up... 18:51:35 INFO - Return code: 0 18:51:35 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 18:51:35 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')]} 18:51:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:35 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')]} 18:51:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:35 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:35 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 18:51:35 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 18:51:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:35 INFO - 'HOME': '/Users/cltbld', 18:51:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:35 INFO - 'LOGNAME': 'cltbld', 18:51:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:35 INFO - 'MOZ_NO_REMOTE': '1', 18:51:35 INFO - 'NO_EM_RESTART': '1', 18:51:35 INFO - 'PAGER': '/bin/cat', 18:51:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:35 INFO - 'PWD': '/builds/slave/test', 18:51:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:35 INFO - 'SHELL': '/bin/bash', 18:51:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:35 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:35 INFO - 'USER': 'cltbld', 18:51:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:35 INFO - 'XPC_FLAGS': '0x0', 18:51:35 INFO - 'XPC_SERVICE_NAME': '0', 18:51:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:36 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:36 INFO - Downloading/unpacking psutil>=3.1.1 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 18:51:40 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:40 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:40 INFO - Installing collected packages: psutil 18:51:40 INFO - Running setup.py install for psutil 18:51:40 INFO - building 'psutil._psutil_osx' extension 18:51:40 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 18:51:41 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 18:51:41 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 18:51:41 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 18:51:41 INFO - building 'psutil._psutil_posix' extension 18:51:41 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 18:51:41 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 18:51:41 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 18:51:41 INFO - ^ 18:51:41 INFO - 1 warning generated. 18:51:41 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 18:51:41 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 18:51:41 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 18:51:41 INFO - Successfully installed psutil 18:51:41 INFO - Cleaning up... 18:51:41 INFO - Return code: 0 18:51:41 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 18:51:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:42 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:42 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:42 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:42 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:42 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 18:51:42 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 18:51:42 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:42 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:42 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:42 INFO - 'HOME': '/Users/cltbld', 18:51:42 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:42 INFO - 'LOGNAME': 'cltbld', 18:51:42 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:42 INFO - 'MOZ_NO_REMOTE': '1', 18:51:42 INFO - 'NO_EM_RESTART': '1', 18:51:42 INFO - 'PAGER': '/bin/cat', 18:51:42 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:42 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:42 INFO - 'PWD': '/builds/slave/test', 18:51:42 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:42 INFO - 'SHELL': '/bin/bash', 18:51:42 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:42 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:42 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:42 INFO - 'USER': 'cltbld', 18:51:42 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:42 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:42 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:42 INFO - 'XPC_FLAGS': '0x0', 18:51:42 INFO - 'XPC_SERVICE_NAME': '0', 18:51:42 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:42 INFO - Downloading/unpacking mozsystemmonitor==0.3 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51: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 18:51:45 INFO - Downloading mozsystemmonitor-0.3.tar.gz 18:51:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 18:51:45 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 18:51:45 INFO - Installing collected packages: mozsystemmonitor 18:51:45 INFO - Running setup.py install for mozsystemmonitor 18:51:45 INFO - Successfully installed mozsystemmonitor 18:51:45 INFO - Cleaning up... 18:51:46 INFO - Return code: 0 18:51:46 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 18:51:46 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')]} 18:51:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:46 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')]} 18:51:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:46 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:46 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 18:51:46 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 18:51:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:46 INFO - 'HOME': '/Users/cltbld', 18:51:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:46 INFO - 'LOGNAME': 'cltbld', 18:51:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:46 INFO - 'MOZ_NO_REMOTE': '1', 18:51:46 INFO - 'NO_EM_RESTART': '1', 18:51:46 INFO - 'PAGER': '/bin/cat', 18:51:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:46 INFO - 'PWD': '/builds/slave/test', 18:51:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:46 INFO - 'SHELL': '/bin/bash', 18:51:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:46 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:46 INFO - 'USER': 'cltbld', 18:51:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:46 INFO - 'XPC_FLAGS': '0x0', 18:51:46 INFO - 'XPC_SERVICE_NAME': '0', 18:51:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:46 INFO - Downloading/unpacking jsonschema==2.5.1 18:51:46 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 18:51:46 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 18:51:46 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 18:51:46 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 18:51:46 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 18:51:46 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 18:51:48 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 18:51:49 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 18:51:49 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 18:51:49 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 18:51:49 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 18:51:49 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 18:51:49 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 18:51:49 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 18:51:49 INFO - Downloading functools32-3.2.3-2.tar.gz 18:51:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 18:51:49 INFO - warning: no files found matching '*.txt' 18:51:49 INFO - no previously-included directories found matching 'build' 18:51:49 INFO - no previously-included directories found matching 'dist' 18:51:49 INFO - no previously-included directories found matching '.git*' 18:51:49 INFO - Installing collected packages: jsonschema, functools32 18:51:49 INFO - Running setup.py install for functools32 18:51:49 INFO - warning: no files found matching '*.txt' 18:51:49 INFO - no previously-included directories found matching 'build' 18:51:49 INFO - no previously-included directories found matching 'dist' 18:51:49 INFO - no previously-included directories found matching '.git*' 18:51:49 INFO - Successfully installed jsonschema functools32 18:51:49 INFO - Cleaning up... 18:51:49 INFO - Return code: 0 18:51:49 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 18:51: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')]} 18:51:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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')]} 18:51:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:49 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:49 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 18:51:49 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 18:51:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:49 INFO - 'HOME': '/Users/cltbld', 18:51:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:49 INFO - 'LOGNAME': 'cltbld', 18:51:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:49 INFO - 'MOZ_NO_REMOTE': '1', 18:51:49 INFO - 'NO_EM_RESTART': '1', 18:51:49 INFO - 'PAGER': '/bin/cat', 18:51:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:49 INFO - 'PWD': '/builds/slave/test', 18:51:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:49 INFO - 'SHELL': '/bin/bash', 18:51:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:49 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:49 INFO - 'USER': 'cltbld', 18:51:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:49 INFO - 'XPC_FLAGS': '0x0', 18:51:49 INFO - 'XPC_SERVICE_NAME': '0', 18:51:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:50 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 18:51:50 INFO - Cleaning up... 18:51:50 INFO - Return code: 0 18:51:50 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 18:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:50 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:50 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 18:51:50 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 18:51:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:50 INFO - 'HOME': '/Users/cltbld', 18:51:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:50 INFO - 'LOGNAME': 'cltbld', 18:51:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:50 INFO - 'MOZ_NO_REMOTE': '1', 18:51:50 INFO - 'NO_EM_RESTART': '1', 18:51:50 INFO - 'PAGER': '/bin/cat', 18:51:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:50 INFO - 'PWD': '/builds/slave/test', 18:51:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:50 INFO - 'SHELL': '/bin/bash', 18:51:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:50 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:50 INFO - 'USER': 'cltbld', 18:51:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:50 INFO - 'XPC_FLAGS': '0x0', 18:51:50 INFO - 'XPC_SERVICE_NAME': '0', 18:51:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:50 INFO - Downloading/unpacking blobuploader==1.2.4 18:51:50 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 18:51:50 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 18:51:50 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 18:51:50 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 18:51:50 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 18:51:50 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 18:51:52 INFO - Downloading blobuploader-1.2.4.tar.gz 18:51:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 18:51:53 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 18:51:53 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:53 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 18:51:54 INFO - Downloading docopt-0.6.1.tar.gz 18:51:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 18:51:54 INFO - Installing collected packages: blobuploader, requests, docopt 18:51:54 INFO - Running setup.py install for blobuploader 18:51:54 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 18:51:54 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 18:51:54 INFO - Running setup.py install for requests 18:51:54 INFO - Running setup.py install for docopt 18:51:55 INFO - Successfully installed blobuploader requests docopt 18:51:55 INFO - Cleaning up... 18:51:55 INFO - Return code: 0 18:51:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51: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')]} 18:51:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51: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')]} 18:51:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:55 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:55 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 18:51:55 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 18:51:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:55 INFO - 'HOME': '/Users/cltbld', 18:51:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:55 INFO - 'LOGNAME': 'cltbld', 18:51:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:55 INFO - 'MOZ_NO_REMOTE': '1', 18:51:55 INFO - 'NO_EM_RESTART': '1', 18:51:55 INFO - 'PAGER': '/bin/cat', 18:51:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:55 INFO - 'PWD': '/builds/slave/test', 18:51:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:55 INFO - 'SHELL': '/bin/bash', 18:51:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:55 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:55 INFO - 'USER': 'cltbld', 18:51:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:55 INFO - 'XPC_FLAGS': '0x0', 18:51:55 INFO - 'XPC_SERVICE_NAME': '0', 18:51:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:51:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-XdY6D9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-AZoaDf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-brGYCM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ZbQbAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-RfH1K7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:51:55 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:51:55 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-epx9ao-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-0IZxRv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-b8OQc8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-xt6ees-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-gNRlNg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-FdK1o7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-g0J7EG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-PYEMEn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Icd5cf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-cHcnUo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:51:56 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:51:56 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-9U4Fw6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:51:57 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:51:57 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-alCDfO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:51:57 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 18:51:57 INFO - Running setup.py install for manifestparser 18:51:57 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 18:51:57 INFO - Running setup.py install for mozcrash 18:51:57 INFO - Running setup.py install for mozdebug 18:51:57 INFO - Running setup.py install for mozdevice 18:51:57 INFO - Installing dm script to /builds/slave/test/build/venv/bin 18:51:57 INFO - Running setup.py install for mozfile 18:51:57 INFO - Running setup.py install for mozhttpd 18:51:58 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Running setup.py install for mozinfo 18:51:58 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Running setup.py install for mozInstall 18:51:58 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Running setup.py install for mozleak 18:51:58 INFO - Running setup.py install for mozlog 18:51:58 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Running setup.py install for moznetwork 18:51:58 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 18:51:58 INFO - Running setup.py install for mozprocess 18:51:58 INFO - Running setup.py install for mozprofile 18:51:59 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 18:51:59 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 18:51:59 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 18:51:59 INFO - Running setup.py install for mozrunner 18:51:59 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 18:51:59 INFO - Running setup.py install for mozscreenshot 18:51:59 INFO - Running setup.py install for moztest 18:51:59 INFO - Running setup.py install for mozversion 18:51:59 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 18:51:59 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 18:51:59 INFO - Cleaning up... 18:51:59 INFO - Return code: 0 18:51:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:51:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:51:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:51:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:51:59 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:51:59 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 18:51:59 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 18:51:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:51:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:51:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:51:59 INFO - 'HOME': '/Users/cltbld', 18:51:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:51:59 INFO - 'LOGNAME': 'cltbld', 18:51:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:51:59 INFO - 'MOZ_NO_REMOTE': '1', 18:51:59 INFO - 'NO_EM_RESTART': '1', 18:51:59 INFO - 'PAGER': '/bin/cat', 18:51:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:51:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:51:59 INFO - 'PWD': '/builds/slave/test', 18:51:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:51:59 INFO - 'SHELL': '/bin/bash', 18:51:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:51:59 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:51:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:51:59 INFO - 'USER': 'cltbld', 18:51:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:51:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:51:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:51:59 INFO - 'XPC_FLAGS': '0x0', 18:51:59 INFO - 'XPC_SERVICE_NAME': '0', 18:51:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:00 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ISVJVF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-wrGIla-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 18:52:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-gTu2tv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ydgaaF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 18:52:00 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.50 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-U24EUE-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-Mpzutq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-rhtUOt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-syL_1x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-r2ayc1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 18:52:00 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)) 18:52:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 18:52:00 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-ClYygN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 18:52:01 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-F2JLhV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 18:52:01 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-LVza7P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 18:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-sgI6iC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 18:52:01 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-WBgUMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 18:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-qby62Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 18:52:01 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-QWVa0S-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 18:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 18:52:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 18:52:01 INFO - Running setup.py (path:/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/pip-7aihv5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 18:52:01 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)) 18:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:52:01 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 18:52:01 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)) 18:52:01 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:52:01 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.50->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 18:52:01 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:01 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 18:52:04 INFO - Downloading blessings-1.6.tar.gz 18:52:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 18:52:04 INFO - Installing collected packages: blessings 18:52:04 INFO - Running setup.py install for blessings 18:52:04 INFO - Successfully installed blessings 18:52:04 INFO - Cleaning up... 18:52:04 INFO - Return code: 0 18:52:04 INFO - Done creating virtualenv /builds/slave/test/build/venv. 18:52:04 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:52:04 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:52:05 INFO - Reading from file tmpfile_stdout 18:52:05 INFO - Current package versions: 18:52:05 INFO - blessings == 1.6 18:52:05 INFO - blobuploader == 1.2.4 18:52:05 INFO - docopt == 0.6.1 18:52:05 INFO - functools32 == 3.2.3-2 18:52:05 INFO - jsonschema == 2.5.1 18:52:05 INFO - manifestparser == 1.1 18:52:05 INFO - mozInstall == 1.12 18:52:05 INFO - mozcrash == 1.0 18:52:05 INFO - mozdebug == 0.1 18:52:05 INFO - mozdevice == 0.50 18:52:05 INFO - mozfile == 1.2 18:52:05 INFO - mozhttpd == 0.7 18:52:05 INFO - mozinfo == 0.9 18:52:05 INFO - mozleak == 0.1 18:52:05 INFO - mozlog == 3.4 18:52:05 INFO - moznetwork == 0.27 18:52:05 INFO - mozprocess == 0.25 18:52:05 INFO - mozprofile == 0.28 18:52:05 INFO - mozrunner == 6.13 18:52:05 INFO - mozscreenshot == 0.1 18:52:05 INFO - mozsystemmonitor == 0.3 18:52:05 INFO - moztest == 0.8 18:52:05 INFO - mozversion == 1.4 18:52:05 INFO - psutil == 3.1.1 18:52:05 INFO - requests == 1.2.3 18:52:05 INFO - wsgiref == 0.1.2 18:52:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 18:52:05 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')]} 18:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:05 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')]} 18:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:05 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:52:05 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 18:52:05 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 18:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:05 INFO - 'HOME': '/Users/cltbld', 18:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:05 INFO - 'LOGNAME': 'cltbld', 18:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:05 INFO - 'MOZ_NO_REMOTE': '1', 18:52:05 INFO - 'NO_EM_RESTART': '1', 18:52:05 INFO - 'PAGER': '/bin/cat', 18:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:05 INFO - 'PWD': '/builds/slave/test', 18:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:05 INFO - 'SHELL': '/bin/bash', 18:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:52:05 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:05 INFO - 'USER': 'cltbld', 18:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:05 INFO - 'XPC_FLAGS': '0x0', 18:52:05 INFO - 'XPC_SERVICE_NAME': '0', 18:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 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)) 18:52:05 INFO - Cleaning up... 18:52:05 INFO - Return code: 0 18:52:05 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 18:52:05 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')]} 18:52:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 18:52:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:05 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')]} 18:52:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 18:52:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 18:52:05 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 0x105ab5b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ab8768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ff5cbd7d360>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, '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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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 18:52:05 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 18:52:05 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 18:52:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:52:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:05 INFO - 'HOME': '/Users/cltbld', 18:52:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:05 INFO - 'LOGNAME': 'cltbld', 18:52:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:05 INFO - 'MOZ_NO_REMOTE': '1', 18:52:05 INFO - 'NO_EM_RESTART': '1', 18:52:05 INFO - 'PAGER': '/bin/cat', 18:52:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:05 INFO - 'PWD': '/builds/slave/test', 18:52:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:05 INFO - 'SHELL': '/bin/bash', 18:52:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:52:05 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:52:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:05 INFO - 'USER': 'cltbld', 18:52:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:05 INFO - 'XPC_FLAGS': '0x0', 18:52:05 INFO - 'XPC_SERVICE_NAME': '0', 18:52:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 18:52:05 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 18:52:05 INFO - Cleaning up... 18:52:05 INFO - Return code: 0 18:52:05 INFO - Running post-action listener: _resource_record_post_action 18:52:05 INFO - Running post-action listener: _start_resource_monitoring 18:52:05 INFO - Starting resource monitoring. 18:52:05 INFO - [mozharness: 2017-04-14 01:52:05.691669Z] Finished create-virtualenv step (success) 18:52:05 INFO - [mozharness: 2017-04-14 01:52:05.692592Z] Running install step. 18:52:05 INFO - Running pre-action listener: _resource_record_pre_action 18:52:05 INFO - Running main action method: install 18:52:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 18:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 18:52:05 INFO - Reading from file tmpfile_stdout 18:52:05 INFO - Detecting whether we're running mozinstall >=1.0... 18:52:05 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 18:52:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 18:52:06 INFO - Reading from file tmpfile_stdout 18:52:06 INFO - Output received: 18:52:06 INFO - Usage: mozinstall [options] installer 18:52:06 INFO - Options: 18:52:06 INFO - -h, --help show this help message and exit 18:52:06 INFO - -d DEST, --destination=DEST 18:52:06 INFO - Directory to install application into. [default: 18:52:06 INFO - "/builds/slave/test"] 18:52:06 INFO - --app=APP Application being installed. [default: firefox] 18:52:06 INFO - mkdir: /builds/slave/test/build/application 18:52:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 18:52:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-55.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 18:52:26 INFO - Reading from file tmpfile_stdout 18:52:26 INFO - Output received: 18:52:26 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 18:52:26 INFO - Running post-action listener: _resource_record_post_action 18:52:26 INFO - [mozharness: 2017-04-14 01:52:26.017968Z] Finished install step (success) 18:52:26 INFO - [mozharness: 2017-04-14 01:52:26.018063Z] Running run-tests step. 18:52:26 INFO - Running pre-action listener: _resource_record_pre_action 18:52:26 INFO - Running main action method: run_tests 18:52:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 18:52:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 18:52:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 18:52:26 INFO - 2017-04-13 18:52:26.031 screenresolution[1627:7291] starting screenresolution argv=screenresolution get 18:52:26 INFO - 2017-04-13 18:52:26.033 screenresolution[1627:7291] Display 0: 1600x1200x32@60 18:52:26 INFO - 2017-04-13 18:52:26.038 screenresolution[1628:7294] starting screenresolution argv=screenresolution list 18:52:26 INFO - Available Modes on Display 0 18:52:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 18:52:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 18:52:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 18:52:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 18:52:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 18:52:26 INFO - Intel Iris: 18:52:26 INFO - Chipset Model: Intel Iris 18:52:26 INFO - Type: GPU 18:52:26 INFO - Bus: Built-In 18:52:26 INFO - VRAM (Dynamic, Max): 1536 MB 18:52:26 INFO - Vendor: Intel (0x8086) 18:52:26 INFO - Device ID: 0x0a2e 18:52:26 INFO - Revision ID: 0x0009 18:52:26 INFO - Displays: 18:52:26 INFO - Display: 18:52:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 18:52:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 18:52:26 INFO - Display Serial Number: 1600x1200 60 18:52:26 INFO - Main Display: Yes 18:52:26 INFO - Mirror: Off 18:52:26 INFO - Online: Yes 18:52:26 INFO - Rotation: Supported 18:52:26 INFO - Return code: 0 18:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 18:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 18:52:26 INFO - Python 2.7.3 18:52:26 INFO - Return code: 0 18:52:26 INFO - grabbing minidump binary from tooltool 18:52:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 18:52:26 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 0x105893d30>, '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 0x1057badb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105888be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 18:52:26 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 18:52:26 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 18:52:26 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 18:52:26 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 18:52:26 INFO - Return code: 0 18:52:26 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 18:52:26 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 18:52:26 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 18:52:26 INFO - ENV: RUST_BACKTRACE is now 1 18:52:26 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 18:52:26 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 18:52:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:52:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Pine --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0316 --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 18:52:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.thM4kQJh41/Render', 18:52:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 18:52:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 18:52:26 INFO - 'HOME': '/Users/cltbld', 18:52:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 18:52:26 INFO - 'LOGNAME': 'cltbld', 18:52:26 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 18:52:26 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 18:52:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 18:52:26 INFO - 'MOZ_NO_REMOTE': '1', 18:52:26 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 18:52:26 INFO - 'NO_EM_RESTART': '1', 18:52:26 INFO - 'PAGER': '/bin/cat', 18:52:26 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 18:52:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 18:52:26 INFO - 'PWD': '/builds/slave/test', 18:52:26 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 18:52:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 18:52:26 INFO - 'RUST_BACKTRACE': '1', 18:52:26 INFO - 'SHELL': '/bin/bash', 18:52:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.iQ2Qn6Qrhq/Listeners', 18:52:26 INFO - 'TMPDIR': '/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/', 18:52:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 18:52:26 INFO - 'USER': 'cltbld', 18:52:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 18:52:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 18:52:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 18:52:26 INFO - 'XPC_FLAGS': '0x0', 18:52:26 INFO - 'XPC_SERVICE_NAME': '0', 18:52:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 18:52:26 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Pine', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0316', '--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 18:52:26 INFO - mozversion application_buildid: 20170413134823 18:52:26 INFO - mozversion application_changeset: 22d0169c276164d03abb48e8c330499ee6197eaf 18:52:26 INFO - mozversion application_display_name: Nightly 18:52:26 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 18:52:26 INFO - mozversion application_name: Firefox 18:52:26 INFO - mozversion application_remotingname: firefox 18:52:26 INFO - mozversion application_repository: https://hg.mozilla.org/projects/pine 18:52:26 INFO - mozversion application_vendor: Mozilla 18:52:26 INFO - mozversion application_version: 55.0a1 18:52:26 INFO - mozversion platform_buildid: 20170413134823 18:52:26 INFO - mozversion platform_changeset: 22d0169c276164d03abb48e8c330499ee6197eaf 18:52:26 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/pine 18:52:26 INFO - mozversion platform_version: 55.0a1 18:52:26 INFO - using testdate: 1492134746 18:52:26 INFO - actual date: 1492134746 18:52:26 INFO - starting webserver on 'localhost:49235' 18:52:26 INFO - SUITE-START | Running 2 tests 18:52:26 INFO - TEST-START | dromaeo_css 18:52:26 INFO - operating with platform_type : mac_ 18:52:26 INFO - Initialising browser for dromaeo_css test... 18:52:26 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmp4HCVaI/profile http://localhost:49235/getInfo.html) 18:52:29 INFO - PID 1638 | 2017-04-13 18:52:29.395 plugin-container[1640:7470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d3f, name = 'com.apple.tsm.portname' 18:52:29 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:29 INFO - PID 1638 | 2017-04-13 18:52:29.398 plugin-container[1640:7470] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x930b, name = 'com.apple.CFPasteboardClient' 18:52:29 INFO - PID 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:29 INFO - PID 1638 | 2017-04-13 18:52:29.398 plugin-container[1640:7470] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:52:29 INFO - PID 1638 | __metrics Screen width/height:1600/1200 18:52:29 INFO - PID 1638 | colorDepth:24 18:52:29 INFO - PID 1638 | Browser inner width/height: 1024/689 18:52:29 INFO - PID 1638 | __metrics 18:52:29 ERROR - PID 1638 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 18:52:30 INFO - TEST-INFO | 1638: exit 0 18:52:30 INFO - Browser initialized. 18:52:30 INFO - Running cycle 1/1 for dromaeo_css test... 18:52:30 INFO - TEST-INFO | started process 1641 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmp4HCVaI/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 18:52:32 INFO - PID 1641 | 2017-04-13 18:52:32.869 plugin-container[1642:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 18:52:32 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:32 INFO - PID 1641 | 2017-04-13 18:52:32.870 plugin-container[1642:7605] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x470b, name = 'com.apple.CFPasteboardClient' 18:52:32 INFO - PID 1641 | See /usr/include/servers/bootstrap_defs.h for the error codes. 18:52:32 INFO - PID 1641 | 2017-04-13 18:52:32.870 plugin-container[1642:7605] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 18:52:42 INFO - PID 1641 | RSS: Main: 250441728 18:52:42 INFO - PID 1641 | 18:55:46 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49235/tests/dromaeo/cssquery-ext.html) 18:55:48 INFO - PID 1641 | RSS: Main: 260755456 18:55:48 INFO - PID 1641 | 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:57:31 INFO - PID 1641 | 18:57:31 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:57:31 INFO - PID 1641 | Full stack: 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:57:31 INFO - PID 1641 | 18:57:31 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:57:31 INFO - PID 1641 | Full stack: 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:57:31 INFO - PID 1641 | 18:57:31 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:57:31 INFO - PID 1641 | Full stack: 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | ************************* 18:57:31 INFO - PID 1641 | A coding exception was thrown and uncaught in a Task. 18:57:31 INFO - PID 1641 | 18:57:31 ERROR - PID 1641 | Full message: TypeError: NetworkError when attempting to fetch resource. 18:57:31 INFO - PID 1641 | Full stack: 18:57:31 INFO - PID 1641 | ************************* 18:58:49 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-ext.html (next: http://localhost:49235/tests/dromaeo/cssquery-jquery.html) 18:58:50 INFO - PID 1641 | RSS: Main: 266579968 18:58:50 INFO - PID 1641 | 19:01:52 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49235/tests/dromaeo/cssquery-mootools.html) 19:01:52 INFO - PID 1641 | RSS: Main: 278568960 19:01:52 INFO - PID 1641 | 19:04:54 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49235/tests/dromaeo/cssquery-prototype.html) 19:04:55 INFO - PID 1641 | RSS: Main: 271765504 19:04:55 INFO - PID 1641 | 19:07:58 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49235/tests/dromaeo/cssquery-yui.html) 19:07:59 INFO - PID 1641 | RSS: Main: 281976832 19:07:59 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | Cycle 1(1): loaded http://localhost:49235/tests/dromaeo/cssquery-yui.html (next: http://localhost:49235/tests/dromaeo/cssquery-dojo.html) 19:11:00 INFO - PID 1641 | RSS: Main: 275693568 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | __start_tp_report 19:11:00 INFO - PID 1641 | _x_x_mozilla_page_load 19:11:00 INFO - PID 1641 | _x_x_mozilla_page_load_details 19:11:00 INFO - PID 1641 | |i|pagename|runs| 19:11:00 INFO - PID 1641 | |0;dojo.html;3519.3665140274748;3536.628653991331;3544.4151714967024;3564.839582218803;3586.0855481852122;5966.284045914496;6122.2041134652545;6132.4480796728285;6160.722767475463;6232.5013998880095;4898.314236006961;5051.570616497602;6039.547033972459;6056.788012419566;6130.080487926802;5154.793808247666;5268.157094864813;5384.300040994665;5405.6214466282245;5475.4524547545125;5467.589930755189;5470.097433923398;5476.069068258385;5478.58910581706;5512.862178445531;400.52525630498116;4333.631641310492;4702.224133018048;5298.629095963284;5451.318585176853;4816.903661926777;5000.699818047297;5371.462853714636;5373.435789242112;5402.459754024605;5314.893702125936;5326.440723723992;5326.520613144836;5331.520163185293;5404.567634589223;5919.940800592025;5989.670568118751;6010.729517171738;6011.669358185298;6036.3360030396625;5724.341700704431;6461.93538064618;6517.837054073639;6524.282328943812;6525.216973963107;6601.306862779387;6602.141721576164;6613.537052406334;6618.602883826986;6627.469802415795;6116.143739876421;6257.374262573706;6275.372462753734;6281.371862813682;6299.6850157491945;6539.47684185264;6629.900551491732;6712.597244165366;6779.694913728894;6785.117934668461;6372.872542549216;6427.196600424947;6448;6461;6466.515011374166;6402.263739669997;6435.646039467781;6436.195475565554;6582.308857569982;6602.207735071822;6416.13382193398;6455.644939528277;6456.451201647913;6499.642519661464;6516.348365163446;6396.136521569621;6489.059086432441;6492.707828147651;6492.772752953719;6499.642519661369;5147.897042059138;5228.869278268074;5230.895382092413;5234.345706786652;5280.392754833243;4585.9541404585525;4626.699264547794;4717.787699553528;4745.833895813699;4764.833230836974;5057.367829021372;6155.6614386209185;6276;6339.017452294902;6343;6585.473162147016;6621.33786621334;6656.400923916871;6670.032845237413;6672.766453174116;6122.20411346531;6154;6165.8458538536;6251.624902505864;6336.85325234531;5799.971000145057;5803.361630220672;5807.796727114531;5851.38560451155;5858.238429004287;6738.225104112992;6776.4240039595215;6778.8644227116165;6779.3220677932795;6838.829029274209;6386.489080873612;6466.838329041811;6558.508111891722;6592.17597800275;6601.042848786995;6709.698063587248;6772.695228714818;6806.693698783665;6817.079694241214;6847.07564478789;6517.087607735016;6525.552917655879;6540.280569137486;6547.803565893031;6568.244651864906;5422.942526207352;6369.840753981188;6551.312112228147;6595;6628.536002479973;6303.306636269915;6305.306416294112;6322.36776322364;6362.109304697438;6370.58591191571;6302.495800336053;6403.29563747978;6418.903716444351;6443.516736244894;6450.54846160783;5330.653507521999;5435.456454354533;6144.064619644798;6170.043643235305;6255.874882502233;6362.936370636235;6430.196225471816;6449.806505804833;6461.2892581816905;6538.509611779231;5505.366882808368;6034.155218269358;6209.658468784261;6242.906356404748;6300.117983482224 19:11:00 INFO - PID 1641 | |1;ext.html;699.50335261964;789.1437789997852;835.1147783349647;1219.7438537907033;1276.1067252922956;29260.9758658447;35510.28979420413;36514.26971460569;36529;36650.45024324634;36838.447423288606;37174.628253717456;37248.25503489929;37340;37442.43836342457;7895.171007044264;8095.352371810271;8319.417640765148;8548.743537693848;8559.358048146414;6869;8502.06477287498;8614.612342444605;8620.396572239946;8630.223279904807;8583.828323433527;8620.741377758677;8640.870386944202;8644.956775216115;8647.437916535406;8572.528510931896;8619.956900215522;8622.741317760478;8642.697505587274;8662.52356120413;8425.70510032152;8435.746927592181;8484.618192181366;8510.957445212796;8540.188682075193;36460.90617281486;36614.26771464556;36622.45066324007;36667.81666091653;36715.63284367149;3582;3610.891673249807;3616;3616.782993020427;3618;30029.69970300291;30102.698973010207;30119.548206776915;30179.849100754574;30202.848985754932;3122.0945925681754;3146.3235404387938;3159.9842000790077;3173.857176427043;3180.3162320101287;178791;246018;265430.67284663644;274586;274918;45917;46054.53945460502;46150.5384946153;46217.76891115523;46227;47294.52705472901;47380.763096184295;47436.5256347439;47481.76259118682;47536.5246347539;14112.2943852809;14137.78793318101;14242.928785356007;14251.216183109822;14275.357608907623;11620.825687614693;11683.00694440982;11701.23941943788;11707.180497365103;11733.88266117345;12988.350582470839;13044.41300141496;13117.212967221998;13166.275854827885;13218.537351192663;5995.550333724988;6002.54980876436;6032.788852390146;6033.78881739137;6046.458153170914;1030.2427715629008;1041.3647674918293;1042.635077722791;1067.4662668665667;1076.9407682577532;17805.376811811526;20337.694934575993;23858.880705596708;24037.879810600836;24166.395840103785;1136.931784092957;1147.6098126636984;1173.9178257522062;1174.4890972426851;1176.7352345722145;11900.523979040914;12298.18478077272;12345.703671153991;12367.16810135944;12372.319522426353;280653;332773;333340;334226;334884;52025.7398713004;52063.95872082537;52181;52210.477895221324;52355.95288094217;1513.5005448202287;1547.2882474062058;1559.485369827954;1560.9141497217536;1569.9764503532456;20298.69551956692;23197.304080877602;23501.647475287544;23717.93269302851;23854.88072559626;34666.306673866886;35799.82100089483;36009.639903600626;36020.63979360173;36326.818365908;310798;311090;311894;311945;312202;27757;31638.892638758454;32274;32312.83843580767;32680.67319326776;42227.3665895018;42961;42998.14003719971;43065.923351916455;43353;25697.100601478858;28044.859775700992;29296.56055159217;29921.401571968872;30189.245268868453;22788;27808.582871257353;28292.009779657612;30360.696393035785;30448.238794030327;35249;35758.10604734902;35840.924772256876;35857.28285434329;35925.92222233337;29792.935201015447;29860;30233;30469.019513731535;30601.23496912595;21851.67222491632;23154.768452315937;23794.635510942513;24123;24167.879160604083 19:11:00 INFO - PID 1641 | |2;jquery.html;433.39757736745906;441.84977107783345;443.92009438301113;446.7676808059811;455.11480171067274;19652.90173549132;21143.365699029015;21791.782082179172;21941.122355105777;22028;21697;22832;23135.07459701614;23150.88424557875;23417;6010.437286305549;6103.786367477139;6360.55476116672;6368.108464814906;6386.10594516768;6125.938740612603;6283.214598175228;6292.276388215368;6306.905396419034;6331.651759153245;5692.089265717486;5887.88224235515;5981.252343457068;6167.444929956303;6168.876622467548;6132.846678833021;6163.414475624831;6228.968855155696;6230.314665386803;6246.156768836195;5450.727463626842;5465.152901300305;5494.148406996922;5517.972410137963;5528.032594296023;26627.60351568368;26761.46477070467;26778.661066946574;26821.32946676329;27146.86426567874;2143.3569929021232;2196.417949243452;2200.8679479231296;2203.911843526257;2205.106931692667;17793.288268469245;17812.55468613295;17828.930264184062;18092.728609070873;18333.358332458432;2147.903344349508;2187.5406164705432;2223.5330580578097;2225.6105181593157;2234.1063574570303;147888;149527;150688;150735.24632376878;152372.2381388086;11981.46083426248;12513.3743312834;12519.624411267676;12577.371131443391;12612.684682882818;15983.200839957955;16077.678446431006;16089.115098669452;16113.516594502184;16129.596760081093;8817.911820881838;9031.683891063782;9046.095390460901;9071.27429805614;9186.908130918739;8566.443181193203;8664.393492455594;8668.60991255395;8857.734267971971;8862.42394244372;7735.6905723770415;7862.331701805296;7871.252231038042;7902.407319451064;7915.960420197862;2192.3094225318973;2214.402111429905;2221.811146052604;2225.799678028985;2233.7207849018873;939.0703203828298;970.553545369124;983.3264214013424;1004.477671610773;1029.4183786160795;18136.818631813778;19898.104585293695;20533.897330513253;21023.579528409347;21451.78548214529;1012.9088382045504;1020.4387586827215;1053.6838948315474;1065.984010239847;1069.6363236499628;10138.898611013796;11136.32955056748;11148.888511114947;11170.324451332202;11231.66305010851;629458;705297;725711;735472;739639;121895.3905230486;130141.34929325293;132339;144760.5523944747;158330;1204.0909113619161;1205.4695933789105;1245.103525461666;1247.4261839553726;1247.9438425270885;21760.89119554392;22301.553968920205;23037.308880733606;23064;23070.538589227785;65338.34661653323;65389;65751.67124164349;66161;68869.31130688629;67752;73536;93363.86269784885;93574;94225.64435888884;7253.238409966877;8663.610137543952;8833.734987950373;8903.376763626484;8913.242374398249;13994.930025350215;14070.577882663536;14099.365528551445;14337.856621433652;14395.568132956028;11877;11904.226225295328;11971.521139154336;12157.148999569945;12240.510379584717;10074.345167564084;10794.815529418684;11070.5571777128;11217.102631789274;11508.136889733136;12192.390380481118;12311.19977201479;12320.75358492843;12331.383430828602;12335.14972754094;8887.11128887132;8936.597853096757;8951.955240223757;8967.372283940062;9199.770005749642;11445.599404021152;11466.598669046543;12052.337121458404;12287;12318.445669944695 19:11:00 INFO - PID 1641 | |3;mootools.html;1340.7385559815834;1400.6288333591597;1456.4028748213234;1469.3314541883444;1496.3565666763295;11197.27217730848;11607.593734219305;11619.244749091304;11770.234934729237;12043.759124817498;11644.126690498204;11774.352410617412;11825.7634847303;11827.053835693145;11896.107791915598;2483.577791775398;2526.911558095467;2528.1404322530334;2528.633348164515;2536.6575512305785;2459.348272707734;2492.750724927511;2516.421223118684;2519.735427780084;2534.1003943600035;2442.7068751749725;2502.4744803591266;2514.798816094717;2515.8993640254366;2518.8110891683195;2133.9999999999923;2276.6129757941026;2305;2476.3561474016797;2490.116008816862;2421.685180926468;2474.9010039598393;2476.9133080342103;2523.5331463679277;2527.886245118974;11790.764184716343;11862.703432414259;11905;11911.761764764657;11919.88080119205;5913.615614985012;6599.571027883172;6824.249332573413;6847.246802851732;6847.8972815407315;4077.4495443115097;4288.270993931039;4342.392065110887;4411.889702757425;4418.624416924566;1221.2794451273703;1243.1360204657767;1294.5598496511138;1297;1302.446460254388;13825.79261311081;16178.83821161785;16286.26711797972;16431.589210269718;16911.408100716417;4794.688345257547;5512.834614961557;5530.419305972815;5728.369879313273;5869.6771677558145;12142.392880356123;12171.513139474499;12215.839495247938;12219.69450763738;12238.938805305916;6605.669716514155;6656.901611234092;6659.966700166565;6662.100616416722;6674.899876501856;6124.78563250293;6173.567850250529;6256.124142620037;6312.715927783261;6320.905186422207;4314.309710446313;4417.889552761142;4444.2000439920475;4466;4497.2129877271345;1300.8504022037398;1315.421214665544;1328.8538260791306;1338.872807083345;1339.6784771654927;774.3882332957013;798.9174668324337;806.8668669669556;823.7240524424385;834.6661335465863;6158.938410615837;6171.444569988633;6328.71520781566;6385.553011289165;6432.195975503062;588.52917665868;589.4812564942908;589.5136512377306;596.9015112506474;611.5077362722961;4119.546849846514;4281.914361712748;4298.290782020931;4313.856827940598;4417.558244175557;12142;15299.770503442458;15466.690666186612;15471.22643867802;15476.148811815234;12752.936235318764;13677.863221367857;15448.304826282843;15458.84541154574;15481.993670411388;9348.158665719982;10825.621103261348;11104.833427498594;11109.666709998713;11131.332120072822;6204.658743769136;6462.903056454251;6544.214694236722;6953.304669533209;7155.499115061895;13365.53220637273;13567.1859688419;13620.659483512594;13843.169409835442;13970.231637260049;11364.318140911571;12278.693032674255;13945.233012184428;14018.018738688193;14079.366428510946;7103.680334384861;7886.819342451554;7955.920440795518;8712.303015758851;9248.907510924804;9266;10739.516721747696;10759.977802108788;10874.238803283693;10889.401082940198;9136.49749263797;9149.31380146505;9225.86161207569;9251.028641992494;9455.527223638654;9040.41237319572;9053.68312109073;9092.727218183465;9172.724818255463;9179.26565874715;8101.959490202511;8120.187981201832;8233.34133269349;8234.094249632415;8286.627101780314;5997.610155340063;6027.909581356371;6081.209442772588;6209.130721698875;6213.9067913982235;5192;6034.426729460694;6101.420365065312;6175.073738939195;6234.064890266496 19:11:00 INFO - PID 1641 | |4;prototype.html;347.2741969284196;388.5027165228507;404.2339766143157;410.2615292473547;412.1262922604078;5971.283445986477;6149.907751383722;6173.598716083451;6197.977333739927;6287;5824.660328124534;6067.787627433041;6111.572189946705;6112.908306375408;6143.648397352576;3704.1665625234377;3841.8847434577006;4628.421447319085;4669.182892993729;4678.43858736952;3811.8284677189595;4083.9183216335655;4391.429114215148;4484.686071974963;4504.639628829702;3844.4233364995334;4407.713498622596;4429.645628349725;4431.689781715281;4475.172093162759;4423.800928958204;4439.1565602535575;4456.621187199092;4482.170798402306;4490.955090449086;3748.9625103748886;3825.483559719458;3856.749311294785;3866.168773713665;3871.728978971473;5870.941290587082;5890.381509941437;5927.585069045169;5927.911081333784;5931.881362372772;4839.758012099416;5117.795288188468;5301.814436494704;5306.3632364116165;5346.144616861284;5696.316442026942;5745.1382292656435;6337.207849018872;6433.600988264361;6504.349565043505;4197.643200327976;4361.149575832714;4370.606645401897;4381.38660587518;4408.493023302297;6720;6884.827879303008;6907.965460172717;6942.132233470817;6949.30506949301;3864.942025869614;4831.396075490564;4857.732824694605;4857.878553036132;4884.3650325457465;4573.199690054227;4728.858134255978;4918.409790825052;4990.226514890198;4991.276264941564;4266.658667306608;5281.023010743025;5541.972290138524;5581.748821303051;5590.496855283044;6013.436506508339;6615.536912416084;6624.867502650017;6633.170853643294;6653.534252602271;1360.7550640884538;1617.7896873406617;1618.7005404000297;1710.9828901711073;1714.4256674014096;1049.129222745135;1162.8895254950767;1165.6012784658274;1178.7446369616332;1197.275648232824;5365;5422.75597598109;6150.876982460326;6164.630122192682;6181.381861813873;5312.3890752563175;5517.2551705520045;6081.726322315506;6698.5980841149685;6730.226024007204;5850.47345738877;6139.877202456015;6184.670295886406;6203.844903877439;6249.187605611241;3128.7015888406513;3498.650134986481;3557.879268030562;3565.5186549815444;3569.3402567806097;6073.51411887048;6079.422454866869;6442.871142577123;6835.213950395669;6990.755323563752;6097.481714054266;6706.960421134732;6975.720971161097;7029.89455158173;7042.9435584662715;6160.876782464416;6716.29478904708;7016.684249208798;7077.469189810682;7107.040549525748;5649.858753531194;6789.626570538668;6846.281140480178;6866.34769696878;6872.381485666314;6205.782797602063;6470.611763294218;6641.970494573349;6772.255051944184;6825.726970921306;6918.377346038881;6992.895106573507;7056.6118863463;7068.505204635626;7081.760691878798;4136.152088821724;4340.457442819647;4450.955490445054;4474.597286244254;4476.4180656514445;6597.208334999831;6798.3881450667695;6812.284710105566;6821.658917054227;6862.142232220973;4898.118338699069;5248.343957005374;6037.305709843425;6568.671566421564;6584.703688333941;5148.7425628717665;6216.316205217513;6394.392532709571;6533.901991470227;6554.180727409074;5609.775608975759;6369.172007638977;6402.487800975818;6460.967695161494;6489.935100648933;5382.623216374816;5759.078547432391;6093.786717465045;6318.462930650946;6596.241432235354;6026.885026269979;6028.849278768066;6392.9041064385;6398.5365634232085;6409.4231519163495 19:11:00 INFO - PID 1641 | |5;yui.html;740.0268646729551;828.8342331533693;838.3460900497614;856.131027007587;882.0914458108149;16385.262663180154;16919.49241522753;16947.57631059224;18297.63404731908;19300.806991930076;17933.10334483274;18348.082595870186;18401.631967360645;18478.07609619517;18746.25014999402;979.2998006425404;989.8119357322122;992.1566668331897;993.3592832622954;996.6960077176494;1012.6303899076858;1016.4917541229386;1018.9949050254738;1019.6838979916213;1020.3367810922886;1067.092970974673;1071.5124618298694;1071.73742433104;1072.9463526823668;1073.0771536478621;1052.0584077250817;1053.1995683280722;1064.2018486135398;1065.339489516497;1065.5471424644495;755.1165136789914;767.9923200768033;770.4029377232633;771.3135309574483;771.8379140380526;16742.66514669712;17146.39987600428;17197.054162021082;17304.826951730574;17315.913420432942;16684.33262669492;16932.984020958782;17069.829301707072;17162.31350745981;17169;13688;13742.450301987908;13981.09122907018;14047.719045619031;14084.366203520865;3442.2599141184764;3495.405781017233;3514.7539672222956;3515.789052656849;3516.5604299462566;108329.45835270852;118795;119407;119779.40110299393;120448;13474;13674.726505470035;13760.862391376158;13789.862101379058;13811.930940345233;24726.381840454134;25336.74663253344;25347.239582812545;25641.102561410262;25734.74265257361;9114.498702571289;9265.583048762823;9266.583003764712;9276.257899368034;9299.953500232456;4817.181079216542;4836.395450568679;4846.200376937766;4860.781264843091;4906.092372910952;4285.485741711014;4412.779361031936;4415.51429342772;4419.1382680377665;4420.8673739787855;860.595520105562;927.8793756811572;941.152962333905;975.1662328708993;989.2036910287144;648.3646026893671;672.1765836849918;677.8440958579554;681.7784220128478;683.9829004274833;11088.168387370979;11607.825882611767;11724.706882327839;11859.288442693465;12246.877531224574;597.8206538038571;636.8758092172075;671.737134187724;676.7259259999709;688.8174633722068;5136.280920671109;5428.5928555358505;5614.214010038598;5670.234518340054;5675.687837168995;86205;86963;87050;92588;93228.06771932195;54125.72937135368;68278;75905.62047189729;83507.49477515684;87529;57367.71316143476;77064;77089.2291077082;77763;80391.99020024546;12041.759164816829;12420.565280215511;12585.119041667362;12586.370681466073;12609.054320925783;39825;40580.79709601433;41070.79464602658;41098.76703698894;41361.96595085147;50574.494255056976;50780.746096269286;50917;51099.74450127874;51503;15844.762328565312;15908.443204487787;16005.519834404986;16252.837471625606;16307.592310191865;17637.29450821953;17911.283548657906;18095.276188952295;18213.54466138357;18217.99801010957;15945.68108637844;16026;16290.755638665667;16469.011859288483;16569.66860662804;13358.198508089547;16276.267567959234;16331;16348.918255408647;16616.75074873902;15891.920540397225;15906.443274485337;15939.36242550285;15982.680346392774;16109.838901610834;11776.352300623548;11777.882221177679;12148.817767733315;12212.145149839425;12341.197822141532;12437.440315185657;12741.299228542519;12803.551875684307;12862.356882155667;12896.290704010995 19:11:00 INFO - PID 1641 | __end_tp_report 19:11:00 INFO - PID 1641 | __start_cc_report 19:11:00 INFO - PID 1641 | _x_x_mozilla_cycle_collect,439 19:11:00 INFO - PID 1641 | __end_cc_report 19:11:00 INFO - PID 1641 | __startTimestamp1492135860955__endTimestamp 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | ------- Summary: start ------- 19:11:00 INFO - PID 1641 | Number of tests: 6 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#0] dojo.html Cycles:165 Average:5973.84 Median:6299.69 stddev:847.81 (13.5%) stddev-sans-first:828.26 19:11:00 INFO - PID 1641 | Values: 3519.4 3536.6 3544.4 3564.8 3586.1 5966.3 6122.2 6132.4 6160.7 6232.5 4898.3 5051.6 6039.5 6056.8 6130.1 5154.8 5268.2 5384.3 5405.6 5475.5 5467.6 5470.1 5476.1 5478.6 5512.9 400.5 4333.6 4702.2 5298.6 5451.3 4816.9 5000.7 5371.5 5373.4 5402.5 5314.9 5326.4 5326.5 5331.5 5404.6 5919.9 5989.7 6010.7 6011.7 6036.3 5724.3 6461.9 6517.8 6524.3 6525.2 6601.3 6602.1 6613.5 6618.6 6627.5 6116.1 6257.4 6275.4 6281.4 6299.7 6539.5 6629.9 6712.6 6779.7 6785.1 6372.9 6427.2 6448.0 6461.0 6466.5 6402.3 6435.6 6436.2 6582.3 6602.2 6416.1 6455.6 6456.5 6499.6 6516.3 6396.1 6489.1 6492.7 6492.8 6499.6 5147.9 5228.9 5230.9 5234.3 5280.4 4586.0 4626.7 4717.8 4745.8 4764.8 5057.4 6155.7 6276.0 6339.0 6343.0 6585.5 6621.3 6656.4 6670.0 6672.8 6122.2 6154.0 6165.8 6251.6 6336.9 5800.0 5803.4 5807.8 5851.4 5858.2 6738.2 6776.4 6778.9 6779.3 6838.8 6386.5 6466.8 6558.5 6592.2 6601.0 6709.7 6772.7 6806.7 6817.1 6847.1 6517.1 6525.6 6540.3 6547.8 6568.2 5422.9 6369.8 6551.3 6595.0 6628.5 6303.3 6305.3 6322.4 6362.1 6370.6 6302.5 6403.3 6418.9 6443.5 6450.5 5330.7 5435.5 6144.1 6170.0 6255.9 6362.9 6430.2 6449.8 6461.3 6538.5 5505.4 6034.2 6209.7 6242.9 6300.1 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#1] ext.html Cycles:180 Average:43865.96 Median:17727.16 stddev:78035.63 (440.2%) stddev-sans-first:78187.24 19:11:00 INFO - PID 1641 | Values: 699.5 789.1 835.1 1219.7 1276.1 29261.0 35510.3 36514.3 36529.0 36650.5 36838.4 37174.6 37248.3 37340.0 37442.4 7895.2 8095.4 8319.4 8548.7 8559.4 6869.0 8502.1 8614.6 8620.4 8630.2 8583.8 8620.7 8640.9 8645.0 8647.4 8572.5 8620.0 8622.7 8642.7 8662.5 8425.7 8435.7 8484.6 8511.0 8540.2 36460.9 36614.3 36622.5 36667.8 36715.6 3582.0 3610.9 3616.0 3616.8 3618.0 30029.7 30102.7 30119.5 30179.8 30202.8 3122.1 3146.3 3160.0 3173.9 3180.3 178791.0 246018.0 265430.7 274586.0 274918.0 45917.0 46054.5 46150.5 46217.8 46227.0 47294.5 47380.8 47436.5 47481.8 47536.5 14112.3 14137.8 14242.9 14251.2 14275.4 11620.8 11683.0 11701.2 11707.2 11733.9 12988.4 13044.4 13117.2 13166.3 13218.5 5995.6 6002.5 6032.8 6033.8 6046.5 1030.2 1041.4 1042.6 1067.5 1076.9 17805.4 20337.7 23858.9 24037.9 24166.4 1136.9 1147.6 1173.9 1174.5 1176.7 11900.5 12298.2 12345.7 12367.2 12372.3 280653.0 332773.0 333340.0 334226.0 334884.0 52025.7 52064.0 52181.0 52210.5 52356.0 1513.5 1547.3 1559.5 1560.9 1570.0 20298.7 23197.3 23501.6 23717.9 23854.9 34666.3 35799.8 36009.6 36020.6 36326.8 310798.0 311090.0 311894.0 311945.0 312202.0 27757.0 31638.9 32274.0 32312.8 32680.7 42227.4 42961.0 42998.1 43065.9 43353.0 25697.1 28044.9 29296.6 29921.4 30189.2 22788.0 27808.6 28292.0 30360.7 30448.2 35249.0 35758.1 35840.9 35857.3 35925.9 29792.9 29860.0 30233.0 30469.0 30601.2 21851.7 23154.8 23794.6 24123.0 24167.9 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#2] jquery.html Cycles:180 Average:40608.61 Median:2225.71 stddev:118228.69 (5312.0%) stddev-sans-first:118521.87 19:11:00 INFO - PID 1641 | Values: 433.4 441.8 443.9 446.8 455.1 19652.9 21143.4 21791.8 21941.1 22028.0 21697.0 22832.0 23135.1 23150.9 23417.0 6010.4 6103.8 6360.6 6368.1 6386.1 6125.9 6283.2 6292.3 6306.9 6331.7 5692.1 5887.9 5981.3 6167.4 6168.9 6132.8 6163.4 6229.0 6230.3 6246.2 5450.7 5465.2 5494.1 5518.0 5528.0 26627.6 26761.5 26778.7 26821.3 27146.9 2143.4 2196.4 2200.9 2203.9 2205.1 17793.3 17812.6 17828.9 18092.7 18333.4 2147.9 2187.5 2223.5 2225.6 2234.1 147888.0 149527.0 150688.0 150735.2 152372.2 11981.5 12513.4 12519.6 12577.4 12612.7 15983.2 16077.7 16089.1 16113.5 16129.6 8817.9 9031.7 9046.1 9071.3 9186.9 8566.4 8664.4 8668.6 8857.7 8862.4 7735.7 7862.3 7871.3 7902.4 7916.0 2192.3 2214.4 2221.8 2225.8 2233.7 939.1 970.6 983.3 1004.5 1029.4 18136.8 19898.1 20533.9 21023.6 21451.8 1012.9 1020.4 1053.7 1066.0 1069.6 10138.9 11136.3 11148.9 11170.3 11231.7 629458.0 705297.0 725711.0 735472.0 739639.0 121895.4 130141.3 132339.0 144760.6 158330.0 1204.1 1205.5 1245.1 1247.4 1247.9 21760.9 22301.6 23037.3 23064.0 23070.5 65338.3 65389.0 65751.7 66161.0 68869.3 67752.0 73536.0 93363.9 93574.0 94225.6 7253.2 8663.6 8833.7 8903.4 8913.2 13994.9 14070.6 14099.4 14337.9 14395.6 11877.0 11904.2 11971.5 12157.1 12240.5 10074.3 10794.8 11070.6 11217.1 11508.1 12192.4 12311.2 12320.8 12331.4 12335.1 8887.1 8936.6 8952.0 8967.4 9199.8 11445.6 11466.6 12052.3 12287.0 12318.4 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#3] mootools.html Cycles:180 Average:7120.08 Median:2531.37 stddev:4500.83 (177.8%) stddev-sans-first:4492.51 19:11:00 INFO - PID 1641 | Values: 1340.7 1400.6 1456.4 1469.3 1496.4 11197.3 11607.6 11619.2 11770.2 12043.8 11644.1 11774.4 11825.8 11827.1 11896.1 2483.6 2526.9 2528.1 2528.6 2536.7 2459.3 2492.8 2516.4 2519.7 2534.1 2442.7 2502.5 2514.8 2515.9 2518.8 2134.0 2276.6 2305.0 2476.4 2490.1 2421.7 2474.9 2476.9 2523.5 2527.9 11790.8 11862.7 11905.0 11911.8 11919.9 5913.6 6599.6 6824.2 6847.2 6847.9 4077.4 4288.3 4342.4 4411.9 4418.6 1221.3 1243.1 1294.6 1297.0 1302.4 13825.8 16178.8 16286.3 16431.6 16911.4 4794.7 5512.8 5530.4 5728.4 5869.7 12142.4 12171.5 12215.8 12219.7 12238.9 6605.7 6656.9 6660.0 6662.1 6674.9 6124.8 6173.6 6256.1 6312.7 6320.9 4314.3 4417.9 4444.2 4466.0 4497.2 1300.9 1315.4 1328.9 1338.9 1339.7 774.4 798.9 806.9 823.7 834.7 6158.9 6171.4 6328.7 6385.6 6432.2 588.5 589.5 589.5 596.9 611.5 4119.5 4281.9 4298.3 4313.9 4417.6 12142.0 15299.8 15466.7 15471.2 15476.1 12752.9 13677.9 15448.3 15458.8 15482.0 9348.2 10825.6 11104.8 11109.7 11131.3 6204.7 6462.9 6544.2 6953.3 7155.5 13365.5 13567.2 13620.7 13843.2 13970.2 11364.3 12278.7 13945.2 14018.0 14079.4 7103.7 7886.8 7955.9 8712.3 9248.9 9266.0 10739.5 10760.0 10874.2 10889.4 9136.5 9149.3 9225.9 9251.0 9455.5 9040.4 9053.7 9092.7 9172.7 9179.3 8102.0 8120.2 8233.3 8234.1 8286.6 5997.6 6027.9 6081.2 6209.1 6213.9 5192.0 6034.4 6101.4 6175.1 6234.1 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#4] prototype.html Cycles:180 Average:5271.71 Median:5880.66 stddev:1634.98 (27.8%) stddev-sans-first:1597.24 19:11:00 INFO - PID 1641 | Values: 347.3 388.5 404.2 410.3 412.1 5971.3 6149.9 6173.6 6198.0 6287.0 5824.7 6067.8 6111.6 6112.9 6143.6 3704.2 3841.9 4628.4 4669.2 4678.4 3811.8 4083.9 4391.4 4484.7 4504.6 3844.4 4407.7 4429.6 4431.7 4475.2 4423.8 4439.2 4456.6 4482.2 4491.0 3749.0 3825.5 3856.7 3866.2 3871.7 5870.9 5890.4 5927.6 5927.9 5931.9 4839.8 5117.8 5301.8 5306.4 5346.1 5696.3 5745.1 6337.2 6433.6 6504.3 4197.6 4361.1 4370.6 4381.4 4408.5 6720.0 6884.8 6908.0 6942.1 6949.3 3864.9 4831.4 4857.7 4857.9 4884.4 4573.2 4728.9 4918.4 4990.2 4991.3 4266.7 5281.0 5542.0 5581.7 5590.5 6013.4 6615.5 6624.9 6633.2 6653.5 1360.8 1617.8 1618.7 1711.0 1714.4 1049.1 1162.9 1165.6 1178.7 1197.3 5365.0 5422.8 6150.9 6164.6 6181.4 5312.4 5517.3 6081.7 6698.6 6730.2 5850.5 6139.9 6184.7 6203.8 6249.2 3128.7 3498.7 3557.9 3565.5 3569.3 6073.5 6079.4 6442.9 6835.2 6990.8 6097.5 6707.0 6975.7 7029.9 7042.9 6160.9 6716.3 7016.7 7077.5 7107.0 5649.9 6789.6 6846.3 6866.3 6872.4 6205.8 6470.6 6642.0 6772.3 6825.7 6918.4 6992.9 7056.6 7068.5 7081.8 4136.2 4340.5 4451.0 4474.6 4476.4 6597.2 6798.4 6812.3 6821.7 6862.1 4898.1 5248.3 6037.3 6568.7 6584.7 5148.7 6216.3 6394.4 6533.9 6554.2 5609.8 6369.2 6402.5 6461.0 6489.9 5382.6 5759.1 6093.8 6318.5 6596.2 6026.9 6028.8 6392.9 6398.5 6409.4 19:11:00 INFO - PID 1641 | 19:11:00 INFO - PID 1641 | [#5] yui.html Cycles:180 Average:20436.87 Median:18374.86 stddev:27210.10 (148.1%) stddev-sans-first:27246.24 19:11:00 INFO - PID 1641 | Values: 740.0 828.8 838.3 856.1 882.1 16385.3 16919.5 16947.6 18297.6 19300.8 17933.1 18348.1 18401.6 18478.1 18746.3 979.3 989.8 992.2 993.4 996.7 1012.6 1016.5 1019.0 1019.7 1020.3 1067.1 1071.5 1071.7 1072.9 1073.1 1052.1 1053.2 1064.2 1065.3 1065.5 755.1 768.0 770.4 771.3 771.8 16742.7 17146.4 17197.1 17304.8 17315.9 16684.3 16933.0 17069.8 17162.3 17169.0 13688.0 13742.5 13981.1 14047.7 14084.4 3442.3 3495.4 3514.8 3515.8 3516.6 108329.5 118795.0 119407.0 119779.4 120448.0 13474.0 13674.7 13760.9 13789.9 13811.9 24726.4 25336.7 25347.2 25641.1 25734.7 9114.5 9265.6 9266.6 9276.3 9300.0 4817.2 4836.4 4846.2 4860.8 4906.1 4285.5 4412.8 4415.5 4419.1 4420.9 860.6 927.9 941.2 975.2 989.2 648.4 672.2 677.8 681.8 684.0 11088.2 11607.8 11724.7 11859.3 12246.9 597.8 636.9 671.7 676.7 688.8 5136.3 5428.6 5614.2 5670.2 5675.7 86205.0 86963.0 87050.0 92588.0 93228.1 54125.7 68278.0 75905.6 83507.5 87529.0 57367.7 77064.0 77089.2 77763.0 80392.0 12041.8 12420.6 12585.1 12586.4 12609.1 39825.0 40580.8 41070.8 41098.8 41362.0 50574.5 50780.7 50917.0 51099.7 51503.0 15844.8 15908.4 16005.5 16252.8 16307.6 17637.3 17911.3 18095.3 18213.5 18218.0 15945.7 16026.0 16290.8 16469.0 16569.7 13358.2 16276.3 16331.0 16348.9 16616.8 15891.9 15906.4 15939.4 15982.7 16109.8 11776.4 11777.9 12148.8 12212.1 12341.2 12437.4 12741.3 12803.6 12862.4 12896.3 19:11:00 INFO - PID 1641 | -------- Summary: end -------- 19:11:00 INFO - PID 1641 | 19:11:01 INFO - TEST-INFO | 1641: exit 0 19:11:01 INFO - TEST-OK | dromaeo_css | took 1114984ms 19:11:01 INFO - TEST-START | kraken 19:11:01 INFO - operating with platform_type : mac_ 19:11:01 INFO - Initialising browser for kraken test... 19:11:01 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmp_rRJ5t/profile http://localhost:49235/getInfo.html) 19:11:04 INFO - PID 1653 | 2017-04-13 19:11:04.249 plugin-container[1654:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8743, name = 'com.apple.tsm.portname' 19:11:04 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:04 INFO - PID 1653 | 2017-04-13 19:11:04.252 plugin-container[1654:10038] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 19:11:04 INFO - PID 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:04 INFO - PID 1653 | 2017-04-13 19:11:04.252 plugin-container[1654:10038] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:11:04 INFO - PID 1653 | __metrics Screen width/height:1600/1200 19:11:04 INFO - PID 1653 | colorDepth:24 19:11:04 INFO - PID 1653 | Browser inner width/height: 1024/689 19:11:04 INFO - PID 1653 | __metrics 19:11:04 ERROR - PID 1653 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3374: TypeError: this.tabs is undefined 19:11:05 INFO - TEST-INFO | 1653: exit 0 19:11:05 INFO - Browser initialized. 19:11:05 INFO - Running cycle 1/1 for kraken test... 19:11:05 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/T/tmp_rRJ5t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:11:07 INFO - PID 1655 | 2017-04-13 19:11:07.748 plugin-container[1656:10159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 19:11:07 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:07 INFO - PID 1655 | 2017-04-13 19:11:07.749 plugin-container[1656:10159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0f, name = 'com.apple.CFPasteboardClient' 19:11:07 INFO - PID 1655 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:11:07 INFO - PID 1655 | 2017-04-13 19:11:07.749 plugin-container[1656:10159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:11:17 INFO - PID 1655 | RSS: Main: 250638336 19:11:17 INFO - PID 1655 | 19:11:20 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection) 19:11:21 INFO - PID 1655 | RSS: Main: 259493888 19:11:21 INFO - PID 1655 | 19:11:24 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-dft) 19:11:24 INFO - PID 1655 | RSS: Main: 266604544 19:11:24 INFO - PID 1655 | 19:11:28 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-fft) 19:11:29 INFO - PID 1655 | RSS: Main: 262938624 19:11:29 INFO - PID 1655 | 19:11:30 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator) 19:11:31 INFO - PID 1655 | RSS: Main: 264556544 19:11:31 INFO - PID 1655 | 19:11:33 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:11:34 INFO - PID 1655 | RSS: Main: 264540160 19:11:34 INFO - PID 1655 | 19:11:37 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom) 19:11:37 INFO - PID 1655 | RSS: Main: 269598720 19:11:37 INFO - PID 1655 | 19:11:41 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate) 19:11:41 INFO - PID 1655 | RSS: Main: 270012416 19:11:41 INFO - PID 1655 | 19:11:44 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial) 19:11:45 INFO - PID 1655 | RSS: Main: 270983168 19:11:45 INFO - PID 1655 | 19:11:46 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:11:47 INFO - PID 1655 | RSS: Main: 270782464 19:11:47 INFO - PID 1655 | 19:11:49 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:11:49 INFO - PID 1655 | RSS: Main: 270843904 19:11:49 INFO - PID 1655 | 19:11:51 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:11:52 INFO - PID 1655 | RSS: Main: 270831616 19:11:52 INFO - PID 1655 | 19:11:55 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:11:56 INFO - PID 1655 | RSS: Main: 270999552 19:11:56 INFO - PID 1655 | 19:11:59 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:12:00 INFO - PID 1655 | RSS: Main: 270925824 19:12:00 INFO - PID 1655 | 19:12:01 INFO - PID 1655 | Cycle 1(1): loaded http://localhost:49235/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49235/tests/kraken/driver.html?testName=ai-astar) 19:12:02 INFO - PID 1655 | RSS: Main: 272400384 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | __start_tp_report 19:12:02 INFO - PID 1655 | _x_x_mozilla_page_load 19:12:02 INFO - PID 1655 | _x_x_mozilla_page_load_details 19:12:02 INFO - PID 1655 | |i|pagename|runs| 19:12:02 INFO - PID 1655 | |0;ai-astar;83;134;169;168;118;115;116;115;116;116 19:12:02 INFO - PID 1655 | |1;audio-beat-detection;140;113;135;182;116;112;111;111;133;113 19:12:02 INFO - PID 1655 | |2;audio-dft;194;204;230;226;231;198;196;197;217;278 19:12:02 INFO - PID 1655 | |3;audio-fft;81;59;60;105;79;58;58;57;75;62 19:12:02 INFO - PID 1655 | |4;audio-oscillator;81;71;84;70;69;75;109;99;102;93 19:12:02 INFO - PID 1655 | |5;imaging-gaussian-blur;127;88;103;110;89;88;87;89;125;88 19:12:02 INFO - PID 1655 | |6;imaging-darkroom;105;113;97;98;105;97;98;100;98;106 19:12:02 INFO - PID 1655 | |7;imaging-desaturate;92;91;90;100;93;92;90;92;116;105 19:12:02 INFO - PID 1655 | |8;json-parse-financial;49;47;48;52;51;52;52;53;52;53 19:12:02 INFO - PID 1655 | |9;json-stringify-tinderbox;58;63;57;68;67;61;58;69;78;83 19:12:02 INFO - PID 1655 | |10;stanford-crypto-aes;83;83;84;85;84;83;84;84;82;85 19:12:02 INFO - PID 1655 | |11;stanford-crypto-ccm;139;149;134;144;154;155;159;162;195;187 19:12:02 INFO - PID 1655 | |12;stanford-crypto-pbkdf2;187;188;187;184;209;229;182;186;185;186 19:12:02 INFO - PID 1655 | |13;stanford-crypto-sha256-iterative;70;70;69;70;69;67;67;67;70;69 19:12:02 INFO - PID 1655 | __end_tp_report 19:12:02 INFO - PID 1655 | __start_cc_report 19:12:02 INFO - PID 1655 | _x_x_mozilla_cycle_collect,1019 19:12:02 INFO - PID 1655 | __end_cc_report 19:12:02 INFO - PID 1655 | __startTimestamp1492135922084__endTimestamp 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | ------- Summary: start ------- 19:12:02 INFO - PID 1655 | Number of tests: 14 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#0] ai-astar Cycles:10 Average:125.00 Median:126.00 stddev:26.08 (20.7%) stddev-sans-first:22.81 19:12:02 INFO - PID 1655 | Values: 83.0 134.0 169.0 168.0 118.0 115.0 116.0 115.0 116.0 116.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#1] audio-beat-detection Cycles:10 Average:126.60 Median:124.50 stddev:22.47 (18.0%) stddev-sans-first:23.30 19:12:02 INFO - PID 1655 | Values: 140.0 113.0 135.0 182.0 116.0 112.0 111.0 111.0 133.0 113.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#2] audio-dft Cycles:10 Average:217.10 Median:221.50 stddev:25.96 (11.7%) stddev-sans-first:26.16 19:12:02 INFO - PID 1655 | Values: 194.0 204.0 230.0 226.0 231.0 198.0 196.0 197.0 217.0 278.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#3] audio-fft Cycles:10 Average:69.40 Median:61.00 stddev:15.60 (25.6%) stddev-sans-first:15.97 19:12:02 INFO - PID 1655 | Values: 81.0 59.0 60.0 105.0 79.0 58.0 58.0 57.0 75.0 62.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#4] audio-oscillator Cycles:10 Average:85.30 Median:78.00 stddev:14.60 (18.7%) stddev-sans-first:15.40 19:12:02 INFO - PID 1655 | Values: 81.0 71.0 84.0 70.0 69.0 75.0 109.0 99.0 102.0 93.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#5] imaging-gaussian-blur Cycles:10 Average:99.40 Median:88.00 stddev:15.99 (18.2%) stddev-sans-first:13.49 19:12:02 INFO - PID 1655 | Values: 127.0 88.0 103.0 110.0 89.0 88.0 87.0 89.0 125.0 88.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#6] imaging-darkroom Cycles:10 Average:101.70 Median:97.00 stddev:5.33 (5.5%) stddev-sans-first:5.52 19:12:02 INFO - PID 1655 | Values: 105.0 113.0 97.0 98.0 105.0 97.0 98.0 100.0 98.0 106.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#7] imaging-desaturate Cycles:10 Average:96.10 Median:91.50 stddev:8.50 (9.3%) stddev-sans-first:8.89 19:12:02 INFO - PID 1655 | Values: 92.0 91.0 90.0 100.0 93.0 92.0 90.0 92.0 116.0 105.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#8] json-parse-financial Cycles:10 Average:50.90 Median:52.00 stddev:2.13 (4.1%) stddev-sans-first:2.15 19:12:02 INFO - PID 1655 | Values: 49.0 47.0 48.0 52.0 51.0 52.0 52.0 53.0 52.0 53.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#9] json-stringify-tinderbox Cycles:10 Average:66.20 Median:67.50 stddev:8.75 (13.0%) stddev-sans-first:8.77 19:12:02 INFO - PID 1655 | Values: 58.0 63.0 57.0 68.0 67.0 61.0 58.0 69.0 78.0 83.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#10] stanford-crypto-aes Cycles:10 Average:83.70 Median:84.00 stddev:0.95 (1.1%) stddev-sans-first:0.97 19:12:02 INFO - PID 1655 | Values: 83.0 83.0 84.0 85.0 84.0 83.0 84.0 84.0 82.0 85.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#11] stanford-crypto-ccm Cycles:10 Average:157.80 Median:157.00 stddev:19.62 (12.5%) stddev-sans-first:19.60 19:12:02 INFO - PID 1655 | Values: 139.0 149.0 134.0 144.0 154.0 155.0 159.0 162.0 195.0 187.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:192.30 Median:187.00 stddev:14.94 (8.0%) stddev-sans-first:15.72 19:12:02 INFO - PID 1655 | Values: 187.0 188.0 187.0 184.0 209.0 229.0 182.0 186.0 185.0 186.0 19:12:02 INFO - PID 1655 | 19:12:02 INFO - PID 1655 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.80 Median:69.50 stddev:1.32 (1.9%) stddev-sans-first:1.32 19:12:02 INFO - PID 1655 | Values: 70.0 70.0 69.0 70.0 69.0 67.0 67.0 67.0 70.0 69.0 19:12:02 INFO - PID 1655 | -------- Summary: end -------- 19:12:02 INFO - PID 1655 | 19:12:03 INFO - TEST-INFO | 1655: exit 0 19:12:03 INFO - TEST-OK | kraken | took 61563ms 19:12:03 INFO - SUITE-END | took 1176s 19:12:03 INFO - Completed test suite (00:19:36) 19:12:03 INFO - javascript benchmark 19:12:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7841.648339574455, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3519.3665140274748, 3536.628653991331, 3544.4151714967024, 3564.839582218803, 3586.0855481852122, 5966.284045914496, 6122.2041134652545, 6132.4480796728285, 6160.722767475463, 6232.5013998880095, 4898.314236006961, 5051.570616497602, 6039.547033972459, 6056.788012419566, 6130.080487926802, 5154.793808247666, 5268.157094864813, 5384.300040994665, 5405.6214466282245, 5475.4524547545125, 5467.589930755189, 5470.097433923398, 5476.069068258385, 5478.58910581706, 5512.862178445531, 400.52525630498116, 4333.631641310492, 4702.224133018048, 5298.629095963284, 5451.318585176853, 4816.903661926777, 5000.699818047297, 5371.462853714636, 5373.435789242112, 5402.459754024605, 5314.893702125936, 5326.440723723992, 5326.520613144836, 5331.520163185293, 5404.567634589223, 5919.940800592025, 5989.670568118751, 6010.729517171738, 6011.669358185298, 6036.3360030396625, 5724.341700704431, 6461.93538064618, 6517.837054073639, 6524.282328943812, 6525.216973963107, 6601.306862779387, 6602.141721576164, 6613.537052406334, 6618.602883826986, 6627.469802415795, 6116.143739876421, 6257.374262573706, 6275.372462753734, 6281.371862813682, 6299.6850157491945, 6539.47684185264, 6629.900551491732, 6712.597244165366, 6779.694913728894, 6785.117934668461, 6372.872542549216, 6427.196600424947, 6448.0, 6461.0, 6466.515011374166, 6402.263739669997, 6435.646039467781, 6436.195475565554, 6582.308857569982, 6602.207735071822, 6416.13382193398, 6455.644939528277, 6456.451201647913, 6499.642519661464, 6516.348365163446, 6396.136521569621, 6489.059086432441, 6492.707828147651, 6492.772752953719, 6499.642519661369, 5147.897042059138, 5228.869278268074, 5230.895382092413, 5234.345706786652, 5280.392754833243, 4585.9541404585525, 4626.699264547794, 4717.787699553528, 4745.833895813699, 4764.833230836974, 5057.367829021372, 6155.6614386209185, 6276.0, 6339.017452294902, 6343.0, 6585.473162147016, 6621.33786621334, 6656.400923916871, 6670.032845237413, 6672.766453174116, 6122.20411346531, 6154.0, 6165.8458538536, 6251.624902505864, 6336.85325234531, 5799.971000145057, 5803.361630220672, 5807.796727114531, 5851.38560451155, 5858.238429004287, 6738.225104112992, 6776.4240039595215, 6778.8644227116165, 6779.3220677932795, 6838.829029274209, 6386.489080873612, 6466.838329041811, 6558.508111891722, 6592.17597800275, 6601.042848786995, 6709.698063587248, 6772.695228714818, 6806.693698783665, 6817.079694241214, 6847.07564478789, 6517.087607735016, 6525.552917655879, 6540.280569137486, 6547.803565893031, 6568.244651864906, 5422.942526207352, 6369.840753981188, 6551.312112228147, 6595.0, 6628.536002479973, 6303.306636269915, 6305.306416294112, 6322.36776322364, 6362.109304697438, 6370.58591191571, 6302.495800336053, 6403.29563747978, 6418.903716444351, 6443.516736244894, 6450.54846160783, 5330.653507521999, 5435.456454354533, 6144.064619644798, 6170.043643235305, 6255.874882502233, 6362.936370636235, 6430.196225471816, 6449.806505804833, 6461.2892581816905, 6538.509611779231, 5505.366882808368, 6034.155218269358, 6209.658468784261, 6242.906356404748, 6300.117983482224], "value": 5917.254044520982, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [699.50335261964, 789.1437789997852, 835.1147783349647, 1219.7438537907033, 1276.1067252922956, 29260.9758658447, 35510.28979420413, 36514.26971460569, 36529.0, 36650.45024324634, 36838.447423288606, 37174.628253717456, 37248.25503489929, 37340.0, 37442.43836342457, 7895.171007044264, 8095.352371810271, 8319.417640765148, 8548.743537693848, 8559.358048146414, 6869.0, 8502.06477287498, 8614.612342444605, 8620.396572239946, 8630.223279904807, 8583.828323433527, 8620.741377758677, 8640.870386944202, 8644.956775216115, 8647.437916535406, 8572.528510931896, 8619.956900215522, 8622.741317760478, 8642.697505587274, 8662.52356120413, 8425.70510032152, 8435.746927592181, 8484.618192181366, 8510.957445212796, 8540.188682075193, 36460.90617281486, 36614.26771464556, 36622.45066324007, 36667.81666091653, 36715.63284367149, 3582.0, 3610.891673249807, 3616.0, 3616.782993020427, 3618.0, 30029.69970300291, 30102.698973010207, 30119.548206776915, 30179.849100754574, 30202.848985754932, 3122.0945925681754, 3146.3235404387938, 3159.9842000790077, 3173.857176427043, 3180.3162320101287, 178791.0, 246018.0, 265430.67284663644, 274586.0, 274918.0, 45917.0, 46054.53945460502, 46150.5384946153, 46217.76891115523, 46227.0, 47294.52705472901, 47380.763096184295, 47436.5256347439, 47481.76259118682, 47536.5246347539, 14112.2943852809, 14137.78793318101, 14242.928785356007, 14251.216183109822, 14275.357608907623, 11620.825687614693, 11683.00694440982, 11701.23941943788, 11707.180497365103, 11733.88266117345, 12988.350582470839, 13044.41300141496, 13117.212967221998, 13166.275854827885, 13218.537351192663, 5995.550333724988, 6002.54980876436, 6032.788852390146, 6033.78881739137, 6046.458153170914, 1030.2427715629008, 1041.3647674918293, 1042.635077722791, 1067.4662668665667, 1076.9407682577532, 17805.376811811526, 20337.694934575993, 23858.880705596708, 24037.879810600836, 24166.395840103785, 1136.931784092957, 1147.6098126636984, 1173.9178257522062, 1174.4890972426851, 1176.7352345722145, 11900.523979040914, 12298.18478077272, 12345.703671153991, 12367.16810135944, 12372.319522426353, 280653.0, 332773.0, 333340.0, 334226.0, 334884.0, 52025.7398713004, 52063.95872082537, 52181.0, 52210.477895221324, 52355.95288094217, 1513.5005448202287, 1547.2882474062058, 1559.485369827954, 1560.9141497217536, 1569.9764503532456, 20298.69551956692, 23197.304080877602, 23501.647475287544, 23717.93269302851, 23854.88072559626, 34666.306673866886, 35799.82100089483, 36009.639903600626, 36020.63979360173, 36326.818365908, 310798.0, 311090.0, 311894.0, 311945.0, 312202.0, 27757.0, 31638.892638758454, 32274.0, 32312.83843580767, 32680.67319326776, 42227.3665895018, 42961.0, 42998.14003719971, 43065.923351916455, 43353.0, 25697.100601478858, 28044.859775700992, 29296.56055159217, 29921.401571968872, 30189.245268868453, 22788.0, 27808.582871257353, 28292.009779657612, 30360.696393035785, 30448.238794030327, 35249.0, 35758.10604734902, 35840.924772256876, 35857.28285434329, 35925.92222233337, 29792.935201015447, 29860.0, 30233.0, 30469.019513731535, 30601.23496912595, 21851.67222491632, 23154.768452315937, 23794.635510942513, 24123.0, 24167.879160604083], "value": 17180.770226279335, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [433.39757736745906, 441.84977107783345, 443.92009438301113, 446.7676808059811, 455.11480171067274, 19652.90173549132, 21143.365699029015, 21791.782082179172, 21941.122355105777, 22028.0, 21697.0, 22832.0, 23135.07459701614, 23150.88424557875, 23417.0, 6010.437286305549, 6103.786367477139, 6360.55476116672, 6368.108464814906, 6386.10594516768, 6125.938740612603, 6283.214598175228, 6292.276388215368, 6306.905396419034, 6331.651759153245, 5692.089265717486, 5887.88224235515, 5981.252343457068, 6167.444929956303, 6168.876622467548, 6132.846678833021, 6163.414475624831, 6228.968855155696, 6230.314665386803, 6246.156768836195, 5450.727463626842, 5465.152901300305, 5494.148406996922, 5517.972410137963, 5528.032594296023, 26627.60351568368, 26761.46477070467, 26778.661066946574, 26821.32946676329, 27146.86426567874, 2143.3569929021232, 2196.417949243452, 2200.8679479231296, 2203.911843526257, 2205.106931692667, 17793.288268469245, 17812.55468613295, 17828.930264184062, 18092.728609070873, 18333.358332458432, 2147.903344349508, 2187.5406164705432, 2223.5330580578097, 2225.6105181593157, 2234.1063574570303, 147888.0, 149527.0, 150688.0, 150735.24632376878, 152372.2381388086, 11981.46083426248, 12513.3743312834, 12519.624411267676, 12577.371131443391, 12612.684682882818, 15983.200839957955, 16077.678446431006, 16089.115098669452, 16113.516594502184, 16129.596760081093, 8817.911820881838, 9031.683891063782, 9046.095390460901, 9071.27429805614, 9186.908130918739, 8566.443181193203, 8664.393492455594, 8668.60991255395, 8857.734267971971, 8862.42394244372, 7735.6905723770415, 7862.331701805296, 7871.252231038042, 7902.407319451064, 7915.960420197862, 2192.3094225318973, 2214.402111429905, 2221.811146052604, 2225.799678028985, 2233.7207849018873, 939.0703203828298, 970.553545369124, 983.3264214013424, 1004.477671610773, 1029.4183786160795, 18136.818631813778, 19898.104585293695, 20533.897330513253, 21023.579528409347, 21451.78548214529, 1012.9088382045504, 1020.4387586827215, 1053.6838948315474, 1065.984010239847, 1069.6363236499628, 10138.898611013796, 11136.32955056748, 11148.888511114947, 11170.324451332202, 11231.66305010851, 629458.0, 705297.0, 725711.0, 735472.0, 739639.0, 121895.3905230486, 130141.34929325293, 132339.0, 144760.5523944747, 158330.0, 1204.0909113619161, 1205.4695933789105, 1245.103525461666, 1247.4261839553726, 1247.9438425270885, 21760.89119554392, 22301.553968920205, 23037.308880733606, 23064.0, 23070.538589227785, 65338.34661653323, 65389.0, 65751.67124164349, 66161.0, 68869.31130688629, 67752.0, 73536.0, 93363.86269784885, 93574.0, 94225.64435888884, 7253.238409966877, 8663.610137543952, 8833.734987950373, 8903.376763626484, 8913.242374398249, 13994.930025350215, 14070.577882663536, 14099.365528551445, 14337.856621433652, 14395.568132956028, 11877.0, 11904.226225295328, 11971.521139154336, 12157.148999569945, 12240.510379584717, 10074.345167564084, 10794.815529418684, 11070.5571777128, 11217.102631789274, 11508.136889733136, 12192.390380481118, 12311.19977201479, 12320.75358492843, 12331.383430828602, 12335.14972754094, 8887.11128887132, 8936.597853096757, 8951.955240223757, 8967.372283940062, 9199.770005749642, 11445.599404021152, 11466.598669046543, 12052.337121458404, 12287.0, 12318.445669944695], "value": 10715.580486221848, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1340.7385559815834, 1400.6288333591597, 1456.4028748213234, 1469.3314541883444, 1496.3565666763295, 11197.27217730848, 11607.593734219305, 11619.244749091304, 11770.234934729237, 12043.759124817498, 11644.126690498204, 11774.352410617412, 11825.7634847303, 11827.053835693145, 11896.107791915598, 2483.577791775398, 2526.911558095467, 2528.1404322530334, 2528.633348164515, 2536.6575512305785, 2459.348272707734, 2492.750724927511, 2516.421223118684, 2519.735427780084, 2534.1003943600035, 2442.7068751749725, 2502.4744803591266, 2514.798816094717, 2515.8993640254366, 2518.8110891683195, 2133.9999999999923, 2276.6129757941026, 2305.0, 2476.3561474016797, 2490.116008816862, 2421.685180926468, 2474.9010039598393, 2476.9133080342103, 2523.5331463679277, 2527.886245118974, 11790.764184716343, 11862.703432414259, 11905.0, 11911.761764764657, 11919.88080119205, 5913.615614985012, 6599.571027883172, 6824.249332573413, 6847.246802851732, 6847.8972815407315, 4077.4495443115097, 4288.270993931039, 4342.392065110887, 4411.889702757425, 4418.624416924566, 1221.2794451273703, 1243.1360204657767, 1294.5598496511138, 1297.0, 1302.446460254388, 13825.79261311081, 16178.83821161785, 16286.26711797972, 16431.589210269718, 16911.408100716417, 4794.688345257547, 5512.834614961557, 5530.419305972815, 5728.369879313273, 5869.6771677558145, 12142.392880356123, 12171.513139474499, 12215.839495247938, 12219.69450763738, 12238.938805305916, 6605.669716514155, 6656.901611234092, 6659.966700166565, 6662.100616416722, 6674.899876501856, 6124.78563250293, 6173.567850250529, 6256.124142620037, 6312.715927783261, 6320.905186422207, 4314.309710446313, 4417.889552761142, 4444.2000439920475, 4466.0, 4497.2129877271345, 1300.8504022037398, 1315.421214665544, 1328.8538260791306, 1338.872807083345, 1339.6784771654927, 774.3882332957013, 798.9174668324337, 806.8668669669556, 823.7240524424385, 834.6661335465863, 6158.938410615837, 6171.444569988633, 6328.71520781566, 6385.553011289165, 6432.195975503062, 588.52917665868, 589.4812564942908, 589.5136512377306, 596.9015112506474, 611.5077362722961, 4119.546849846514, 4281.914361712748, 4298.290782020931, 4313.856827940598, 4417.558244175557, 12142.0, 15299.770503442458, 15466.690666186612, 15471.22643867802, 15476.148811815234, 12752.936235318764, 13677.863221367857, 15448.304826282843, 15458.84541154574, 15481.993670411388, 9348.158665719982, 10825.621103261348, 11104.833427498594, 11109.666709998713, 11131.332120072822, 6204.658743769136, 6462.903056454251, 6544.214694236722, 6953.304669533209, 7155.499115061895, 13365.53220637273, 13567.1859688419, 13620.659483512594, 13843.169409835442, 13970.231637260049, 11364.318140911571, 12278.693032674255, 13945.233012184428, 14018.018738688193, 14079.366428510946, 7103.680334384861, 7886.819342451554, 7955.920440795518, 8712.303015758851, 9248.907510924804, 9266.0, 10739.516721747696, 10759.977802108788, 10874.238803283693, 10889.401082940198, 9136.49749263797, 9149.31380146505, 9225.86161207569, 9251.028641992494, 9455.527223638654, 9040.41237319572, 9053.68312109073, 9092.727218183465, 9172.724818255463, 9179.26565874715, 8101.959490202511, 8120.187981201832, 8233.34133269349, 8234.094249632415, 8286.627101780314, 5997.610155340063, 6027.909581356371, 6081.209442772588, 6209.130721698875, 6213.9067913982235, 5192.0, 6034.426729460694, 6101.420365065312, 6175.073738939195, 6234.064890266496], "value": 5339.991814853655, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [347.2741969284196, 388.5027165228507, 404.2339766143157, 410.2615292473547, 412.1262922604078, 5971.283445986477, 6149.907751383722, 6173.598716083451, 6197.977333739927, 6287.0, 5824.660328124534, 6067.787627433041, 6111.572189946705, 6112.908306375408, 6143.648397352576, 3704.1665625234377, 3841.8847434577006, 4628.421447319085, 4669.182892993729, 4678.43858736952, 3811.8284677189595, 4083.9183216335655, 4391.429114215148, 4484.686071974963, 4504.639628829702, 3844.4233364995334, 4407.713498622596, 4429.645628349725, 4431.689781715281, 4475.172093162759, 4423.800928958204, 4439.1565602535575, 4456.621187199092, 4482.170798402306, 4490.955090449086, 3748.9625103748886, 3825.483559719458, 3856.749311294785, 3866.168773713665, 3871.728978971473, 5870.941290587082, 5890.381509941437, 5927.585069045169, 5927.911081333784, 5931.881362372772, 4839.758012099416, 5117.795288188468, 5301.814436494704, 5306.3632364116165, 5346.144616861284, 5696.316442026942, 5745.1382292656435, 6337.207849018872, 6433.600988264361, 6504.349565043505, 4197.643200327976, 4361.149575832714, 4370.606645401897, 4381.38660587518, 4408.493023302297, 6720.0, 6884.827879303008, 6907.965460172717, 6942.132233470817, 6949.30506949301, 3864.942025869614, 4831.396075490564, 4857.732824694605, 4857.878553036132, 4884.3650325457465, 4573.199690054227, 4728.858134255978, 4918.409790825052, 4990.226514890198, 4991.276264941564, 4266.658667306608, 5281.023010743025, 5541.972290138524, 5581.748821303051, 5590.496855283044, 6013.436506508339, 6615.536912416084, 6624.867502650017, 6633.170853643294, 6653.534252602271, 1360.7550640884538, 1617.7896873406617, 1618.7005404000297, 1710.9828901711073, 1714.4256674014096, 1049.129222745135, 1162.8895254950767, 1165.6012784658274, 1178.7446369616332, 1197.275648232824, 5365.0, 5422.75597598109, 6150.876982460326, 6164.630122192682, 6181.381861813873, 5312.3890752563175, 5517.2551705520045, 6081.726322315506, 6698.5980841149685, 6730.226024007204, 5850.47345738877, 6139.877202456015, 6184.670295886406, 6203.844903877439, 6249.187605611241, 3128.7015888406513, 3498.650134986481, 3557.879268030562, 3565.5186549815444, 3569.3402567806097, 6073.51411887048, 6079.422454866869, 6442.871142577123, 6835.213950395669, 6990.755323563752, 6097.481714054266, 6706.960421134732, 6975.720971161097, 7029.89455158173, 7042.9435584662715, 6160.876782464416, 6716.29478904708, 7016.684249208798, 7077.469189810682, 7107.040549525748, 5649.858753531194, 6789.626570538668, 6846.281140480178, 6866.34769696878, 6872.381485666314, 6205.782797602063, 6470.611763294218, 6641.970494573349, 6772.255051944184, 6825.726970921306, 6918.377346038881, 6992.895106573507, 7056.6118863463, 7068.505204635626, 7081.760691878798, 4136.152088821724, 4340.457442819647, 4450.955490445054, 4474.597286244254, 4476.4180656514445, 6597.208334999831, 6798.3881450667695, 6812.284710105566, 6821.658917054227, 6862.142232220973, 4898.118338699069, 5248.343957005374, 6037.305709843425, 6568.671566421564, 6584.703688333941, 5148.7425628717665, 6216.316205217513, 6394.392532709571, 6533.901991470227, 6554.180727409074, 5609.775608975759, 6369.172007638977, 6402.487800975818, 6460.967695161494, 6489.935100648933, 5382.623216374816, 5759.078547432391, 6093.786717465045, 6318.462930650946, 6596.241432235354, 6026.885026269979, 6028.849278768066, 6392.9041064385, 6398.5365634232085, 6409.4231519163495], "value": 4774.9581306341715, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [740.0268646729551, 828.8342331533693, 838.3460900497614, 856.131027007587, 882.0914458108149, 16385.262663180154, 16919.49241522753, 16947.57631059224, 18297.63404731908, 19300.806991930076, 17933.10334483274, 18348.082595870186, 18401.631967360645, 18478.07609619517, 18746.25014999402, 979.2998006425404, 989.8119357322122, 992.1566668331897, 993.3592832622954, 996.6960077176494, 1012.6303899076858, 1016.4917541229386, 1018.9949050254738, 1019.6838979916213, 1020.3367810922886, 1067.092970974673, 1071.5124618298694, 1071.73742433104, 1072.9463526823668, 1073.0771536478621, 1052.0584077250817, 1053.1995683280722, 1064.2018486135398, 1065.339489516497, 1065.5471424644495, 755.1165136789914, 767.9923200768033, 770.4029377232633, 771.3135309574483, 771.8379140380526, 16742.66514669712, 17146.39987600428, 17197.054162021082, 17304.826951730574, 17315.913420432942, 16684.33262669492, 16932.984020958782, 17069.829301707072, 17162.31350745981, 17169.0, 13688.0, 13742.450301987908, 13981.09122907018, 14047.719045619031, 14084.366203520865, 3442.2599141184764, 3495.405781017233, 3514.7539672222956, 3515.789052656849, 3516.5604299462566, 108329.45835270852, 118795.0, 119407.0, 119779.40110299393, 120448.0, 13474.0, 13674.726505470035, 13760.862391376158, 13789.862101379058, 13811.930940345233, 24726.381840454134, 25336.74663253344, 25347.239582812545, 25641.102561410262, 25734.74265257361, 9114.498702571289, 9265.583048762823, 9266.583003764712, 9276.257899368034, 9299.953500232456, 4817.181079216542, 4836.395450568679, 4846.200376937766, 4860.781264843091, 4906.092372910952, 4285.485741711014, 4412.779361031936, 4415.51429342772, 4419.1382680377665, 4420.8673739787855, 860.595520105562, 927.8793756811572, 941.152962333905, 975.1662328708993, 989.2036910287144, 648.3646026893671, 672.1765836849918, 677.8440958579554, 681.7784220128478, 683.9829004274833, 11088.168387370979, 11607.825882611767, 11724.706882327839, 11859.288442693465, 12246.877531224574, 597.8206538038571, 636.8758092172075, 671.737134187724, 676.7259259999709, 688.8174633722068, 5136.280920671109, 5428.5928555358505, 5614.214010038598, 5670.234518340054, 5675.687837168995, 86205.0, 86963.0, 87050.0, 92588.0, 93228.06771932195, 54125.72937135368, 68278.0, 75905.62047189729, 83507.49477515684, 87529.0, 57367.71316143476, 77064.0, 77089.2291077082, 77763.0, 80391.99020024546, 12041.759164816829, 12420.565280215511, 12585.119041667362, 12586.370681466073, 12609.054320925783, 39825.0, 40580.79709601433, 41070.79464602658, 41098.76703698894, 41361.96595085147, 50574.494255056976, 50780.746096269286, 50917.0, 51099.74450127874, 51503.0, 15844.762328565312, 15908.443204487787, 16005.519834404986, 16252.837471625606, 16307.592310191865, 17637.29450821953, 17911.283548657906, 18095.276188952295, 18213.54466138357, 18217.99801010957, 15945.68108637844, 16026.0, 16290.755638665667, 16469.011859288483, 16569.66860662804, 13358.198508089547, 16276.267567959234, 16331.0, 16348.918255408647, 16616.75074873902, 15891.920540397225, 15906.443274485337, 15939.36242550285, 15982.680346392774, 16109.838901610834, 11776.352300623548, 11777.882221177679, 12148.817767733315, 12212.145149839425, 12341.197822141532, 12437.440315185657, 12741.299228542519, 12803.551875684307, 12862.356882155667, 12896.290704010995], "value": 8369.979679592247, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1540.3, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 134.0, 169.0, 168.0, 118.0, 115.0, 116.0, 115.0, 116.0, 116.0], "value": 125.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [140.0, 113.0, 135.0, 182.0, 116.0, 112.0, 111.0, 111.0, 133.0, 113.0], "value": 126.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 204.0, 230.0, 226.0, 231.0, 198.0, 196.0, 197.0, 217.0, 278.0], "value": 217.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 59.0, 60.0, 105.0, 79.0, 58.0, 58.0, 57.0, 75.0, 62.0], "value": 69.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 71.0, 84.0, 70.0, 69.0, 75.0, 109.0, 99.0, 102.0, 93.0], "value": 85.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [127.0, 88.0, 103.0, 110.0, 89.0, 88.0, 87.0, 89.0, 125.0, 88.0], "value": 99.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 113.0, 97.0, 98.0, 105.0, 97.0, 98.0, 100.0, 98.0, 106.0], "value": 101.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 91.0, 90.0, 100.0, 93.0, 92.0, 90.0, 92.0, 116.0, 105.0], "value": 96.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [49.0, 47.0, 48.0, 52.0, 51.0, 52.0, 52.0, 53.0, 52.0, 53.0], "value": 50.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 63.0, 57.0, 68.0, 67.0, 61.0, 58.0, 69.0, 78.0, 83.0], "value": 66.2, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 83.0, 84.0, 85.0, 84.0, 83.0, 84.0, 84.0, 82.0, 85.0], "value": 83.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 149.0, 134.0, 144.0, 154.0, 155.0, 159.0, 162.0, 195.0, 187.0], "value": 157.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [187.0, 188.0, 187.0, 184.0, 209.0, 229.0, 182.0, 186.0, 185.0, 186.0], "value": 192.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 70.0, 69.0, 70.0, 69.0, 67.0, 67.0, 67.0, 70.0, 69.0], "value": 68.8, "unit": "score"}]}]} 19:12:03 INFO - Return code: 0 19:12:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:12:03 ERROR - # TBPL SUCCESS # 19:12:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:12:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:12:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:12:03 INFO - 2017-04-13 19:12:03.445 screenresolution[1660:10544] starting screenresolution argv=screenresolution get 19:12:03 INFO - 2017-04-13 19:12:03.448 screenresolution[1660:10544] Display 0: 1600x1200x32@60 19:12:03 INFO - 2017-04-13 19:12:03.454 screenresolution[1661:10547] starting screenresolution argv=screenresolution list 19:12:03 INFO - Available Modes on Display 0 19:12:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:12:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:12:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:12:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:12:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:12:03 INFO - Intel Iris: 19:12:03 INFO - Chipset Model: Intel Iris 19:12:03 INFO - Type: GPU 19:12:03 INFO - Bus: Built-In 19:12:03 INFO - VRAM (Dynamic, Max): 1536 MB 19:12:03 INFO - Vendor: Intel (0x8086) 19:12:03 INFO - Device ID: 0x0a2e 19:12:03 INFO - Revision ID: 0x0009 19:12:03 INFO - Displays: 19:12:03 INFO - Display: 19:12:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:12:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:12:03 INFO - Display Serial Number: 1600x1200 60 19:12:03 INFO - Main Display: Yes 19:12:03 INFO - Mirror: Off 19:12:03 INFO - Online: Yes 19:12:03 INFO - Rotation: Supported 19:12:03 INFO - Return code: 0 19:12:03 INFO - Running post-action listener: _resource_record_post_action 19:12:03 INFO - [mozharness: 2017-04-14 02:12:03.703320Z] Finished run-tests step (success) 19:12:03 INFO - Running post-run listener: _resource_record_post_run 19:12:03 INFO - Total resource usage - Wall time: 1198s; CPU: 55.0%; Read bytes: 37841408; Write bytes: 516864512; Read time: 248; Write time: 1631 19:12:03 INFO - TinderboxPrint: CPU usage
54.6% 19:12:03 INFO - TinderboxPrint: I/O read bytes / time
37,841,408 / 248 19:12:03 INFO - TinderboxPrint: I/O write bytes / time
516,864,512 / 1,631 19:12:03 INFO - TinderboxPrint: CPU idle
2,174.4 (45.4%) 19:12:03 INFO - TinderboxPrint: CPU system
971.6 (20.3%) 19:12:03 INFO - TinderboxPrint: CPU user
1,643.8 (34.3%) 19:12:03 INFO - TinderboxPrint: Swap in / out
246,067,200 / 0 19:12:03 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205545984; Write bytes: 204056576; Read time: 16306; Write time: 433 19:12:03 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35744256; Write bytes: 305738240; Read time: 226; Write time: 1171 19:12:04 INFO - Running post-run listener: _upload_blobber_files 19:12:04 INFO - Blob upload gear active. 19:12:04 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:12:04 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 19:12:04 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', 'Pine', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:12:04 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 Pine -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:12:04 INFO - (blobuploader) - INFO - Open directory for files ... 19:12:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:12:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:12:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:05 INFO - (blobuploader) - INFO - Done attempting. 19:12:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:12:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:12:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:06 INFO - (blobuploader) - INFO - Done attempting. 19:12:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 19:12:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:08 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 19:12:08 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:08 INFO - (blobuploader) - INFO - Done attempting. 19:12:08 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:12:08 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:12:08 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:12:10 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:12:10 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:12:10 INFO - (blobuploader) - INFO - Done attempting. 19:12:10 INFO - (blobuploader) - INFO - Iteration through files over. 19:12:10 INFO - Return code: 0 19:12:10 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:12:10 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:12:10 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/35e6c3b59d885de21287c02e22d8490ada8c87dc5b80a31bf30f9c627bb85f1fff43b5dacda44e7e2d1c55ee621cb868c7b56285bb439a2c7afd1e988f1d46d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28f5c61c8477912bd0429f0f08f4d0550a9328560f332aa4bc0716552d027451ec7b47064f8f5d4128597768ba51a130e26b674af2e61f11a05c0ff7cc5d40a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/321488972cf326fa25121118288540026aba8d9a6a5f2e9c13e44e7c42f15ca78152bac51bb4877b7f83e29b9f538ace0684f9af08eefa24e37cdc7b3b868a30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1a27ee64ccc455e6c5b39c099b7683773b03dabaf2141bc2bddb5f56f05be491136a8b059c83cfe767da4ad578cf17a2f2f368fdeafedac59744fb5c54e49c8a"} 19:12:10 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:12:10 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:12:10 INFO - Contents: 19:12:10 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/35e6c3b59d885de21287c02e22d8490ada8c87dc5b80a31bf30f9c627bb85f1fff43b5dacda44e7e2d1c55ee621cb868c7b56285bb439a2c7afd1e988f1d46d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28f5c61c8477912bd0429f0f08f4d0550a9328560f332aa4bc0716552d027451ec7b47064f8f5d4128597768ba51a130e26b674af2e61f11a05c0ff7cc5d40a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/321488972cf326fa25121118288540026aba8d9a6a5f2e9c13e44e7c42f15ca78152bac51bb4877b7f83e29b9f538ace0684f9af08eefa24e37cdc7b3b868a30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1a27ee64ccc455e6c5b39c099b7683773b03dabaf2141bc2bddb5f56f05be491136a8b059c83cfe767da4ad578cf17a2f2f368fdeafedac59744fb5c54e49c8a"} 19:12:10 INFO - Running post-run listener: copy_logs_to_upload_dir 19:12:10 INFO - Copying logs to upload dir... 19:12:10 INFO - mkdir: /builds/slave/test/build/upload/logs 19:12:10 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.074390 ========= master_lag: -0.18 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 21 secs) (at 2017-04-13 19:12:10.528057) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 19:12:10.531388) ========= 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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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/Pine/sha512/35e6c3b59d885de21287c02e22d8490ada8c87dc5b80a31bf30f9c627bb85f1fff43b5dacda44e7e2d1c55ee621cb868c7b56285bb439a2c7afd1e988f1d46d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28f5c61c8477912bd0429f0f08f4d0550a9328560f332aa4bc0716552d027451ec7b47064f8f5d4128597768ba51a130e26b674af2e61f11a05c0ff7cc5d40a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/321488972cf326fa25121118288540026aba8d9a6a5f2e9c13e44e7c42f15ca78152bac51bb4877b7f83e29b9f538ace0684f9af08eefa24e37cdc7b3b868a30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1a27ee64ccc455e6c5b39c099b7683773b03dabaf2141bc2bddb5f56f05be491136a8b059c83cfe767da4ad578cf17a2f2f368fdeafedac59744fb5c54e49c8a"} build_url:https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011413 build_url: 'https://queue.taskcluster.net/v1/task/LxcCWBvzTZapATu0u4RSYg/artifacts/public/build/firefox-55.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/35e6c3b59d885de21287c02e22d8490ada8c87dc5b80a31bf30f9c627bb85f1fff43b5dacda44e7e2d1c55ee621cb868c7b56285bb439a2c7afd1e988f1d46d1", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/28f5c61c8477912bd0429f0f08f4d0550a9328560f332aa4bc0716552d027451ec7b47064f8f5d4128597768ba51a130e26b674af2e61f11a05c0ff7cc5d40a5", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/321488972cf326fa25121118288540026aba8d9a6a5f2e9c13e44e7c42f15ca78152bac51bb4877b7f83e29b9f538ace0684f9af08eefa24e37cdc7b3b868a30", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Pine/sha512/1a27ee64ccc455e6c5b39c099b7683773b03dabaf2141bc2bddb5f56f05be491136a8b059c83cfe767da4ad578cf17a2f2f368fdeafedac59744fb5c54e49c8a"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-04-13 19:12:10.567085) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 19:12:10.567502) ========= 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.thM4kQJh41/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.iQ2Qn6Qrhq/Listeners TMPDIR=/var/folders/tg/9cs16h3j7pn5rd3v0kkz9lgc00000w/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.005347 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-04-13 19:12:10.615805) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 19:12:10.616131) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-04-13 19:12:10.616476) ========= ========= Total master_lag: 0.20 =========