builder: larch_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0185 starttime: 1473197122.31 results: success (0) buildid: 20160906094754 builduid: 91240e828c4b4db382a5c6bf86e9b40e revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.307787) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.308307) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.308596) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.334052) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.334447) ========= 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.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-09-06 14:25:22-- 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: 12141 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 163M=0s 2016-09-06 14:25:22 (163 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.391484 ========= master_lag: 0.12 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.849455) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.849797) ========= 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.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.030883 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.902884) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:22.903582) ========= bash -c 'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo projects/larch --rev 236f750b6a3002e91e551fd6c2ddec63995a6e0e --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-09-06 14:25:22,971 truncating revision to first 12 chars 2016-09-06 14:25:22,971 Setting DEBUG logging. 2016-09-06 14:25:22,971 attempt 1/10 2016-09-06 14:25:22,971 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/projects/larch/236f750b6a30?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-09-06 14:25:23,656 unpacking tar archive at: larch-236f750b6a30/testing/mozharness/ program finished with exit code 0 elapsedTime=0.942343 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:23.868421) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:23.868747) ========= script_repo_revision: 236f750b6a3002e91e551fd6c2ddec63995a6e0e ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:23.869117) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:23.869380) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-09-06 14:25:23.888092) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-06 14:25:23.888395) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Larch', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Larch'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:25:24 INFO - MultiFileLogger online at 20160906 14:25:24 in /builds/slave/test 14:25:24 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Larch --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Larch 14:25:24 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:25:24 INFO - {'append_to_log': False, 14:25:24 INFO - 'base_work_dir': '/builds/slave/test', 14:25:24 INFO - 'blob_upload_branch': 'Larch', 14:25:24 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:25:24 INFO - 'branch': 'Larch', 14:25:24 INFO - 'buildbot_json_path': 'buildprops.json', 14:25:24 INFO - 'config_files': ('talos/mac_config.py',), 14:25:24 INFO - 'default_actions': ('clobber', 14:25:24 INFO - 'read-buildbot-config', 14:25:24 INFO - 'download-and-extract', 14:25:24 INFO - 'populate-webroot', 14:25:24 INFO - 'create-virtualenv', 14:25:24 INFO - 'install', 14:25:24 INFO - 'run-tests'), 14:25:24 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:25:24 INFO - 'download_minidump_stackwalk': True, 14:25:24 INFO - 'download_symbols': 'ondemand', 14:25:24 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:25:24 INFO - 'tooltool.py': '/tools/tooltool.py', 14:25:24 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:25:24 INFO - '/tools/misc-python/virtualenv.py')}, 14:25:24 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:25:24 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:25:24 INFO - 'installer_path': 'installer.exe', 14:25:24 INFO - 'log_level': 'info', 14:25:24 INFO - 'log_name': 'talos', 14:25:24 INFO - 'log_to_console': True, 14:25:24 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:25:24 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:25:24 INFO - 'opt_config_files': (), 14:25:24 INFO - 'pip_index': False, 14:25:24 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:24 INFO - 'cmd': ('bash', 14:25:24 INFO - '-c', 14:25:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:24 INFO - 'enabled': True, 14:25:24 INFO - 'halt_on_failure': False, 14:25:24 INFO - 'name': 'check_screen_resolution'},), 14:25:24 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:25:24 INFO - 'cmd': ('bash', 14:25:24 INFO - '-c', 14:25:24 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:25:24 INFO - 'enabled': True, 14:25:24 INFO - 'halt_on_failure': False, 14:25:24 INFO - 'name': 'check_screen_resolution'},), 14:25:24 INFO - 'run_cmd_checks_enabled': True, 14:25:24 INFO - 'sps_profile': False, 14:25:24 INFO - 'sps_profile_interval': 0, 14:25:24 INFO - 'suite': 'dromaeojs', 14:25:24 INFO - 'system_bits': '32', 14:25:24 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:25:24 INFO - 'title': 't-yosemite-r7-0185', 14:25:24 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:25:24 INFO - 'use_talos_json': True, 14:25:24 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:25:24 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:25:24 INFO - 'work_dir': 'build'} 14:25:24 INFO - [mozharness: 2016-09-06 21:25:24.074860Z] Running clobber step. 14:25:24 INFO - Running pre-action listener: _resource_record_pre_action 14:25:24 INFO - Running main action method: clobber 14:25:24 INFO - rmtree: /builds/slave/test/build 14:25:24 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:25:25 INFO - Running post-action listener: _resource_record_post_action 14:25:25 INFO - [mozharness: 2016-09-06 21:25:25.920089Z] Finished clobber step (success) 14:25:25 INFO - [mozharness: 2016-09-06 21:25:25.920246Z] Running read-buildbot-config step. 14:25:25 INFO - Running pre-action listener: _resource_record_pre_action 14:25:25 INFO - Running main action method: read_buildbot_config 14:25:25 INFO - Using buildbot properties: 14:25:25 INFO - { 14:25:25 INFO - "project": "", 14:25:25 INFO - "product": "firefox", 14:25:25 INFO - "script_repo_revision": "production", 14:25:25 INFO - "builddir": "larch_yosemite_r7_test-dromaeojs", 14:25:25 INFO - "repository": "", 14:25:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 larch talos dromaeojs", 14:25:25 INFO - "buildid": "20160906094754", 14:25:25 INFO - "pgo_build": "False", 14:25:25 INFO - "basedir": "/builds/slave/test", 14:25:25 INFO - "buildnumber": 0, 14:25:25 INFO - "slavename": "t-yosemite-r7-0185", 14:25:25 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 14:25:25 INFO - "platform": "yosemite_r7", 14:25:25 INFO - "scheduler": "tests-larch-macosx64-talos", 14:25:25 INFO - "branch": "larch", 14:25:25 INFO - "repo_path": "projects/larch", 14:25:25 INFO - "revision": "236f750b6a3002e91e551fd6c2ddec63995a6e0e", 14:25:25 INFO - "stage_platform": "macosx64", 14:25:25 INFO - "builduid": "91240e828c4b4db382a5c6bf86e9b40e", 14:25:25 INFO - "slavebuilddir": "test" 14:25:25 INFO - } 14:25:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 14:25:25 INFO - Running post-action listener: _resource_record_post_action 14:25:25 INFO - [mozharness: 2016-09-06 21:25:25.923278Z] Finished read-buildbot-config step (success) 14:25:25 INFO - [mozharness: 2016-09-06 21:25:25.923403Z] Running download-and-extract step. 14:25:25 INFO - Running pre-action listener: _resource_record_pre_action 14:25:25 INFO - Running main action method: download_and_extract 14:25:25 INFO - mkdir: /builds/slave/test/build/tests 14:25:25 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')]} 14:25:25 INFO - trying https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 14:25:25 INFO - Downloading https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:25:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json'}, attempt #1 14:25:26 INFO - Downloaded 1199 bytes. 14:25:26 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 14:25:26 INFO - Using the following test package requirements: 14:25:26 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:25:26 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 14:25:26 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 14:25:26 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'jsshell-mac.zip'], 14:25:26 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 14:25:26 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 14:25:26 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 14:25:26 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 14:25:26 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 14:25:26 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 14:25:26 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 14:25:26 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:25: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')]} 14:25:26 INFO - trying https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:25:26 INFO - Downloading https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:25:26 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:25:27 INFO - Downloaded 16369898 bytes. 14:25:27 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:25:34 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip', u'firefox-51.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:25:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:25:34 INFO - trying https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:25:34 INFO - Downloading https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip 14:25:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:25:35 INFO - Downloaded 16369898 bytes. 14:25:35 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 14:25: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')]} 14:25:42 INFO - trying https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:25:42 INFO - Downloading https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip 14:25:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip', 'file_name': u'/builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:25:43 INFO - Downloaded 14413998 bytes. 14:25:43 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 14:25:44 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')]} 14:25:44 INFO - trying https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:25:44 INFO - Downloading https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 14:25:44 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg'}, attempt #1 14:25:46 INFO - Downloaded 111604374 bytes. 14:25:46 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:25:46 INFO - mkdir: /builds/slave/test/properties 14:25:46 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:25:46 INFO - Writing to file /builds/slave/test/properties/build_url 14:25:46 INFO - Contents: 14:25:46 INFO - build_url:https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 14:25:47 INFO - Running post-action listener: _resource_record_post_action 14:25:47 INFO - Running post-action listener: set_extra_try_arguments 14:25:47 INFO - [mozharness: 2016-09-06 21:25:47.449890Z] Finished download-and-extract step (success) 14:25:47 INFO - [mozharness: 2016-09-06 21:25:47.450004Z] Running populate-webroot step. 14:25:47 INFO - Running pre-action listener: _resource_record_pre_action 14:25:47 INFO - Running main action method: populate_webroot 14:25:47 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:25:47 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:25:47 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:25:47 INFO - '--tppagecycles', 14:25:47 INFO - '7'], 14:25:47 INFO - 'tests': ['tsvgm']}}, 14:25:47 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:25:47 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:25:47 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:25:47 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:25:47 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:47 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:25:47 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['damp', 'tps']}, 14:25:47 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'tests': ['damp', 'tps']}, 14:25:47 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['dromaeo_dom']}, 14:25:47 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:25:47 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['basic_compositor_video']}, 14:25:47 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:25:47 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'other-e10s': {'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['a11yr', 14:25:47 INFO - 'ts_paint', 14:25:47 INFO - 'tpaint', 14:25:47 INFO - 'sessionrestore', 14:25:47 INFO - 'sessionrestore_no_auto_restore', 14:25:47 INFO - 'tabpaint']}, 14:25:47 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['tsvgx', 14:25:47 INFO - 'tsvgr_opacity', 14:25:47 INFO - 'tart', 14:25:47 INFO - 'tscrollx', 14:25:47 INFO - 'cart']}, 14:25:47 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:25:47 INFO - 'tsvgr_opacity', 14:25:47 INFO - 'tart', 14:25:47 INFO - 'tscrollx', 14:25:47 INFO - 'cart']}, 14:25:47 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'talos_options': ['--disable-e10s'], 14:25:47 INFO - 'tests': ['tp5o']}, 14:25:47 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'tests': ['tp5o']}, 14:25:47 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'talos_options': ['--disable-e10s', 14:25:47 INFO - '--xperf_path', 14:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:47 INFO - 'tests': ['tp5n']}, 14:25:47 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:25:47 INFO - 'talos_options': ['--xperf_path', 14:25:47 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:25:47 INFO - 'tests': ['tp5n']}}, 14:25:47 INFO - 'talos.zip': {'path': '', 14:25:47 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:25:47 INFO - Running post-action listener: _resource_record_post_action 14:25:47 INFO - [mozharness: 2016-09-06 21:25:47.467977Z] Finished populate-webroot step (success) 14:25:47 INFO - [mozharness: 2016-09-06 21:25:47.468096Z] Running create-virtualenv step. 14:25:47 INFO - Running pre-action listener: _resource_record_pre_action 14:25:47 INFO - Running main action method: create_virtualenv 14:25:47 INFO - Creating virtualenv /builds/slave/test/build/venv 14:25:47 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 14:25:47 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:25:47 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:25:47 INFO - Using real prefix '/tools/python27' 14:25:47 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:25:48 INFO - Installing distribute.............................................................................................................................................................................................done. 14:25:51 INFO - Installing pip.................done. 14:25:51 INFO - Return code: 0 14:25:51 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:25: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')]} 14:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25: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')]} 14:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:51 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:25:51 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 14:25:51 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 14:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:51 INFO - 'HOME': '/Users/cltbld', 14:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:51 INFO - 'LOGNAME': 'cltbld', 14:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:51 INFO - 'MOZ_NO_REMOTE': '1', 14:25:51 INFO - 'NO_EM_RESTART': '1', 14:25:51 INFO - 'PAGER': '/bin/cat', 14:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:51 INFO - 'PWD': '/builds/slave/test', 14:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:51 INFO - 'SHELL': '/bin/bash', 14:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:25:51 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:51 INFO - 'USER': 'cltbld', 14:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:51 INFO - 'XPC_FLAGS': '0x0', 14:25:51 INFO - 'XPC_SERVICE_NAME': '0', 14:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:51 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 14:25:51 INFO - Cleaning up... 14:25:51 INFO - Return code: 0 14:25:51 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:25: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')]} 14:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:51 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25: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')]} 14:25:51 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:51 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:51 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:25:51 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 14:25:51 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 14:25:51 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:25:51 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:51 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:51 INFO - 'HOME': '/Users/cltbld', 14:25:51 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:51 INFO - 'LOGNAME': 'cltbld', 14:25:51 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:51 INFO - 'MOZ_NO_REMOTE': '1', 14:25:51 INFO - 'NO_EM_RESTART': '1', 14:25:51 INFO - 'PAGER': '/bin/cat', 14:25:51 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:51 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:51 INFO - 'PWD': '/builds/slave/test', 14:25:51 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:51 INFO - 'SHELL': '/bin/bash', 14:25:51 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:25:51 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:25:51 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:51 INFO - 'USER': 'cltbld', 14:25:51 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:51 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:51 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:51 INFO - 'XPC_FLAGS': '0x0', 14:25:51 INFO - 'XPC_SERVICE_NAME': '0', 14:25:51 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:51 INFO - Downloading/unpacking psutil>=3.1.1 14:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:25:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:25:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:25:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:25:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:54 INFO - Installing collected packages: psutil 14:25:54 INFO - Running setup.py install for psutil 14:25:54 INFO - building 'psutil._psutil_osx' extension 14:25:54 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 14:25:55 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 14:25:55 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 14:25:55 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 14:25:55 INFO - building 'psutil._psutil_posix' extension 14:25:55 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 14:25:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:25:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:25:55 INFO - ^ 14:25:55 INFO - 1 warning generated. 14:25:55 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 14:25:56 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:25:56 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:25:56 INFO - Successfully installed psutil 14:25:56 INFO - Cleaning up... 14:25:56 INFO - Return code: 0 14:25:56 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:25:56 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')]} 14:25:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:56 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')]} 14:25:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:56 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:25:56 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 14:25:56 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 14:25:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:25:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:56 INFO - 'HOME': '/Users/cltbld', 14:25:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:56 INFO - 'LOGNAME': 'cltbld', 14:25:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:56 INFO - 'MOZ_NO_REMOTE': '1', 14:25:56 INFO - 'NO_EM_RESTART': '1', 14:25:56 INFO - 'PAGER': '/bin/cat', 14:25:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:56 INFO - 'PWD': '/builds/slave/test', 14:25:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:56 INFO - 'SHELL': '/bin/bash', 14:25:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:25:56 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:25:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:56 INFO - 'USER': 'cltbld', 14:25:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:56 INFO - 'XPC_FLAGS': '0x0', 14:25:56 INFO - 'XPC_SERVICE_NAME': '0', 14:25:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:56 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:25:56 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 14:25:56 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 14:25:56 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 14:25:56 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 14:25:56 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 14:25:56 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 14:25:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:25:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:25:59 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:25:59 INFO - Installing collected packages: mozsystemmonitor 14:25:59 INFO - Running setup.py install for mozsystemmonitor 14:25:59 INFO - Successfully installed mozsystemmonitor 14:25:59 INFO - Cleaning up... 14:25:59 INFO - Return code: 0 14:25:59 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:25: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')]} 14:25:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:25:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25: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')]} 14:25:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:25:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:25:59 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:25:59 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 14:25:59 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 14:25:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:25:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:25:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:25:59 INFO - 'HOME': '/Users/cltbld', 14:25:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:25:59 INFO - 'LOGNAME': 'cltbld', 14:25:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:25:59 INFO - 'MOZ_NO_REMOTE': '1', 14:25:59 INFO - 'NO_EM_RESTART': '1', 14:25:59 INFO - 'PAGER': '/bin/cat', 14:25:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:25:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:25:59 INFO - 'PWD': '/builds/slave/test', 14:25:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:25:59 INFO - 'SHELL': '/bin/bash', 14:25:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:25:59 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:25:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:25:59 INFO - 'USER': 'cltbld', 14:25:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:25:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:25:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:25:59 INFO - 'XPC_FLAGS': '0x0', 14:25:59 INFO - 'XPC_SERVICE_NAME': '0', 14:25:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:25:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:25:59 INFO - Downloading/unpacking jsonschema==2.5.1 14:25:59 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 14:25:59 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 14:25:59 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 14:25:59 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 14:25:59 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 14:25:59 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 14:26:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:26:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:26: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 14:26: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 14:26: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 14:26: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 14:26: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 14:26: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 14:26:02 INFO - Downloading functools32-3.2.3-2.tar.gz 14:26:02 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:26:02 INFO - warning: no files found matching '*.txt' 14:26:02 INFO - no previously-included directories found matching 'build' 14:26:02 INFO - no previously-included directories found matching 'dist' 14:26:02 INFO - no previously-included directories found matching '.git*' 14:26:02 INFO - Installing collected packages: jsonschema, functools32 14:26:02 INFO - Running setup.py install for functools32 14:26:02 INFO - warning: no files found matching '*.txt' 14:26:02 INFO - no previously-included directories found matching 'build' 14:26:02 INFO - no previously-included directories found matching 'dist' 14:26:02 INFO - no previously-included directories found matching '.git*' 14:26:02 INFO - Successfully installed jsonschema functools32 14:26:02 INFO - Cleaning up... 14:26:02 INFO - Return code: 0 14:26:02 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:02 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:02 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:26:02 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:26:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:02 INFO - 'HOME': '/Users/cltbld', 14:26:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:02 INFO - 'LOGNAME': 'cltbld', 14:26:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:02 INFO - 'MOZ_NO_REMOTE': '1', 14:26:02 INFO - 'NO_EM_RESTART': '1', 14:26:02 INFO - 'PAGER': '/bin/cat', 14:26:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:02 INFO - 'PWD': '/builds/slave/test', 14:26:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:02 INFO - 'SHELL': '/bin/bash', 14:26:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:02 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:02 INFO - 'USER': 'cltbld', 14:26:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:02 INFO - 'XPC_FLAGS': '0x0', 14:26:02 INFO - 'XPC_SERVICE_NAME': '0', 14:26:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:26:02 INFO - Cleaning up... 14:26:03 INFO - Return code: 0 14:26:03 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:03 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:03 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 14:26:03 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 14:26:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:03 INFO - 'HOME': '/Users/cltbld', 14:26:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:03 INFO - 'LOGNAME': 'cltbld', 14:26:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:03 INFO - 'MOZ_NO_REMOTE': '1', 14:26:03 INFO - 'NO_EM_RESTART': '1', 14:26:03 INFO - 'PAGER': '/bin/cat', 14:26:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:03 INFO - 'PWD': '/builds/slave/test', 14:26:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:03 INFO - 'SHELL': '/bin/bash', 14:26:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:03 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:03 INFO - 'USER': 'cltbld', 14:26:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:03 INFO - 'XPC_FLAGS': '0x0', 14:26:03 INFO - 'XPC_SERVICE_NAME': '0', 14:26:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:03 INFO - Downloading/unpacking blobuploader==1.2.4 14:26:03 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 14:26:03 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 14:26:03 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 14:26:03 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 14:26:03 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 14:26:03 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 14:26:05 INFO - Downloading blobuploader-1.2.4.tar.gz 14:26:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:26:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:26:05 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 14:26:05 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 14:26:05 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 14:26:05 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 14:26:05 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 14:26:05 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 14:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:26:06 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:26:06 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 14:26:06 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 14:26:06 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 14:26:06 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 14:26:06 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 14:26:06 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 14:26:06 INFO - Downloading docopt-0.6.1.tar.gz 14:26:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:26:06 INFO - Installing collected packages: blobuploader, requests, docopt 14:26:06 INFO - Running setup.py install for blobuploader 14:26:07 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:26:07 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:26:07 INFO - Running setup.py install for requests 14:26:07 INFO - Running setup.py install for docopt 14:26:07 INFO - Successfully installed blobuploader requests docopt 14:26:07 INFO - Cleaning up... 14:26:07 INFO - Return code: 0 14:26:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:07 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:07 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:07 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 14:26:07 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 14:26:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:07 INFO - 'HOME': '/Users/cltbld', 14:26:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:07 INFO - 'LOGNAME': 'cltbld', 14:26:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:07 INFO - 'MOZ_NO_REMOTE': '1', 14:26:07 INFO - 'NO_EM_RESTART': '1', 14:26:07 INFO - 'PAGER': '/bin/cat', 14:26:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:07 INFO - 'PWD': '/builds/slave/test', 14:26:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:07 INFO - 'SHELL': '/bin/bash', 14:26:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:07 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:07 INFO - 'USER': 'cltbld', 14:26:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:07 INFO - 'XPC_FLAGS': '0x0', 14:26:07 INFO - 'XPC_SERVICE_NAME': '0', 14:26:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-IRY_rG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-XmEEwR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-dhcnyU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-s9_ZVP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ZZEoMC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-wAMxeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-bZ9lCv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-k6pJD_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:08 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-xvPUJk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-Ke6qen-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-p7Ak5p-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-KiUA9s-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-wLH6XA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-eYx4Z4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ePjxWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-p5F19a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:09 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-cAa33n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:26:09 INFO - Running setup.py install for manifestparser 14:26:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:26:10 INFO - Running setup.py install for mozcrash 14:26:10 INFO - Running setup.py install for mozdebug 14:26:10 INFO - Running setup.py install for mozdevice 14:26:10 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:26:10 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:26:10 INFO - Running setup.py install for mozfile 14:26:10 INFO - Running setup.py install for mozhttpd 14:26:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:26:10 INFO - Running setup.py install for mozinfo 14:26:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:26:10 INFO - Running setup.py install for mozInstall 14:26:11 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Running setup.py install for mozleak 14:26:11 INFO - Running setup.py install for mozlog 14:26:11 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Running setup.py install for moznetwork 14:26:11 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Running setup.py install for mozprocess 14:26:11 INFO - Running setup.py install for mozprofile 14:26:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:26:11 INFO - Running setup.py install for mozrunner 14:26:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:26:12 INFO - Running setup.py install for mozscreenshot 14:26:12 INFO - Running setup.py install for moztest 14:26:12 INFO - Running setup.py install for mozversion 14:26:12 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:26:12 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:26:12 INFO - Cleaning up... 14:26:12 INFO - Return code: 0 14:26:12 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:12 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')]} 14:26:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:12 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')]} 14:26:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:12 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:12 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 14:26:12 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 14:26:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:12 INFO - 'HOME': '/Users/cltbld', 14:26:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:12 INFO - 'LOGNAME': 'cltbld', 14:26:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:12 INFO - 'MOZ_NO_REMOTE': '1', 14:26:12 INFO - 'NO_EM_RESTART': '1', 14:26:12 INFO - 'PAGER': '/bin/cat', 14:26:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:12 INFO - 'PWD': '/builds/slave/test', 14:26:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:12 INFO - 'SHELL': '/bin/bash', 14:26:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:12 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:12 INFO - 'USER': 'cltbld', 14:26:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:12 INFO - 'XPC_FLAGS': '0x0', 14:26:12 INFO - 'XPC_SERVICE_NAME': '0', 14:26:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:26:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-9wVN2f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:26:12 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)) 14:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:26:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-_Q6vA6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:26:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:26:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:26:12 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-UzM_cJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-T34KAf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-imB0mN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-XUzOJg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-OhClIW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-nJ0iwB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-xjikBV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-JQrM81-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.3 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-QUiOep-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:26:13 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)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:26:13 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-P8zbHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:26:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:26:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:26:14 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-xc6ojL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:26:14 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)) 14:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:26:14 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-lIYAXQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:14 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-mIjokg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:26:14 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)) 14:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:26:14 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-ilH8UY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:26:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:26:14 INFO - Running setup.py (path:/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/pip-CLuYyi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:26:14 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)) 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:26:14 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)) 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:26:14 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:26:14 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:26:14 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 14:26:14 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 14:26:14 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 14:26:14 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 14:26:14 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 14:26:14 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 14:26:17 INFO - Downloading blessings-1.6.tar.gz 14:26:17 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:26:17 INFO - Installing collected packages: blessings 14:26:17 INFO - Running setup.py install for blessings 14:26:17 INFO - Successfully installed blessings 14:26:17 INFO - Cleaning up... 14:26:17 INFO - Return code: 0 14:26:17 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:26:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:17 INFO - Reading from file tmpfile_stdout 14:26:17 INFO - Current package versions: 14:26:17 INFO - blessings == 1.6 14:26:17 INFO - blobuploader == 1.2.4 14:26:17 INFO - docopt == 0.6.1 14:26:17 INFO - functools32 == 3.2.3-2 14:26:17 INFO - jsonschema == 2.5.1 14:26:17 INFO - manifestparser == 1.1 14:26:17 INFO - mozInstall == 1.12 14:26:17 INFO - mozcrash == 0.17 14:26:17 INFO - mozdebug == 0.1 14:26:17 INFO - mozdevice == 0.48 14:26:17 INFO - mozfile == 1.2 14:26:17 INFO - mozhttpd == 0.7 14:26:17 INFO - mozinfo == 0.9 14:26:17 INFO - mozleak == 0.1 14:26:17 INFO - mozlog == 3.3 14:26:17 INFO - moznetwork == 0.27 14:26:17 INFO - mozprocess == 0.23 14:26:17 INFO - mozprofile == 0.28 14:26:17 INFO - mozrunner == 6.12 14:26:17 INFO - mozscreenshot == 0.1 14:26:17 INFO - mozsystemmonitor == 0.3 14:26:17 INFO - moztest == 0.7 14:26:17 INFO - mozversion == 1.4 14:26:17 INFO - psutil == 3.1.1 14:26:17 INFO - requests == 1.2.3 14:26:17 INFO - wsgiref == 0.1.2 14:26:17 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:26:17 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:18 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 14:26:18 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 14:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:18 INFO - 'HOME': '/Users/cltbld', 14:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:18 INFO - 'LOGNAME': 'cltbld', 14:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:18 INFO - 'MOZ_NO_REMOTE': '1', 14:26:18 INFO - 'NO_EM_RESTART': '1', 14:26:18 INFO - 'PAGER': '/bin/cat', 14:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:18 INFO - 'PWD': '/builds/slave/test', 14:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:18 INFO - 'SHELL': '/bin/bash', 14:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:18 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:18 INFO - 'USER': 'cltbld', 14:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:18 INFO - 'XPC_FLAGS': '0x0', 14:26:18 INFO - 'XPC_SERVICE_NAME': '0', 14:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 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)) 14:26:18 INFO - Cleaning up... 14:26:18 INFO - Return code: 0 14:26:18 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:26:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:26:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:26:18 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 0x103f7ea30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x104013618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f80c34abb80>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, '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.KhKKum0j2v/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.Y22sob9nET/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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 14:26:18 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 14:26:18 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 14:26:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:18 INFO - 'HOME': '/Users/cltbld', 14:26:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:18 INFO - 'LOGNAME': 'cltbld', 14:26:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:18 INFO - 'MOZ_NO_REMOTE': '1', 14:26:18 INFO - 'NO_EM_RESTART': '1', 14:26:18 INFO - 'PAGER': '/bin/cat', 14:26:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:18 INFO - 'PWD': '/builds/slave/test', 14:26:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:18 INFO - 'SHELL': '/bin/bash', 14:26:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:18 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:18 INFO - 'USER': 'cltbld', 14:26:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:18 INFO - 'XPC_FLAGS': '0x0', 14:26:18 INFO - 'XPC_SERVICE_NAME': '0', 14:26:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:26:18 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:26:18 INFO - Cleaning up... 14:26:18 INFO - Return code: 0 14:26:18 INFO - Running post-action listener: _resource_record_post_action 14:26:18 INFO - Running post-action listener: _start_resource_monitoring 14:26:18 INFO - Starting resource monitoring. 14:26:18 INFO - [mozharness: 2016-09-06 21:26:18.643221Z] Finished create-virtualenv step (success) 14:26:18 INFO - [mozharness: 2016-09-06 21:26:18.644236Z] Running install step. 14:26:18 INFO - Running pre-action listener: _resource_record_pre_action 14:26:18 INFO - Running main action method: install 14:26:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:26:18 INFO - Reading from file tmpfile_stdout 14:26:18 INFO - Detecting whether we're running mozinstall >=1.0... 14:26:18 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:26:18 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:26:19 INFO - Reading from file tmpfile_stdout 14:26:19 INFO - Output received: 14:26:19 INFO - Usage: mozinstall [options] installer 14:26:19 INFO - Options: 14:26:19 INFO - -h, --help show this help message and exit 14:26:19 INFO - -d DEST, --destination=DEST 14:26:19 INFO - Directory to install application into. [default: 14:26:19 INFO - "/builds/slave/test"] 14:26:19 INFO - --app=APP Application being installed. [default: firefox] 14:26:19 INFO - mkdir: /builds/slave/test/build/application 14:26:19 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:26:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:26:53 INFO - Reading from file tmpfile_stdout 14:26:53 INFO - Output received: 14:26:53 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:26:53 INFO - Running post-action listener: _resource_record_post_action 14:26:53 INFO - [mozharness: 2016-09-06 21:26:53.362942Z] Finished install step (success) 14:26:53 INFO - [mozharness: 2016-09-06 21:26:53.363085Z] Running run-tests step. 14:26:53 INFO - Running pre-action listener: _resource_record_pre_action 14:26:53 INFO - Running main action method: run_tests 14:26:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:26:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:26:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:26:53 INFO - 2016-09-06 14:26:53.377 screenresolution[1640:7367] starting screenresolution argv=screenresolution get 14:26:53 INFO - 2016-09-06 14:26:53.380 screenresolution[1640:7367] Display 0: 1600x1200x32@60 14:26:53 INFO - 2016-09-06 14:26:53.385 screenresolution[1641:7369] starting screenresolution argv=screenresolution list 14:26:53 INFO - Available Modes on Display 0 14:26:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:26:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:26:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:26:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:26:53 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:26:53 INFO - Intel Iris: 14:26:53 INFO - Chipset Model: Intel Iris 14:26:53 INFO - Type: GPU 14:26:53 INFO - Bus: Built-In 14:26:53 INFO - VRAM (Dynamic, Max): 1536 MB 14:26:53 INFO - Vendor: Intel (0x8086) 14:26:53 INFO - Device ID: 0x0a2e 14:26:53 INFO - Revision ID: 0x0009 14:26:53 INFO - Displays: 14:26:53 INFO - Display: 14:26:53 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:26:53 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:26:53 INFO - Display Serial Number: 1600x1200 60 14:26:53 INFO - Main Display: Yes 14:26:53 INFO - Mirror: Off 14:26:53 INFO - Online: Yes 14:26:53 INFO - Rotation: Supported 14:26:53 INFO - Return code: 0 14:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:26:53 INFO - Python 2.7.3 14:26:53 INFO - Return code: 0 14:26:53 INFO - grabbing minidump binary from tooltool 14:26:53 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')]} 14:26:53 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 0x103ee2d30>, '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 0x103c47db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x103edab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:26:53 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 14:26:53 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 14:26:53 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 14:26:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:26:53 INFO - Return code: 0 14:26:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:26:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:26:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:26:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:26:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:26:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 14:26:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Larch --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0185 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 14:26:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.KhKKum0j2v/Render', 14:26:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:26:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:26:53 INFO - 'HOME': '/Users/cltbld', 14:26:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:26:53 INFO - 'LOGNAME': 'cltbld', 14:26:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:26:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:26:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:26:53 INFO - 'MOZ_NO_REMOTE': '1', 14:26:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:26:53 INFO - 'NO_EM_RESTART': '1', 14:26:53 INFO - 'PAGER': '/bin/cat', 14:26:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:26:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:26:53 INFO - 'PWD': '/builds/slave/test', 14:26:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:26:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:26:53 INFO - 'SHELL': '/bin/bash', 14:26:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.Y22sob9nET/Listeners', 14:26:53 INFO - 'TMPDIR': '/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/', 14:26:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:26:53 INFO - 'USER': 'cltbld', 14:26:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:26:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:26:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:26:53 INFO - 'XPC_FLAGS': '0x0', 14:26:53 INFO - 'XPC_SERVICE_NAME': '0', 14:26:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:26:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Larch', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0185', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 14:26:53 INFO - mozversion application_buildid: 20160906094754 14:26:53 INFO - mozversion application_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 14:26:53 INFO - mozversion application_display_name: Nightly 14:26:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:26:53 INFO - mozversion application_name: Firefox 14:26:53 INFO - mozversion application_remotingname: firefox 14:26:53 INFO - mozversion application_repository: https://hg.mozilla.org/projects/larch 14:26:53 INFO - mozversion application_vendor: Mozilla 14:26:53 INFO - mozversion application_version: 51.0a1 14:26:53 INFO - mozversion platform_buildid: 20160906094754 14:26:53 INFO - mozversion platform_changeset: 236f750b6a3002e91e551fd6c2ddec63995a6e0e 14:26:53 INFO - mozversion platform_repository: https://hg.mozilla.org/projects/larch 14:26:53 INFO - mozversion platform_version: 51.0a1 14:26:53 INFO - using testdate: 1473197213 14:26:53 INFO - actual date: 1473197213 14:26:53 INFO - starting webserver on 'localhost:49267' 14:26:53 INFO - SUITE-START | Running 2 tests 14:26:53 INFO - TEST-START | dromaeo_css 14:26:53 INFO - operating with platform_type : mac_ 14:26:53 INFO - Initialising browser for dromaeo_css test... 14:26:53 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpoqqIfu/profile http://localhost:49267/getInfo.html) 14:26:56 INFO - PROCESS | 1651 | __metrics Screen width/height:1600/1200 14:26:56 INFO - PROCESS | 1651 | colorDepth:24 14:26:56 INFO - PROCESS | 1651 | Browser inner width/height: 1024/689 14:26:56 INFO - PROCESS | 1651 | __metrics 14:26:56 INFO - PROCESS | 1651 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:26:56 INFO - PROCESS | 1651 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:26:57 INFO - TEST-INFO | 1651: exit 0 14:26:57 INFO - Browser initialized. 14:26:57 INFO - Running cycle 1/1 for dromaeo_css test... 14:26:57 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpoqqIfu/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:27:09 INFO - PROCESS | 1653 | RSS: Main: 246374400 14:27:09 INFO - PROCESS | 1653 | 14:28:00 INFO - PROCESS | 1653 | 1473197280789 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:30:12 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 14:30:13 INFO - PROCESS | 1653 | RSS: Main: 370712576 14:30:13 INFO - PROCESS | 1653 | 14:33:16 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 14:33:17 INFO - PROCESS | 1653 | RSS: Main: 374956032 14:33:17 INFO - PROCESS | 1653 | 14:36:19 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 14:36:20 INFO - PROCESS | 1653 | RSS: Main: 362512384 14:36:20 INFO - PROCESS | 1653 | 14:39:22 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 14:39:23 INFO - PROCESS | 1653 | RSS: Main: 349323264 14:39:23 INFO - PROCESS | 1653 | 14:42:26 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 14:42:27 INFO - PROCESS | 1653 | RSS: Main: 327741440 14:42:27 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 14:45:30 INFO - PROCESS | 1653 | RSS: Main: 522571776 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | __start_tp_report 14:45:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 14:45:30 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 14:45:30 INFO - PROCESS | 1653 | |i|pagename|runs| 14:45:30 INFO - PROCESS | 1653 | |0;dojo.html;3257.7230935370494;3307.7849939753914;3363.915902102447;3375.7974521528727;3419.162305235216;5517.972410137953;5601.663900165982;5645.2661154049965;5709.657420554764;5715.599908006441;5673.574481913863;5703.458171473703;5706.115552089423;5747.453991870776;5792.565557583177;5088.771005304751;5142.588592912577;5172.767225474862;5186.377634683843;5269.973650131763;5276.1030624793875;5276.445973172819;5291.444398338177;5301.787928482856;5336.332958380202;4658.510856360067;4710.034442939189;5105.770240339194;5194.818181363672;5223.112070947948;5182.378114626231;5190.273361729361;5192.948070519285;5208.817691380784;5216.008958297949;5108.872278193075;5148.407933087706;5155.71643559604;5156.664816786897;5162.92255616166;5561.416051314634;5596.916046259309;5628.549716022709;5629.60592758507;5752.309722833287;6208.906866397007;6225.7509699611965;6227.501799856001;6228.22147231596;6254.186955695776;6101.542384321194;6160.6919654017565;6249.937500624981;6289;6298.87402251957;5749.712514374306;5755;5798.478136967693;5801.332846722681;5851.531877449836;6259.155014073133;6277.780277690259;6288.685565721696;6293.527985401021;6295.150154729145;5991.161237426763;6045.607387642967;6068.726907289182;6126.509879209652;6142.170806941095;6107.694075676726;6123.191113103742;6125.296497837281;6220.222472190976;6235.501159907197;6153.784617538454;6178.41305076017;6181.938180618226;6187.690615469209;6200.968995154995;5183.585313174937;5239.1355426354985;6104.694765261719;6198.008318668992;6220.844478887973;4821.228603423436;4914.950850491521;4924.064427758714;4937.456879743226;4984.7258400788305;3466.3933811582874;4405.185040767468;4431.8448854289945;4436.090601426764;4577.1074640444795;5674.943250567441;5683.8010669626365;5687.630304030225;5713.885722285531;5762.683052432157;5189.714565698923;6108.35862234459;6112.358202388774;6121.234845644294;6140.846478837975;5651;5666.34836993751;5677.403872593318;5699.974004679115;5719.428057194247;5419.078756611386;5437.401885792559;5445.945540544623;5446.618736688394;5447.891042179134;6165.229346331708;6170.876582468415;6224.128621992834;6242.126102345766;6253.187085678924;6213.96893015532;6218.968905155445;6236.064590311308;6243.375662433902;6254.593451425642;6341.302456729663;6348.111264422892;6360.745570177141;6362.968185159045;6374.840628984312;6007.729652165575;6034.818955431344;6057.42454466825;6086.239220097488;6180.443760061616;6168.753249870135;6186.81439556796;6194.132821404917;6210.906836397548;6213.782517611862;6050.727717252822;6066.969665151646;6105.328413874559;6108.816735497942;6117.1130186123655;5896.262967129109;5997.430244126801;6010.068439391901;6053.182820319201;6095.908561371494;5954.404559544011;5957.821265362046;5962.731677074629;5972.313183983897;6013.5189184865985;6111.236095488064;6120.265568131853;6127.417895299939;6132.478739307209;6132.724027418866;5712.80005199816;5739.8565035874435;5840.036393995113;5884.823455296348;5956.106584012433 14:45:30 INFO - PROCESS | 1653 | |1;ext.html;466.39368820533343;491.7197197597366;525.1702310349646;527.6596595196098;555.572209398763;30409.695903040963;32686.67313326866;40754.38868416976;41239.58760412391;41882.79058604703;40829.795851020775;41321.58678413215;41359.58640413595;43337.56662433375;43343.91640208996;9914.157296629777;10129.898701013006;10132.54403551842;10146.847797283046;10159.441230732307;8939.55302234889;12195.08536859739;12410;12443.875561244362;12643.902243950199;12347.320897350643;12391.752164956695;12423.503059877594;12434;12445.502179912837;12443.751124977494;12507.06197035226;12521.373931303398;12525.997920166365;12536.185147965352;12196.634100976986;12248.44881980313;12255.896969272808;12270.693232669166;12289.56986505468;40850.79574602138;41083.38374924379;41137.588624113676;41188.176236475105;41332;3459.8270086495572;3795.449659799314;4029.454080409221;4064.43097966282;4067.410225517313;30893.536596951064;30983.690163098305;31241.687583124105;31607.84196079005;31855.84072079625;3205.182678417012;3262.722668573174;3265.0857759827286;3352.949705754416;3371.7471189660873;494429;497242;497814;498614;499356;57339.71330143406;57627.55931101756;57641.42358576361;57666.42333576611;57747.71126144427;60497;60503;60505.789884202066;60656.090158647654;60667;15992.520224393287;16200.75698864518;16220.432284869972;16230.188490575423;16252.75620865688;11406.600768973047;11600.88399116015;12634.557790477473;12699.492020319269;12735.936320318338;14922.925385373004;15298.694026119638;15320.463783767515;15661;15690.921545392428;6089.786857459968;6434.227892652912;6945.687444064928;7255.52839065459;7285.70857165718;876.4565969099199;880.3485420788699;958.1951161024773;991.6281394477071;1069.850220969065;29253.853730731636;29703.851480742458;29723.108306750833;30436.239094022825;30622;1017.9694609161737;1043.8642976413018;1061.1404762142688;1062.4262898034974;1064.095518809006;14581.052231604912;15391.61520961985;15420.92289538545;15600.765988510182;15787.36850525989;662069;663062.6846865831;666189;666825;667475;50170.749146254035;57075.14387284194;57828;57941.710291448275;60492.69753651292;1377.9655508612163;1403.8245219347582;1420.6306360346384;1426.0302993964203;1428.6214153249398;29980.209273226825;30517;30602;31106.37787244197;31494;49644.00711985812;50157.49842501674;50167.99664006772;50189.49810501848;50227.49772502228;588271;590111.0494447501;592666;593205;594374;25300.74699252984;25465.363365916;25810.483790324462;25886.61170082488;25902.61146082848;46895.08993291264;48026.759866200446;48054.519454805006;48126;48539;38525.42211866768;39182.80408597939;39269.6073039266;40044.99887502836;40534.879652078045;38461.46155153574;39056.41415378828;39092.60907391003;40009.39985900272;40561.59438405578;38629.22741545209;38828.61171388363;38913.027174320865;39008.21983560256;39039.80480097581;27405.177844664693;32442.188945276557;34438.82780586081;34606.13484662904;34668.47997279993;24871.87564062168;25014.124505642216;25293.86177622048;25460.618090729025;25532.10637627674 14:45:30 INFO - PROCESS | 1653 | |2;jquery.html;356.80019189254006;374.4476896577548;379.6981399787167;387.17145309038654;409.52086059310625;15877.523674289776;15887.602809929758;21305.680414793773;21330.89334553325;21847.563048739015;21069.367918962456;21434.82108484033;21443.892780536076;21773.891130544325;21984;5742.196092547047;5797.565182611311;5957.106434034908;6028.005379112441;6079;4186.623203911648;5898.911516327258;5986.132010858423;6016.187814645033;6358.586691865014;6621;6638.634875081869;6649.700763465631;6672.566283191577;6690.297518760533;5781.682007489587;6101.0848372744;6159.045347971071;6159.692015399212;6649.468042556608;5167.379914410257;5733.598648094635;5925.644461332334;5942.465178133989;5978.312494063195;13389.397477113553;13818.170909745348;13820.93089534556;13852.030357874954;15210.771838422432;2165.545235500531;2248.482848944752;2254.9514475768847;2370.9051637934463;2406.362313986795;9472.57373418191;9963.551640176209;10013.148882345009;10334.483275836179;10505.317154385017;1774.9911250443795;1859.8233167849032;1885.575745457275;1893.6875415556417;1954.5309125809845;96889;129449.35275323657;130072.99999999905;130209.34895325557;130439;11109.83335249972;11404.429778511041;11470.254433461798;11505.654830355103;11703.552649992947;14475.92762036183;14490.275486225646;14504.202268875313;14509.274536273144;14567.92716036413;6867.862642747117;8184.83630327402;8459.873101903477;8595.398322117391;8613.913860861436;8396.328293736486;8396.538190399464;8432.78918027042;8586.527740974307;8621.396502244783;4399.62603178727;4491.927872190064;4497.033137875373;4544.454665440168;4580.633549316047;1469.6105532034019;1629.0144462600192;1713.100622173344;1798.6312805874763;1828.8994105324068;899.3794281945568;958.91849192818;970.8689326940772;989.5151375825938;1026.8665073540392;20466;21271.149154033665;21278.680819787714;21407.46481337948;21540.13839446436;892.5804871710286;934.0519372836576;985.5121714751244;996.5117092624706;1004.7437903334603;9875.654352097643;10619.787604248026;11083.612073577548;11085.448052173884;11227.326360418401;554897;567708;568594;577766;582155;121363;124039.37980310041;125824;126034;126952;945.8392073347547;964.840801267797;969.699393188114;1025.103034844526;1026.573971801694;19258.844469331885;19303.710444343065;19600.765873631262;19760;19852.801471985673;40584.59415405926;43384;51656.192033278654;56554;58607;82673.17326826655;82693.58653206695;82830;84137;84485;7387.852242955219;7493.175750667313;7627.656755445907;7688.500247483896;7710.7686769397005;8551.871721924052;11561.653150405502;13009.349532523147;13412.530561430303;13582.86417135816;9836.311458197857;11405.771884562428;11438.199326047097;11768.529258829893;11958.760824783629;11411.315321080761;11423.828642570534;11552.075833933097;11742.58900938463;11891.583794567148;11522.654320370402;11725.941370293094;11910.940445297718;11969.64091077269;12019.399030048287;8059.556724379981;8095;8171.264586187272;8376.623051962799;8638;8369.707060252862;10895.509702063267;11348.602798901999;11562;11682.065434765369 14:45:30 INFO - PROCESS | 1653 | |3;mootools.html;1366.460248201961;1524.7103050420424;1550.6356006338508;1741.4601473543203;1903.3243198664481;7644.120926093501;8092.231238032392;8100.513969161847;8104.270615644606;8108.91891081089;8389.91610083899;8577.742667719962;8718.73843784686;8720.302375809935;8758.649654013832;2354.929352119435;2375.489269806991;2381.7499162587937;2386.3556839653284;2387.4747555537842;2332.4402143485613;2365.3022358404314;2375.1212051540956;2385.510970251103;2389.772971567707;2344.23812261015;2364.207990323244;2373.976260237401;2377.988110059447;2379.7858192762646;2313.7917587417046;2340.0990618611786;2344.34358379654;2345.3433038749176;2366.786989170983;2318.2349824557855;2337.6026075567192;2345.3550273674705;2345.988270058656;2350.435895385095;8682.78293042679;8684;8765;8801.251893589053;8819.073997230262;5161.329027226474;5168.793248270064;5340.25236466895;5346.03771321162;5356.571474282087;3572.3212589607892;3674.6876515496215;3694.1688120173017;3703.203811180609;3713.424419215026;1089.9073578745738;1107.2083460325825;1125.2517076144354;1125.6172901213545;1127.363039882472;11281.37952412617;11444.828327575093;11449.198556101077;11461.770764584746;11467.36929468879;4542.091581683677;4714.175019371596;4721.15019296523;4769.880752981203;4773.498782627844;9809.803803923882;9814.950925245328;9817.214622830157;9819.116279534876;9841.803163936682;4514.255147900625;5307.2569840222395;5309.203619457112;5311.229871668586;5320.334958130234;4091.7954102294766;4359.694821362475;4789.856304310876;4837.153498137882;4862.586680132228;3541.7249790075552;3828.9234215315537;3859.749116307431;3879.553851307079;4010.2981978153707;1257.8239046533492;1273.3887733887786;1281.833361662978;1293.8706129386985;1293.9611811645666;745.3999530377989;796.916193976192;899.40189773801;904.6788390121544;905.6422713028317;5469.753861076261;5684.147377893267;5901.468867801918;5999.130126131687;6025.517958563304;732.6922692469069;880.1990188928045;898.9370744047985;962.9133377996014;992.8858181309098;3561.5013898054294;3832.1952389997855;3942.586028467027;3960.6237407446247;4038.697097717683;10152.086312231935;10561.102306303897;10584.259101862795;10698.358098513958;10708.411037393018;9106.635734570675;10453.268271221094;10664.946675266729;10666.306690065121;10718.785624287471;7899.170587088388;8120.675172993132;8140.3487720982175;8151.347892168612;8153.7553873383895;5623;5825.242718446575;5985.970070149796;6060.212172417558;6079;9845.409275443497;9847.901520984698;9878.357906736039;9879.506024698592;9913.405195688281;7511.699532018658;9445.622175113192;9460.195883349992;9488.573014214238;9956.352837065857;6915.412189963708;7071.752488663078;7080.114985626797;7205.639718013974;7475.289847464483;7973.3621310293875;8107.47301425429;8194.098649148707;8195.385346098947;8263.049749278669;7669.07971043456;7688.269614386624;7744.483043010931;7761.844763104594;7790.454668173174;7518.097828260644;7529.661165247468;7545.396368290414;7569.962150189213;7581.50720203185;6533.248676402274;6765.763198288037;6848.280930502425;6877.58734475913;6905.102336696197;4973.428055773632;5018.397792264952;5043.460885203346;5046.9747651261505;5240.161574148118;4997.975010125072;5101.642884998015;5106.719130447861;5142.665726727751;5272.9736351318 14:45:30 INFO - PROCESS | 1653 | |4;prototype.html;287.80429308070507;299.3085971406052;307.67232896964725;310.58691939720177;311.2312587907868;3754.493143425637;4728.148933192024;5014.674046186995;5250.868728281795;5269.76286067127;4345.804438800254;4685.688007357937;4822.638302127346;5218.373795144578;5288;3344.4147274227034;3374.8312584370806;3655.9268814623692;3746.587875333711;4012.3179059559943;3731.2910546995986;4038.899027524321;4049.959500404988;4075.8369665213354;4086.652634526069;3110.2690867646083;3734.906627334311;3897.4543563901075;3951.2097580483874;3981.6263389130704;3266.6733326667263;3953.8220780064967;3971.517801374141;3986.501687289074;4004.759714417115;2910.534314509668;2977.8957736479338;3444.2594842108815;3452.085197422685;3458.204612939013;4717.905641887143;4723.7638118093955;4839.854804355875;5057.772400241998;5107.3360463139925;3807.9056518914485;4494.37078808967;4513.668467801974;4528.592426681615;4537.254960748733;4795;4885.682430641997;5540.889182216333;5567.164925261202;5587.217789509472;3518.9091381671506;3706.8887933362043;3707.5694545018714;3783.810809459516;3819.0261483321574;5750.45370689788;5763.331701321858;5764.74058667361;5773.2494775679315;5864.179014937912;3222.452183128874;3417.2994536120036;3787.715921305913;3799.715021373408;3819.644026370654;3464.3071385722956;3822.7324087313623;3867.8646247381207;3883.76697398157;3897.337452633059;3916.5300163979937;3935.940960885589;4136.627703506699;4509.774511274423;4511.842085526991;4834.589059929875;4886.926696099561;5569.721513924287;5580.748866300945;5586.55307575393;1127.4588197665166;1258.1884684378708;1282.9037822163375;1315.493534989036;1334.379513526215;862.3790870573177;881.823635272948;888.4136469929815;890.8975467821153;904.8190361927641;5136.8202112925865;5168.94036722471;5170.095233334151;5178.870528236825;5194.558462530652;5132.717700526507;5202.687838729613;5671.262735844314;5707.400722924116;5751.964646363611;4838.7338696372035;4863.878403039952;5107.1103691634335;5114.923276150861;5125.282460455539;2250.691483519224;2501.9749802502106;2750.8858912140613;2957.840776233362;2966.442617625764;4631.606313463326;4842.322074909515;5492.5605951523785;5679.630823996427;5789.797357092481;5727.026405511073;5744.51171650406;5755.510781583613;5782.6819524925695;5848.590598658053;5383.600263931406;5854;5858.238429004202;5885.910501771895;5895.233619629421;4948.777305021355;5270.051390749702;5536.366771802409;5580.637258578179;5686.488216060575;5313.468653134656;5569.164625306236;5580.497755202051;5612.130119831432;5624.212610234488;4969.751512424436;5144.4792051893555;5874.471297583067;5894.970525147347;5921.259842519685;3709.8516059357326;3774.3772277573894;3775.8300876461176;3803.809809509569;3806.7715937043868;4900.95099049005;5566.554675626021;5702.173184888251;5703.62926409782;5709.744650862969;5035.697858128524;5084.949150508448;5479.863003424787;5490.639025219194;5506.889862202814;4278.422412974209;5407.972960135174;5418.729063546728;5490.133354659435;5530.115181570929;4091.5294741105154;4465.4864690561;4825.758712064313;5281.735913204401;5292.364916210079;5004.649674522749;5313.203019547099;5390.541803946709;5404.459728977223;5453.618246722692;4953.306537084739;5328.8401347959625;5346.679199248058;5365.490278423544;5437.374701909331 14:45:30 INFO - PROCESS | 1653 | |5;yui.html;667.9833004174892;712.7790384980657;756.4251169111475;759.8860170974353;764.9961750191248;15288;15905.52283431496;15928.920355398206;15938.760918586202;16039.957402768812;14257.215853128073;14373.92813035936;14376.281185940714;14391.2084835334;14792.6301842454;674.6930146783226;694.7568351077108;845.7420486751554;850.345234169689;881.180502133016;724.6087112958995;868.6047848229041;875.251659830845;879.8900137482815;888.05865782271;927.2535608834885;928.0672923711694;929.7210836748948;930.9162175404213;934.9345545811796;716.0977168767339;924.0574613893791;925.2644147902433;925.842606756853;927.2442958988403;671.0605152786089;685.9794206173773;686.4062585863243;689.4759982413402;694.0595493106819;14514.201718905457;14525.201113938729;14528.564143075619;14539.054961427579;14585.635359116;13405;13760;13965.860341396557;14030.43878244869;14314.928425357806;12318.568850090203;12662.028378344661;12808.487660493569;13024.088313818036;13110.27893465859;4517.774111294422;4552.8861778455475;4554.180247555471;4740.099381117611;4744.335792988984;119693.40153299177;120282.3985880065;122714;124504;124729.7743203107;10920.617778377718;10994.725131871608;11035.503402346752;11129.998300152864;11472.4837382318;21709.782902170777;21731.13075476963;21813.236536721455;21824.781752182276;21827.12691492354;7877.487963282369;7895.447318687637;8052.476589021695;8120.472169308976;8183.427160098736;3951.565327813938;4350.456192975878;4426.557344265548;4515.1195516873895;4573.794179261941;3443.9483407748903;3498.772579782306;3553.3959226930965;3555.715542756625;3561.7506774526055;636.7166610858123;646.2019406033545;654.1953397321226;657.1260223902199;657.8486948001986;514.6526094886012;552.9806456773994;608.8843119807223;633.1547384242089;636.7519661463583;6903.6548172591165;9251.629934802533;9483.66807161746;9612.711618651452;9778.804423911624;566.5354409384265;584.736868409209;637.7927173668576;678.9932100679029;684.1038239905739;4292.56404668028;4682.531746825358;4705.388299521041;4707.03505781314;4711.293306004127;150942;152374;157029;162771;167504;105618;107488.92511074789;108348.4582577082;139374;146004;98734.50632746934;98984.08127349794;99658;109550.90449095407;111905;7488.363489103487;8878.245349145394;9285.582148803454;9444.574994125143;9477.715668529956;57252.42747572471;58660;61629.691851540454;62764.68617656882;63992.32188113942;84312;88434;89335.55332223297;89987;90263;12165.026797856328;12535.686607834878;13246;13445.731085378042;13704;14338.283085845875;14396.352164152848;14404.063735857137;14569.053011554215;14922.701545969237;13858.307084645929;14050.414501606409;14089.638774304889;14166.433342666178;14293.070950388192;13887.58337249884;14159.504417345344;14173.645658858612;14661;14812.925935370255;13504.797428038391;13718.65703357424;13764.724705505634;13923.354562633067;13931.791023134863;10739.570417183537;11056.502457389275;11207.887921120684;11505.079593632321;11830.526778928746;9416;9965.701028969142;10846.13230941507;11447.828282575934;11733 14:45:30 INFO - PROCESS | 1653 | __end_tp_report 14:45:30 INFO - PROCESS | 1653 | __start_cc_report 14:45:30 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,1267 14:45:30 INFO - PROCESS | 1653 | __end_cc_report 14:45:30 INFO - PROCESS | 1653 | __startTimestamp1473198330584__endTimestamp 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | ------- Summary: start ------- 14:45:30 INFO - PROCESS | 1653 | Number of tests: 6 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5693.89 Median:5896.26 stddev:650.76 (11.0%) stddev-sans-first:624.06 14:45:30 INFO - PROCESS | 1653 | Values: 3257.7 3307.8 3363.9 3375.8 3419.2 5518.0 5601.7 5645.3 5709.7 5715.6 5673.6 5703.5 5706.1 5747.5 5792.6 5088.8 5142.6 5172.8 5186.4 5270.0 5276.1 5276.4 5291.4 5301.8 5336.3 4658.5 4710.0 5105.8 5194.8 5223.1 5182.4 5190.3 5192.9 5208.8 5216.0 5108.9 5148.4 5155.7 5156.7 5162.9 5561.4 5596.9 5628.5 5629.6 5752.3 6208.9 6225.8 6227.5 6228.2 6254.2 6101.5 6160.7 6249.9 6289.0 6298.9 5749.7 5755.0 5798.5 5801.3 5851.5 6259.2 6277.8 6288.7 6293.5 6295.2 5991.2 6045.6 6068.7 6126.5 6142.2 6107.7 6123.2 6125.3 6220.2 6235.5 6153.8 6178.4 6181.9 6187.7 6201.0 5183.6 5239.1 6104.7 6198.0 6220.8 4821.2 4915.0 4924.1 4937.5 4984.7 3466.4 4405.2 4431.8 4436.1 4577.1 5674.9 5683.8 5687.6 5713.9 5762.7 5189.7 6108.4 6112.4 6121.2 6140.8 5651.0 5666.3 5677.4 5700.0 5719.4 5419.1 5437.4 5445.9 5446.6 5447.9 6165.2 6170.9 6224.1 6242.1 6253.2 6214.0 6219.0 6236.1 6243.4 6254.6 6341.3 6348.1 6360.7 6363.0 6374.8 6007.7 6034.8 6057.4 6086.2 6180.4 6168.8 6186.8 6194.1 6210.9 6213.8 6050.7 6067.0 6105.3 6108.8 6117.1 5896.3 5997.4 6010.1 6053.2 6095.9 5954.4 5957.8 5962.7 5972.3 6013.5 6111.2 6120.3 6127.4 6132.5 6132.7 5712.8 5739.9 5840.0 5884.8 5956.1 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:71590.36 Median:34637.31 stddev:157398.64 (454.4%) stddev-sans-first:157749.60 14:45:30 INFO - PROCESS | 1653 | Values: 466.4 491.7 525.2 527.7 555.6 30409.7 32686.7 40754.4 41239.6 41882.8 40829.8 41321.6 41359.6 43337.6 43343.9 9914.2 10129.9 10132.5 10146.8 10159.4 8939.6 12195.1 12410.0 12443.9 12643.9 12347.3 12391.8 12423.5 12434.0 12445.5 12443.8 12507.1 12521.4 12526.0 12536.2 12196.6 12248.4 12255.9 12270.7 12289.6 40850.8 41083.4 41137.6 41188.2 41332.0 3459.8 3795.4 4029.5 4064.4 4067.4 30893.5 30983.7 31241.7 31607.8 31855.8 3205.2 3262.7 3265.1 3352.9 3371.7 494429.0 497242.0 497814.0 498614.0 499356.0 57339.7 57627.6 57641.4 57666.4 57747.7 60497.0 60503.0 60505.8 60656.1 60667.0 15992.5 16200.8 16220.4 16230.2 16252.8 11406.6 11600.9 12634.6 12699.5 12735.9 14922.9 15298.7 15320.5 15661.0 15690.9 6089.8 6434.2 6945.7 7255.5 7285.7 876.5 880.3 958.2 991.6 1069.9 29253.9 29703.9 29723.1 30436.2 30622.0 1018.0 1043.9 1061.1 1062.4 1064.1 14581.1 15391.6 15420.9 15600.8 15787.4 662069.0 663062.7 666189.0 666825.0 667475.0 50170.7 57075.1 57828.0 57941.7 60492.7 1378.0 1403.8 1420.6 1426.0 1428.6 29980.2 30517.0 30602.0 31106.4 31494.0 49644.0 50157.5 50168.0 50189.5 50227.5 588271.0 590111.0 592666.0 593205.0 594374.0 25300.7 25465.4 25810.5 25886.6 25902.6 46895.1 48026.8 48054.5 48126.0 48539.0 38525.4 39182.8 39269.6 40045.0 40534.9 38461.5 39056.4 39092.6 40009.4 40561.6 38629.2 38828.6 38913.0 39008.2 39039.8 27405.2 32442.2 34438.8 34606.1 34668.5 24871.9 25014.1 25293.9 25460.6 25532.1 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:34115.83 Median:2388.63 stddev:95636.31 (4003.8%) stddev-sans-first:95871.00 14:45:30 INFO - PROCESS | 1653 | Values: 356.8 374.4 379.7 387.2 409.5 15877.5 15887.6 21305.7 21330.9 21847.6 21069.4 21434.8 21443.9 21773.9 21984.0 5742.2 5797.6 5957.1 6028.0 6079.0 4186.6 5898.9 5986.1 6016.2 6358.6 6621.0 6638.6 6649.7 6672.6 6690.3 5781.7 6101.1 6159.0 6159.7 6649.5 5167.4 5733.6 5925.6 5942.5 5978.3 13389.4 13818.2 13820.9 13852.0 15210.8 2165.5 2248.5 2255.0 2370.9 2406.4 9472.6 9963.6 10013.1 10334.5 10505.3 1775.0 1859.8 1885.6 1893.7 1954.5 96889.0 129449.4 130073.0 130209.3 130439.0 11109.8 11404.4 11470.3 11505.7 11703.6 14475.9 14490.3 14504.2 14509.3 14567.9 6867.9 8184.8 8459.9 8595.4 8613.9 8396.3 8396.5 8432.8 8586.5 8621.4 4399.6 4491.9 4497.0 4544.5 4580.6 1469.6 1629.0 1713.1 1798.6 1828.9 899.4 958.9 970.9 989.5 1026.9 20466.0 21271.1 21278.7 21407.5 21540.1 892.6 934.1 985.5 996.5 1004.7 9875.7 10619.8 11083.6 11085.4 11227.3 554897.0 567708.0 568594.0 577766.0 582155.0 121363.0 124039.4 125824.0 126034.0 126952.0 945.8 964.8 969.7 1025.1 1026.6 19258.8 19303.7 19600.8 19760.0 19852.8 40584.6 43384.0 51656.2 56554.0 58607.0 82673.2 82693.6 82830.0 84137.0 84485.0 7387.9 7493.2 7627.7 7688.5 7710.8 8551.9 11561.7 13009.3 13412.5 13582.9 9836.3 11405.8 11438.2 11768.5 11958.8 11411.3 11423.8 11552.1 11742.6 11891.6 11522.7 11725.9 11910.9 11969.6 12019.4 8059.6 8095.0 8171.3 8376.6 8638.0 8369.7 10895.5 11348.6 11562.0 11682.1 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5626.47 Median:5256.57 stddev:3106.10 (59.1%) stddev-sans-first:3098.31 14:45:30 INFO - PROCESS | 1653 | Values: 1366.5 1524.7 1550.6 1741.5 1903.3 7644.1 8092.2 8100.5 8104.3 8108.9 8389.9 8577.7 8718.7 8720.3 8758.6 2354.9 2375.5 2381.7 2386.4 2387.5 2332.4 2365.3 2375.1 2385.5 2389.8 2344.2 2364.2 2374.0 2378.0 2379.8 2313.8 2340.1 2344.3 2345.3 2366.8 2318.2 2337.6 2345.4 2346.0 2350.4 8682.8 8684.0 8765.0 8801.3 8819.1 5161.3 5168.8 5340.3 5346.0 5356.6 3572.3 3674.7 3694.2 3703.2 3713.4 1089.9 1107.2 1125.3 1125.6 1127.4 11281.4 11444.8 11449.2 11461.8 11467.4 4542.1 4714.2 4721.2 4769.9 4773.5 9809.8 9815.0 9817.2 9819.1 9841.8 4514.3 5307.3 5309.2 5311.2 5320.3 4091.8 4359.7 4789.9 4837.2 4862.6 3541.7 3828.9 3859.7 3879.6 4010.3 1257.8 1273.4 1281.8 1293.9 1294.0 745.4 796.9 899.4 904.7 905.6 5469.8 5684.1 5901.5 5999.1 6025.5 732.7 880.2 898.9 962.9 992.9 3561.5 3832.2 3942.6 3960.6 4038.7 10152.1 10561.1 10584.3 10698.4 10708.4 9106.6 10453.3 10664.9 10666.3 10718.8 7899.2 8120.7 8140.3 8151.3 8153.8 5623.0 5825.2 5986.0 6060.2 6079.0 9845.4 9847.9 9878.4 9879.5 9913.4 7511.7 9445.6 9460.2 9488.6 9956.4 6915.4 7071.8 7080.1 7205.6 7475.3 7973.4 8107.5 8194.1 8195.4 8263.0 7669.1 7688.3 7744.5 7761.8 7790.5 7518.1 7529.7 7545.4 7570.0 7581.5 6533.2 6765.8 6848.3 6877.6 6905.1 4973.4 5018.4 5043.5 5047.0 5240.2 4998.0 5101.6 5106.7 5142.7 5273.0 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4400.51 Median:4924.86 stddev:1381.29 (28.0%) stddev-sans-first:1350.23 14:45:30 INFO - PROCESS | 1653 | Values: 287.8 299.3 307.7 310.6 311.2 3754.5 4728.1 5014.7 5250.9 5269.8 4345.8 4685.7 4822.6 5218.4 5288.0 3344.4 3374.8 3655.9 3746.6 4012.3 3731.3 4038.9 4050.0 4075.8 4086.7 3110.3 3734.9 3897.5 3951.2 3981.6 3266.7 3953.8 3971.5 3986.5 4004.8 2910.5 2977.9 3444.3 3452.1 3458.2 4717.9 4723.8 4839.9 5057.8 5107.3 3807.9 4494.4 4513.7 4528.6 4537.3 4795.0 4885.7 5540.9 5567.2 5587.2 3518.9 3706.9 3707.6 3783.8 3819.0 5750.5 5763.3 5764.7 5773.2 5864.2 3222.5 3417.3 3787.7 3799.7 3819.6 3464.3 3822.7 3867.9 3883.8 3897.3 3916.5 3935.9 4136.6 4509.8 4511.8 4834.6 4886.9 5569.7 5580.7 5586.6 1127.5 1258.2 1282.9 1315.5 1334.4 862.4 881.8 888.4 890.9 904.8 5136.8 5168.9 5170.1 5178.9 5194.6 5132.7 5202.7 5671.3 5707.4 5752.0 4838.7 4863.9 5107.1 5114.9 5125.3 2250.7 2502.0 2750.9 2957.8 2966.4 4631.6 4842.3 5492.6 5679.6 5789.8 5727.0 5744.5 5755.5 5782.7 5848.6 5383.6 5854.0 5858.2 5885.9 5895.2 4948.8 5270.1 5536.4 5580.6 5686.5 5313.5 5569.2 5580.5 5612.1 5624.2 4969.8 5144.5 5874.5 5895.0 5921.3 3709.9 3774.4 3775.8 3803.8 3806.8 4901.0 5566.6 5702.2 5703.6 5709.7 5035.7 5084.9 5479.9 5490.6 5506.9 4278.4 5408.0 5418.7 5490.1 5530.1 4091.5 4465.5 4825.8 5281.7 5292.4 5004.6 5313.2 5390.5 5404.5 5453.6 4953.3 5328.8 5346.7 5365.5 5437.4 14:45:30 INFO - PROCESS | 1653 | 14:45:30 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25023.45 Median:4122.06 stddev:40254.09 (976.6%) stddev-sans-first:40325.48 14:45:30 INFO - PROCESS | 1653 | Values: 668.0 712.8 756.4 759.9 765.0 15288.0 15905.5 15928.9 15938.8 16040.0 14257.2 14373.9 14376.3 14391.2 14792.6 674.7 694.8 845.7 850.3 881.2 724.6 868.6 875.3 879.9 888.1 927.3 928.1 929.7 930.9 934.9 716.1 924.1 925.3 925.8 927.2 671.1 686.0 686.4 689.5 694.1 14514.2 14525.2 14528.6 14539.1 14585.6 13405.0 13760.0 13965.9 14030.4 14314.9 12318.6 12662.0 12808.5 13024.1 13110.3 4517.8 4552.9 4554.2 4740.1 4744.3 119693.4 120282.4 122714.0 124504.0 124729.8 10920.6 10994.7 11035.5 11130.0 11472.5 21709.8 21731.1 21813.2 21824.8 21827.1 7877.5 7895.4 8052.5 8120.5 8183.4 3951.6 4350.5 4426.6 4515.1 4573.8 3443.9 3498.8 3553.4 3555.7 3561.8 636.7 646.2 654.2 657.1 657.8 514.7 553.0 608.9 633.2 636.8 6903.7 9251.6 9483.7 9612.7 9778.8 566.5 584.7 637.8 679.0 684.1 4292.6 4682.5 4705.4 4707.0 4711.3 150942.0 152374.0 157029.0 162771.0 167504.0 105618.0 107488.9 108348.5 139374.0 146004.0 98734.5 98984.1 99658.0 109550.9 111905.0 7488.4 8878.2 9285.6 9444.6 9477.7 57252.4 58660.0 61629.7 62764.7 63992.3 84312.0 88434.0 89335.6 89987.0 90263.0 12165.0 12535.7 13246.0 13445.7 13704.0 14338.3 14396.4 14404.1 14569.1 14922.7 13858.3 14050.4 14089.6 14166.4 14293.1 13887.6 14159.5 14173.6 14661.0 14812.9 13504.8 13718.7 13764.7 13923.4 13931.8 10739.6 11056.5 11207.9 11505.1 11830.5 9416.0 9965.7 10846.1 11447.8 11733.0 14:45:30 INFO - PROCESS | 1653 | -------- Summary: end -------- 14:45:30 INFO - PROCESS | 1653 | 14:45:31 INFO - TEST-INFO | 1653: exit 0 14:45:31 INFO - TEST-OK | dromaeo_css | took 1117222ms 14:45:31 INFO - TEST-START | kraken 14:45:31 INFO - operating with platform_type : mac_ 14:45:31 INFO - Initialising browser for kraken test... 14:45:31 INFO - TEST-INFO | started process 1664 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpOHZGWJ/profile http://localhost:49267/getInfo.html) 14:45:33 INFO - PROCESS | 1664 | __metrics Screen width/height:1600/1200 14:45:33 INFO - PROCESS | 1664 | colorDepth:24 14:45:33 INFO - PROCESS | 1664 | Browser inner width/height: 1024/689 14:45:33 INFO - PROCESS | 1664 | __metrics 14:45:33 INFO - PROCESS | 1664 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 693: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:45:33 INFO - PROCESS | 1664 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:45:34 INFO - TEST-INFO | 1664: exit 0 14:45:34 INFO - Browser initialized. 14:45:34 INFO - Running cycle 1/1 for kraken test... 14:45:34 INFO - TEST-INFO | started process 1665 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/T/tmpOHZGWJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:45:46 INFO - PROCESS | 1665 | RSS: Main: 244711424 14:45:46 INFO - PROCESS | 1665 | 14:45:48 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection) 14:45:49 INFO - PROCESS | 1665 | RSS: Main: 294572032 14:45:49 INFO - PROCESS | 1665 | 14:45:52 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-dft) 14:45:53 INFO - PROCESS | 1665 | RSS: Main: 290402304 14:45:53 INFO - PROCESS | 1665 | 14:45:56 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-fft) 14:45:57 INFO - PROCESS | 1665 | RSS: Main: 379088896 14:45:57 INFO - PROCESS | 1665 | 14:45:59 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator) 14:45:59 INFO - PROCESS | 1665 | RSS: Main: 284430336 14:45:59 INFO - PROCESS | 1665 | 14:46:02 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:46:02 INFO - PROCESS | 1665 | RSS: Main: 287481856 14:46:02 INFO - PROCESS | 1665 | 14:46:05 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom) 14:46:06 INFO - PROCESS | 1665 | RSS: Main: 324358144 14:46:06 INFO - PROCESS | 1665 | 14:46:09 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate) 14:46:09 INFO - PROCESS | 1665 | RSS: Main: 327901184 14:46:09 INFO - PROCESS | 1665 | 14:46:12 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial) 14:46:13 INFO - PROCESS | 1665 | RSS: Main: 324923392 14:46:13 INFO - PROCESS | 1665 | 14:46:14 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:46:15 INFO - PROCESS | 1665 | RSS: Main: 299184128 14:46:15 INFO - PROCESS | 1665 | 14:46:17 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:46:17 INFO - PROCESS | 1665 | RSS: Main: 304840704 14:46:17 INFO - PROCESS | 1665 | 14:46:20 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:46:20 INFO - PROCESS | 1665 | RSS: Main: 304693248 14:46:20 INFO - PROCESS | 1665 | 14:46:23 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:46:24 INFO - PROCESS | 1665 | RSS: Main: 323108864 14:46:24 INFO - PROCESS | 1665 | 14:46:27 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:46:28 INFO - PROCESS | 1665 | RSS: Main: 318341120 14:46:28 INFO - PROCESS | 1665 | 14:46:29 INFO - PROCESS | 1665 | Cycle 1(1): loaded http://localhost:49267/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49267/tests/kraken/driver.html?testName=ai-astar) 14:46:30 INFO - PROCESS | 1665 | RSS: Main: 361824256 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | __start_tp_report 14:46:30 INFO - PROCESS | 1665 | _x_x_mozilla_page_load 14:46:30 INFO - PROCESS | 1665 | _x_x_mozilla_page_load_details 14:46:30 INFO - PROCESS | 1665 | |i|pagename|runs| 14:46:30 INFO - PROCESS | 1665 | |0;ai-astar;112;113;113;113;111;120;176;115;112;112 14:46:30 INFO - PROCESS | 1665 | |1;audio-beat-detection;145;112;130;161;107;131;130;115;201;139 14:46:30 INFO - PROCESS | 1665 | |2;audio-dft;182;185;185;183;183;193;262;180;183;181 14:46:30 INFO - PROCESS | 1665 | |3;audio-fft;72;78;75;153;58;57;63;69;135;69 14:46:30 INFO - PROCESS | 1665 | |4;audio-oscillator;82;84;122;64;110;120;136;69;70;118 14:46:30 INFO - PROCESS | 1665 | |5;imaging-gaussian-blur;87;85;88;84;86;84;87;84;88;84 14:46:30 INFO - PROCESS | 1665 | |6;imaging-darkroom;96;94;95;110;167;96;96;96;95;119 14:46:30 INFO - PROCESS | 1665 | |7;imaging-desaturate;87;86;85;84;84;81;89;85;84;86 14:46:30 INFO - PROCESS | 1665 | |8;json-parse-financial;55;55;58;57;53;55;54;56;54;56 14:46:30 INFO - PROCESS | 1665 | |9;json-stringify-tinderbox;68;66;78;79;73;71;72;73;71;73 14:46:30 INFO - PROCESS | 1665 | |10;stanford-crypto-aes;85;89;86;84;105;93;85;81;89;86 14:46:30 INFO - PROCESS | 1665 | |11;stanford-crypto-ccm;139;140;136;134;148;152;154;139;178;137 14:46:30 INFO - PROCESS | 1665 | |12;stanford-crypto-pbkdf2;166;175;234;196;168;166;189;236;156;230 14:46:30 INFO - PROCESS | 1665 | |13;stanford-crypto-sha256-iterative;67;67;69;68;68;65;68;70;81;87 14:46:30 INFO - PROCESS | 1665 | __end_tp_report 14:46:30 INFO - PROCESS | 1665 | __start_cc_report 14:46:30 INFO - PROCESS | 1665 | _x_x_mozilla_cycle_collect,2037 14:46:30 INFO - PROCESS | 1665 | __end_cc_report 14:46:30 INFO - PROCESS | 1665 | __startTimestamp1473198390112__endTimestamp 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | ------- Summary: start ------- 14:46:30 INFO - PROCESS | 1665 | Number of tests: 14 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#0] ai-astar Cycles:10 Average:119.70 Median:113.00 stddev:19.94 (17.7%) stddev-sans-first:20.96 14:46:30 INFO - PROCESS | 1665 | Values: 112.0 113.0 113.0 113.0 111.0 120.0 176.0 115.0 112.0 112.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#1] audio-beat-detection Cycles:10 Average:137.10 Median:135.00 stddev:27.65 (20.5%) stddev-sans-first:29.18 14:46:30 INFO - PROCESS | 1665 | Values: 145.0 112.0 130.0 161.0 107.0 131.0 130.0 115.0 201.0 139.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#2] audio-dft Cycles:10 Average:191.70 Median:184.00 stddev:24.96 (13.6%) stddev-sans-first:26.22 14:46:30 INFO - PROCESS | 1665 | Values: 182.0 185.0 185.0 183.0 183.0 193.0 262.0 180.0 183.0 181.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#3] audio-fft Cycles:10 Average:82.90 Median:69.00 stddev:33.18 (48.1%) stddev-sans-first:34.96 14:46:30 INFO - PROCESS | 1665 | Values: 72.0 78.0 75.0 153.0 58.0 57.0 63.0 69.0 135.0 69.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#4] audio-oscillator Cycles:10 Average:97.50 Median:66.50 stddev:26.41 (39.7%) stddev-sans-first:27.41 14:46:30 INFO - PROCESS | 1665 | Values: 82.0 84.0 122.0 64.0 110.0 120.0 136.0 69.0 70.0 118.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#5] imaging-gaussian-blur Cycles:10 Average:85.70 Median:86.50 stddev:1.70 (2.0%) stddev-sans-first:1.74 14:46:30 INFO - PROCESS | 1665 | Values: 87.0 85.0 88.0 84.0 86.0 84.0 87.0 84.0 88.0 84.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#6] imaging-darkroom Cycles:10 Average:106.40 Median:95.50 stddev:22.83 (23.9%) stddev-sans-first:23.90 14:46:30 INFO - PROCESS | 1665 | Values: 96.0 94.0 95.0 110.0 167.0 96.0 96.0 96.0 95.0 119.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#7] imaging-desaturate Cycles:10 Average:85.10 Median:85.50 stddev:2.13 (2.5%) stddev-sans-first:2.15 14:46:30 INFO - PROCESS | 1665 | Values: 87.0 86.0 85.0 84.0 84.0 81.0 89.0 85.0 84.0 86.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#8] json-parse-financial Cycles:10 Average:55.30 Median:55.50 stddev:1.49 (2.7%) stddev-sans-first:1.58 14:46:30 INFO - PROCESS | 1665 | Values: 55.0 55.0 58.0 57.0 53.0 55.0 54.0 56.0 54.0 56.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#9] json-stringify-tinderbox Cycles:10 Average:72.40 Median:73.00 stddev:3.95 (5.4%) stddev-sans-first:3.86 14:46:30 INFO - PROCESS | 1665 | Values: 68.0 66.0 78.0 79.0 73.0 71.0 72.0 73.0 71.0 73.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#10] stanford-crypto-aes Cycles:10 Average:88.30 Median:86.00 stddev:6.72 (7.8%) stddev-sans-first:7.02 14:46:30 INFO - PROCESS | 1665 | Values: 85.0 89.0 86.0 84.0 105.0 93.0 85.0 81.0 89.0 86.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#11] stanford-crypto-ccm Cycles:10 Average:145.70 Median:144.00 stddev:13.28 (9.2%) stddev-sans-first:13.86 14:46:30 INFO - PROCESS | 1665 | Values: 139.0 140.0 136.0 134.0 148.0 152.0 154.0 139.0 178.0 137.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.60 Median:192.50 stddev:31.06 (16.1%) stddev-sans-first:31.53 14:46:30 INFO - PROCESS | 1665 | Values: 166.0 175.0 234.0 196.0 168.0 166.0 189.0 236.0 156.0 230.0 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - PROCESS | 1665 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:71.00 Median:68.50 stddev:7.12 (10.4%) stddev-sans-first:7.40 14:46:30 INFO - PROCESS | 1665 | Values: 67.0 67.0 69.0 68.0 68.0 65.0 68.0 70.0 81.0 87.0 14:46:30 INFO - PROCESS | 1665 | -------- Summary: end -------- 14:46:30 INFO - PROCESS | 1665 | 14:46:30 INFO - TEST-INFO | 1665: exit 0 14:46:30 INFO - TEST-OK | kraken | took 59774ms 14:46:30 INFO - SUITE-END | took 1176s 14:46:31 INFO - Completed test suite (00:19:37) 14:46:31 INFO - javascript benchmark 14:46:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3257.7230935370494, 3307.7849939753914, 3363.915902102447, 3375.7974521528727, 3419.162305235216, 5517.972410137953, 5601.663900165982, 5645.2661154049965, 5709.657420554764, 5715.599908006441, 5673.574481913863, 5703.458171473703, 5706.115552089423, 5747.453991870776, 5792.565557583177, 5088.771005304751, 5142.588592912577, 5172.767225474862, 5186.377634683843, 5269.973650131763, 5276.1030624793875, 5276.445973172819, 5291.444398338177, 5301.787928482856, 5336.332958380202, 4658.510856360067, 4710.034442939189, 5105.770240339194, 5194.818181363672, 5223.112070947948, 5182.378114626231, 5190.273361729361, 5192.948070519285, 5208.817691380784, 5216.008958297949, 5108.872278193075, 5148.407933087706, 5155.71643559604, 5156.664816786897, 5162.92255616166, 5561.416051314634, 5596.916046259309, 5628.549716022709, 5629.60592758507, 5752.309722833287, 6208.906866397007, 6225.7509699611965, 6227.501799856001, 6228.22147231596, 6254.186955695776, 6101.542384321194, 6160.6919654017565, 6249.937500624981, 6289.0, 6298.87402251957, 5749.712514374306, 5755.0, 5798.478136967693, 5801.332846722681, 5851.531877449836, 6259.155014073133, 6277.780277690259, 6288.685565721696, 6293.527985401021, 6295.150154729145, 5991.161237426763, 6045.607387642967, 6068.726907289182, 6126.509879209652, 6142.170806941095, 6107.694075676726, 6123.191113103742, 6125.296497837281, 6220.222472190976, 6235.501159907197, 6153.784617538454, 6178.41305076017, 6181.938180618226, 6187.690615469209, 6200.968995154995, 5183.585313174937, 5239.1355426354985, 6104.694765261719, 6198.008318668992, 6220.844478887973, 4821.228603423436, 4914.950850491521, 4924.064427758714, 4937.456879743226, 4984.7258400788305, 3466.3933811582874, 4405.185040767468, 4431.8448854289945, 4436.090601426764, 4577.1074640444795, 5674.943250567441, 5683.8010669626365, 5687.630304030225, 5713.885722285531, 5762.683052432157, 5189.714565698923, 6108.35862234459, 6112.358202388774, 6121.234845644294, 6140.846478837975, 5651.0, 5666.34836993751, 5677.403872593318, 5699.974004679115, 5719.428057194247, 5419.078756611386, 5437.401885792559, 5445.945540544623, 5446.618736688394, 5447.891042179134, 6165.229346331708, 6170.876582468415, 6224.128621992834, 6242.126102345766, 6253.187085678924, 6213.96893015532, 6218.968905155445, 6236.064590311308, 6243.375662433902, 6254.593451425642, 6341.302456729663, 6348.111264422892, 6360.745570177141, 6362.968185159045, 6374.840628984312, 6007.729652165575, 6034.818955431344, 6057.42454466825, 6086.239220097488, 6180.443760061616, 6168.753249870135, 6186.81439556796, 6194.132821404917, 6210.906836397548, 6213.782517611862, 6050.727717252822, 6066.969665151646, 6105.328413874559, 6108.816735497942, 6117.1130186123655, 5896.262967129109, 5997.430244126801, 6010.068439391901, 6053.182820319201, 6095.908561371494, 5954.404559544011, 5957.821265362046, 5962.731677074629, 5972.313183983897, 6013.5189184865985, 6111.236095488064, 6120.265568131853, 6127.417895299939, 6132.478739307209, 6132.724027418866, 5712.80005199816, 5739.8565035874435, 5840.036393995113, 5884.823455296348, 5956.106584012433], "value": 5651.806578464357, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [466.39368820533343, 491.7197197597366, 525.1702310349646, 527.6596595196098, 555.572209398763, 30409.695903040963, 32686.67313326866, 40754.38868416976, 41239.58760412391, 41882.79058604703, 40829.795851020775, 41321.58678413215, 41359.58640413595, 43337.56662433375, 43343.91640208996, 9914.157296629777, 10129.898701013006, 10132.54403551842, 10146.847797283046, 10159.441230732307, 8939.55302234889, 12195.08536859739, 12410.0, 12443.875561244362, 12643.902243950199, 12347.320897350643, 12391.752164956695, 12423.503059877594, 12434.0, 12445.502179912837, 12443.751124977494, 12507.06197035226, 12521.373931303398, 12525.997920166365, 12536.185147965352, 12196.634100976986, 12248.44881980313, 12255.896969272808, 12270.693232669166, 12289.56986505468, 40850.79574602138, 41083.38374924379, 41137.588624113676, 41188.176236475105, 41332.0, 3459.8270086495572, 3795.449659799314, 4029.454080409221, 4064.43097966282, 4067.410225517313, 30893.536596951064, 30983.690163098305, 31241.687583124105, 31607.84196079005, 31855.84072079625, 3205.182678417012, 3262.722668573174, 3265.0857759827286, 3352.949705754416, 3371.7471189660873, 494429.0, 497242.0, 497814.0, 498614.0, 499356.0, 57339.71330143406, 57627.55931101756, 57641.42358576361, 57666.42333576611, 57747.71126144427, 60497.0, 60503.0, 60505.789884202066, 60656.090158647654, 60667.0, 15992.520224393287, 16200.75698864518, 16220.432284869972, 16230.188490575423, 16252.75620865688, 11406.600768973047, 11600.88399116015, 12634.557790477473, 12699.492020319269, 12735.936320318338, 14922.925385373004, 15298.694026119638, 15320.463783767515, 15661.0, 15690.921545392428, 6089.786857459968, 6434.227892652912, 6945.687444064928, 7255.52839065459, 7285.70857165718, 876.4565969099199, 880.3485420788699, 958.1951161024773, 991.6281394477071, 1069.850220969065, 29253.853730731636, 29703.851480742458, 29723.108306750833, 30436.239094022825, 30622.0, 1017.9694609161737, 1043.8642976413018, 1061.1404762142688, 1062.4262898034974, 1064.095518809006, 14581.052231604912, 15391.61520961985, 15420.92289538545, 15600.765988510182, 15787.36850525989, 662069.0, 663062.6846865831, 666189.0, 666825.0, 667475.0, 50170.749146254035, 57075.14387284194, 57828.0, 57941.710291448275, 60492.69753651292, 1377.9655508612163, 1403.8245219347582, 1420.6306360346384, 1426.0302993964203, 1428.6214153249398, 29980.209273226825, 30517.0, 30602.0, 31106.37787244197, 31494.0, 49644.00711985812, 50157.49842501674, 50167.99664006772, 50189.49810501848, 50227.49772502228, 588271.0, 590111.0494447501, 592666.0, 593205.0, 594374.0, 25300.74699252984, 25465.363365916, 25810.483790324462, 25886.61170082488, 25902.61146082848, 46895.08993291264, 48026.759866200446, 48054.519454805006, 48126.0, 48539.0, 38525.42211866768, 39182.80408597939, 39269.6073039266, 40044.99887502836, 40534.879652078045, 38461.46155153574, 39056.41415378828, 39092.60907391003, 40009.39985900272, 40561.59438405578, 38629.22741545209, 38828.61171388363, 38913.027174320865, 39008.21983560256, 39039.80480097581, 27405.177844664693, 32442.188945276557, 34438.82780586081, 34606.13484662904, 34668.47997279993, 24871.87564062168, 25014.124505642216, 25293.86177622048, 25460.618090729025, 25532.10637627674], "value": 20432.22069632477, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [356.80019189254006, 374.4476896577548, 379.6981399787167, 387.17145309038654, 409.52086059310625, 15877.523674289776, 15887.602809929758, 21305.680414793773, 21330.89334553325, 21847.563048739015, 21069.367918962456, 21434.82108484033, 21443.892780536076, 21773.891130544325, 21984.0, 5742.196092547047, 5797.565182611311, 5957.106434034908, 6028.005379112441, 6079.0, 4186.623203911648, 5898.911516327258, 5986.132010858423, 6016.187814645033, 6358.586691865014, 6621.0, 6638.634875081869, 6649.700763465631, 6672.566283191577, 6690.297518760533, 5781.682007489587, 6101.0848372744, 6159.045347971071, 6159.692015399212, 6649.468042556608, 5167.379914410257, 5733.598648094635, 5925.644461332334, 5942.465178133989, 5978.312494063195, 13389.397477113553, 13818.170909745348, 13820.93089534556, 13852.030357874954, 15210.771838422432, 2165.545235500531, 2248.482848944752, 2254.9514475768847, 2370.9051637934463, 2406.362313986795, 9472.57373418191, 9963.551640176209, 10013.148882345009, 10334.483275836179, 10505.317154385017, 1774.9911250443795, 1859.8233167849032, 1885.575745457275, 1893.6875415556417, 1954.5309125809845, 96889.0, 129449.35275323657, 130072.99999999905, 130209.34895325557, 130439.0, 11109.83335249972, 11404.429778511041, 11470.254433461798, 11505.654830355103, 11703.552649992947, 14475.92762036183, 14490.275486225646, 14504.202268875313, 14509.274536273144, 14567.92716036413, 6867.862642747117, 8184.83630327402, 8459.873101903477, 8595.398322117391, 8613.913860861436, 8396.328293736486, 8396.538190399464, 8432.78918027042, 8586.527740974307, 8621.396502244783, 4399.62603178727, 4491.927872190064, 4497.033137875373, 4544.454665440168, 4580.633549316047, 1469.6105532034019, 1629.0144462600192, 1713.100622173344, 1798.6312805874763, 1828.8994105324068, 899.3794281945568, 958.91849192818, 970.8689326940772, 989.5151375825938, 1026.8665073540392, 20466.0, 21271.149154033665, 21278.680819787714, 21407.46481337948, 21540.13839446436, 892.5804871710286, 934.0519372836576, 985.5121714751244, 996.5117092624706, 1004.7437903334603, 9875.654352097643, 10619.787604248026, 11083.612073577548, 11085.448052173884, 11227.326360418401, 554897.0, 567708.0, 568594.0, 577766.0, 582155.0, 121363.0, 124039.37980310041, 125824.0, 126034.0, 126952.0, 945.8392073347547, 964.840801267797, 969.699393188114, 1025.103034844526, 1026.573971801694, 19258.844469331885, 19303.710444343065, 19600.765873631262, 19760.0, 19852.801471985673, 40584.59415405926, 43384.0, 51656.192033278654, 56554.0, 58607.0, 82673.17326826655, 82693.58653206695, 82830.0, 84137.0, 84485.0, 7387.852242955219, 7493.175750667313, 7627.656755445907, 7688.500247483896, 7710.7686769397005, 8551.871721924052, 11561.653150405502, 13009.349532523147, 13412.530561430303, 13582.86417135816, 9836.311458197857, 11405.771884562428, 11438.199326047097, 11768.529258829893, 11958.760824783629, 11411.315321080761, 11423.828642570534, 11552.075833933097, 11742.58900938463, 11891.583794567148, 11522.654320370402, 11725.941370293094, 11910.940445297718, 11969.64091077269, 12019.399030048287, 8059.556724379981, 8095.0, 8171.264586187272, 8376.623051962799, 8638.0, 8369.707060252862, 10895.509702063267, 11348.602798901999, 11562.0, 11682.065434765369], "value": 9442.30791439736, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1366.460248201961, 1524.7103050420424, 1550.6356006338508, 1741.4601473543203, 1903.3243198664481, 7644.120926093501, 8092.231238032392, 8100.513969161847, 8104.270615644606, 8108.91891081089, 8389.91610083899, 8577.742667719962, 8718.73843784686, 8720.302375809935, 8758.649654013832, 2354.929352119435, 2375.489269806991, 2381.7499162587937, 2386.3556839653284, 2387.4747555537842, 2332.4402143485613, 2365.3022358404314, 2375.1212051540956, 2385.510970251103, 2389.772971567707, 2344.23812261015, 2364.207990323244, 2373.976260237401, 2377.988110059447, 2379.7858192762646, 2313.7917587417046, 2340.0990618611786, 2344.34358379654, 2345.3433038749176, 2366.786989170983, 2318.2349824557855, 2337.6026075567192, 2345.3550273674705, 2345.988270058656, 2350.435895385095, 8682.78293042679, 8684.0, 8765.0, 8801.251893589053, 8819.073997230262, 5161.329027226474, 5168.793248270064, 5340.25236466895, 5346.03771321162, 5356.571474282087, 3572.3212589607892, 3674.6876515496215, 3694.1688120173017, 3703.203811180609, 3713.424419215026, 1089.9073578745738, 1107.2083460325825, 1125.2517076144354, 1125.6172901213545, 1127.363039882472, 11281.37952412617, 11444.828327575093, 11449.198556101077, 11461.770764584746, 11467.36929468879, 4542.091581683677, 4714.175019371596, 4721.15019296523, 4769.880752981203, 4773.498782627844, 9809.803803923882, 9814.950925245328, 9817.214622830157, 9819.116279534876, 9841.803163936682, 4514.255147900625, 5307.2569840222395, 5309.203619457112, 5311.229871668586, 5320.334958130234, 4091.7954102294766, 4359.694821362475, 4789.856304310876, 4837.153498137882, 4862.586680132228, 3541.7249790075552, 3828.9234215315537, 3859.749116307431, 3879.553851307079, 4010.2981978153707, 1257.8239046533492, 1273.3887733887786, 1281.833361662978, 1293.8706129386985, 1293.9611811645666, 745.3999530377989, 796.916193976192, 899.40189773801, 904.6788390121544, 905.6422713028317, 5469.753861076261, 5684.147377893267, 5901.468867801918, 5999.130126131687, 6025.517958563304, 732.6922692469069, 880.1990188928045, 898.9370744047985, 962.9133377996014, 992.8858181309098, 3561.5013898054294, 3832.1952389997855, 3942.586028467027, 3960.6237407446247, 4038.697097717683, 10152.086312231935, 10561.102306303897, 10584.259101862795, 10698.358098513958, 10708.411037393018, 9106.635734570675, 10453.268271221094, 10664.946675266729, 10666.306690065121, 10718.785624287471, 7899.170587088388, 8120.675172993132, 8140.3487720982175, 8151.347892168612, 8153.7553873383895, 5623.0, 5825.242718446575, 5985.970070149796, 6060.212172417558, 6079.0, 9845.409275443497, 9847.901520984698, 9878.357906736039, 9879.506024698592, 9913.405195688281, 7511.699532018658, 9445.622175113192, 9460.195883349992, 9488.573014214238, 9956.352837065857, 6915.412189963708, 7071.752488663078, 7080.114985626797, 7205.639718013974, 7475.289847464483, 7973.3621310293875, 8107.47301425429, 8194.098649148707, 8195.385346098947, 8263.049749278669, 7669.07971043456, 7688.269614386624, 7744.483043010931, 7761.844763104594, 7790.454668173174, 7518.097828260644, 7529.661165247468, 7545.396368290414, 7569.962150189213, 7581.50720203185, 6533.248676402274, 6765.763198288037, 6848.280930502425, 6877.58734475913, 6905.102336696197, 4973.428055773632, 5018.397792264952, 5043.460885203346, 5046.9747651261505, 5240.161574148118, 4997.975010125072, 5101.642884998015, 5106.719130447861, 5142.665726727751, 5272.9736351318], "value": 4541.557070357966, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [287.80429308070507, 299.3085971406052, 307.67232896964725, 310.58691939720177, 311.2312587907868, 3754.493143425637, 4728.148933192024, 5014.674046186995, 5250.868728281795, 5269.76286067127, 4345.804438800254, 4685.688007357937, 4822.638302127346, 5218.373795144578, 5288.0, 3344.4147274227034, 3374.8312584370806, 3655.9268814623692, 3746.587875333711, 4012.3179059559943, 3731.2910546995986, 4038.899027524321, 4049.959500404988, 4075.8369665213354, 4086.652634526069, 3110.2690867646083, 3734.906627334311, 3897.4543563901075, 3951.2097580483874, 3981.6263389130704, 3266.6733326667263, 3953.8220780064967, 3971.517801374141, 3986.501687289074, 4004.759714417115, 2910.534314509668, 2977.8957736479338, 3444.2594842108815, 3452.085197422685, 3458.204612939013, 4717.905641887143, 4723.7638118093955, 4839.854804355875, 5057.772400241998, 5107.3360463139925, 3807.9056518914485, 4494.37078808967, 4513.668467801974, 4528.592426681615, 4537.254960748733, 4795.0, 4885.682430641997, 5540.889182216333, 5567.164925261202, 5587.217789509472, 3518.9091381671506, 3706.8887933362043, 3707.5694545018714, 3783.810809459516, 3819.0261483321574, 5750.45370689788, 5763.331701321858, 5764.74058667361, 5773.2494775679315, 5864.179014937912, 3222.452183128874, 3417.2994536120036, 3787.715921305913, 3799.715021373408, 3819.644026370654, 3464.3071385722956, 3822.7324087313623, 3867.8646247381207, 3883.76697398157, 3897.337452633059, 3916.5300163979937, 3935.940960885589, 4136.627703506699, 4509.774511274423, 4511.842085526991, 4834.589059929875, 4886.926696099561, 5569.721513924287, 5580.748866300945, 5586.55307575393, 1127.4588197665166, 1258.1884684378708, 1282.9037822163375, 1315.493534989036, 1334.379513526215, 862.3790870573177, 881.823635272948, 888.4136469929815, 890.8975467821153, 904.8190361927641, 5136.8202112925865, 5168.94036722471, 5170.095233334151, 5178.870528236825, 5194.558462530652, 5132.717700526507, 5202.687838729613, 5671.262735844314, 5707.400722924116, 5751.964646363611, 4838.7338696372035, 4863.878403039952, 5107.1103691634335, 5114.923276150861, 5125.282460455539, 2250.691483519224, 2501.9749802502106, 2750.8858912140613, 2957.840776233362, 2966.442617625764, 4631.606313463326, 4842.322074909515, 5492.5605951523785, 5679.630823996427, 5789.797357092481, 5727.026405511073, 5744.51171650406, 5755.510781583613, 5782.6819524925695, 5848.590598658053, 5383.600263931406, 5854.0, 5858.238429004202, 5885.910501771895, 5895.233619629421, 4948.777305021355, 5270.051390749702, 5536.366771802409, 5580.637258578179, 5686.488216060575, 5313.468653134656, 5569.164625306236, 5580.497755202051, 5612.130119831432, 5624.212610234488, 4969.751512424436, 5144.4792051893555, 5874.471297583067, 5894.970525147347, 5921.259842519685, 3709.8516059357326, 3774.3772277573894, 3775.8300876461176, 3803.809809509569, 3806.7715937043868, 4900.95099049005, 5566.554675626021, 5702.173184888251, 5703.62926409782, 5709.744650862969, 5035.697858128524, 5084.949150508448, 5479.863003424787, 5490.639025219194, 5506.889862202814, 4278.422412974209, 5407.972960135174, 5418.729063546728, 5490.133354659435, 5530.115181570929, 4091.5294741105154, 4465.4864690561, 4825.758712064313, 5281.735913204401, 5292.364916210079, 5004.649674522749, 5313.203019547099, 5390.541803946709, 5404.459728977223, 5453.618246722692, 4953.306537084739, 5328.8401347959625, 5346.679199248058, 5365.490278423544, 5437.374701909331], "value": 3965.4387248060357, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.9833004174892, 712.7790384980657, 756.4251169111475, 759.8860170974353, 764.9961750191248, 15288.0, 15905.52283431496, 15928.920355398206, 15938.760918586202, 16039.957402768812, 14257.215853128073, 14373.92813035936, 14376.281185940714, 14391.2084835334, 14792.6301842454, 674.6930146783226, 694.7568351077108, 845.7420486751554, 850.345234169689, 881.180502133016, 724.6087112958995, 868.6047848229041, 875.251659830845, 879.8900137482815, 888.05865782271, 927.2535608834885, 928.0672923711694, 929.7210836748948, 930.9162175404213, 934.9345545811796, 716.0977168767339, 924.0574613893791, 925.2644147902433, 925.842606756853, 927.2442958988403, 671.0605152786089, 685.9794206173773, 686.4062585863243, 689.4759982413402, 694.0595493106819, 14514.201718905457, 14525.201113938729, 14528.564143075619, 14539.054961427579, 14585.635359116, 13405.0, 13760.0, 13965.860341396557, 14030.43878244869, 14314.928425357806, 12318.568850090203, 12662.028378344661, 12808.487660493569, 13024.088313818036, 13110.27893465859, 4517.774111294422, 4552.8861778455475, 4554.180247555471, 4740.099381117611, 4744.335792988984, 119693.40153299177, 120282.3985880065, 122714.0, 124504.0, 124729.7743203107, 10920.617778377718, 10994.725131871608, 11035.503402346752, 11129.998300152864, 11472.4837382318, 21709.782902170777, 21731.13075476963, 21813.236536721455, 21824.781752182276, 21827.12691492354, 7877.487963282369, 7895.447318687637, 8052.476589021695, 8120.472169308976, 8183.427160098736, 3951.565327813938, 4350.456192975878, 4426.557344265548, 4515.1195516873895, 4573.794179261941, 3443.9483407748903, 3498.772579782306, 3553.3959226930965, 3555.715542756625, 3561.7506774526055, 636.7166610858123, 646.2019406033545, 654.1953397321226, 657.1260223902199, 657.8486948001986, 514.6526094886012, 552.9806456773994, 608.8843119807223, 633.1547384242089, 636.7519661463583, 6903.6548172591165, 9251.629934802533, 9483.66807161746, 9612.711618651452, 9778.804423911624, 566.5354409384265, 584.736868409209, 637.7927173668576, 678.9932100679029, 684.1038239905739, 4292.56404668028, 4682.531746825358, 4705.388299521041, 4707.03505781314, 4711.293306004127, 150942.0, 152374.0, 157029.0, 162771.0, 167504.0, 105618.0, 107488.92511074789, 108348.4582577082, 139374.0, 146004.0, 98734.50632746934, 98984.08127349794, 99658.0, 109550.90449095407, 111905.0, 7488.363489103487, 8878.245349145394, 9285.582148803454, 9444.574994125143, 9477.715668529956, 57252.42747572471, 58660.0, 61629.691851540454, 62764.68617656882, 63992.32188113942, 84312.0, 88434.0, 89335.55332223297, 89987.0, 90263.0, 12165.026797856328, 12535.686607834878, 13246.0, 13445.731085378042, 13704.0, 14338.283085845875, 14396.352164152848, 14404.063735857137, 14569.053011554215, 14922.701545969237, 13858.307084645929, 14050.414501606409, 14089.638774304889, 14166.433342666178, 14293.070950388192, 13887.58337249884, 14159.504417345344, 14173.645658858612, 14661.0, 14812.925935370255, 13504.797428038391, 13718.65703357424, 13764.724705505634, 13923.354562633067, 13931.791023134863, 10739.570417183537, 11056.502457389275, 11207.887921120684, 11505.079593632321, 11830.526778928746, 9416.0, 9965.701028969142, 10846.13230941507, 11447.828282575934, 11733.0], "value": 7856.326149992699, "unit": "score"}], "name": "dromaeo_css", "value": 7323.593109555841, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 113.0, 113.0, 113.0, 111.0, 120.0, 176.0, 115.0, 112.0, 112.0], "value": 119.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 112.0, 130.0, 161.0, 107.0, 131.0, 130.0, 115.0, 201.0, 139.0], "value": 137.1, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 185.0, 185.0, 183.0, 183.0, 193.0, 262.0, 180.0, 183.0, 181.0], "value": 191.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 78.0, 75.0, 153.0, 58.0, 57.0, 63.0, 69.0, 135.0, 69.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 84.0, 122.0, 64.0, 110.0, 120.0, 136.0, 69.0, 70.0, 118.0], "value": 97.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 85.0, 88.0, 84.0, 86.0, 84.0, 87.0, 84.0, 88.0, 84.0], "value": 85.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 94.0, 95.0, 110.0, 167.0, 96.0, 96.0, 96.0, 95.0, 119.0], "value": 106.4, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 86.0, 85.0, 84.0, 84.0, 81.0, 89.0, 85.0, 84.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 55.0, 58.0, 57.0, 53.0, 55.0, 54.0, 56.0, 54.0, 56.0], "value": 55.3, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 66.0, 78.0, 79.0, 73.0, 71.0, 72.0, 73.0, 71.0, 73.0], "value": 72.4, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [85.0, 89.0, 86.0, 84.0, 105.0, 93.0, 85.0, 81.0, 89.0, 86.0], "value": 88.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 140.0, 136.0, 134.0, 148.0, 152.0, 154.0, 139.0, 178.0, 137.0], "value": 145.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 175.0, 234.0, 196.0, 168.0, 166.0, 189.0, 236.0, 156.0, 230.0], "value": 191.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 69.0, 68.0, 68.0, 65.0, 68.0, 70.0, 81.0, 87.0], "value": 71.0, "unit": "score"}], "name": "kraken", "value": 1530.3999999999999, "alertThreshold": 2.0}]} 14:46:31 INFO - Return code: 0 14:46:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:46:31 INFO - # TBPL SUCCESS # 14:46:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:46:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:46:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:46:31 INFO - 2016-09-06 14:46:31.370 screenresolution[1669:9729] starting screenresolution argv=screenresolution get 14:46:31 INFO - 2016-09-06 14:46:31.372 screenresolution[1669:9729] Display 0: 1600x1200x32@60 14:46:31 INFO - 2016-09-06 14:46:31.378 screenresolution[1670:9732] starting screenresolution argv=screenresolution list 14:46:31 INFO - Available Modes on Display 0 14:46:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:46:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:46:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:46:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:46:31 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:46:31 INFO - Intel Iris: 14:46:31 INFO - Chipset Model: Intel Iris 14:46:31 INFO - Type: GPU 14:46:31 INFO - Bus: Built-In 14:46:31 INFO - VRAM (Dynamic, Max): 1536 MB 14:46:31 INFO - Vendor: Intel (0x8086) 14:46:31 INFO - Device ID: 0x0a2e 14:46:31 INFO - Revision ID: 0x0009 14:46:31 INFO - Displays: 14:46:31 INFO - Display: 14:46:31 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:46:31 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:46:31 INFO - Display Serial Number: 1600x1200 60 14:46:31 INFO - Main Display: Yes 14:46:31 INFO - Mirror: Off 14:46:31 INFO - Online: Yes 14:46:31 INFO - Rotation: Supported 14:46:31 INFO - Return code: 0 14:46:31 INFO - Running post-action listener: _resource_record_post_action 14:46:31 INFO - [mozharness: 2016-09-06 21:46:31.616275Z] Finished run-tests step (success) 14:46:31 INFO - Running post-run listener: _resource_record_post_run 14:46:31 INFO - Total resource usage - Wall time: 1212s; CPU: 54.0%; Read bytes: 37979136; Write bytes: 704115712; Read time: 377; Write time: 1818 14:46:31 INFO - TinderboxPrint: CPU usage
53.6% 14:46:31 INFO - TinderboxPrint: I/O read bytes / time
37,979,136 / 377 14:46:31 INFO - TinderboxPrint: I/O write bytes / time
704,115,712 / 1,818 14:46:31 INFO - TinderboxPrint: CPU idle
2,249.8 (46.4%) 14:46:31 INFO - TinderboxPrint: CPU system
970.5 (20.0%) 14:46:31 INFO - TinderboxPrint: CPU user
1,629.4 (33.6%) 14:46:31 INFO - TinderboxPrint: Swap in / out
444,198,912 / 0 14:46:31 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 392561152; Write bytes: 385984000; Read time: 29737; Write time: 678 14:46:31 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 37000192; Write bytes: 295972352; Read time: 361; Write time: 1089 14:46:32 INFO - Running post-run listener: _upload_blobber_files 14:46:32 INFO - Blob upload gear active. 14:46:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:46:32 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 14:46:32 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', 'Larch', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:46:32 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 Larch -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:46:32 INFO - (blobuploader) - INFO - Open directory for files ... 14:46:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:46:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:46:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:33 INFO - (blobuploader) - INFO - Done attempting. 14:46:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:46:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:46:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:34 INFO - (blobuploader) - INFO - Done attempting. 14:46:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:46:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:46:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:46:36 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:46:36 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:46:36 INFO - (blobuploader) - INFO - Done attempting. 14:46:36 INFO - (blobuploader) - INFO - Iteration through files over. 14:46:36 INFO - Return code: 0 14:46:36 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:46:36 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:46:36 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/535104a2d40dd52570202dd186519cb32a36b7a5287fd790077d9417fc57d3c54fd62faf60d29163a9ba3e2311682ae885f7bd900db5376d0341f6e7bd6d8a0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f5d6ae95710aa46cddb9de4fa8faf1e0b19af7785454cebddf86773b8b53ba38b3692b2017690ba0ff7387d758e29c77d5287075a5e615d796b5117e9afb9a8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2022f23e24504fc4ea1dd3640eaa488155e361e6cdc84a8a5d41e40bfc69bc424b1964e24e73be6ed238ac146b568bf82926fcae73dc1d740a60356ddfa01c40"} 14:46:36 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:46:36 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:46:36 INFO - Contents: 14:46:36 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/535104a2d40dd52570202dd186519cb32a36b7a5287fd790077d9417fc57d3c54fd62faf60d29163a9ba3e2311682ae885f7bd900db5376d0341f6e7bd6d8a0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f5d6ae95710aa46cddb9de4fa8faf1e0b19af7785454cebddf86773b8b53ba38b3692b2017690ba0ff7387d758e29c77d5287075a5e615d796b5117e9afb9a8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2022f23e24504fc4ea1dd3640eaa488155e361e6cdc84a8a5d41e40bfc69bc424b1964e24e73be6ed238ac146b568bf82926fcae73dc1d740a60356ddfa01c40"} 14:46:36 INFO - Running post-run listener: copy_logs_to_upload_dir 14:46:36 INFO - Copying logs to upload dir... 14:46:36 INFO - mkdir: /builds/slave/test/build/upload/logs 14:46:36 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1273.073494 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-09-06 14:46:36.999204) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 14:46:37.003113) ========= 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.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/535104a2d40dd52570202dd186519cb32a36b7a5287fd790077d9417fc57d3c54fd62faf60d29163a9ba3e2311682ae885f7bd900db5376d0341f6e7bd6d8a0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f5d6ae95710aa46cddb9de4fa8faf1e0b19af7785454cebddf86773b8b53ba38b3692b2017690ba0ff7387d758e29c77d5287075a5e615d796b5117e9afb9a8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2022f23e24504fc4ea1dd3640eaa488155e361e6cdc84a8a5d41e40bfc69bc424b1964e24e73be6ed238ac146b568bf82926fcae73dc1d740a60356ddfa01c40"} build_url:https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011030 build_url: 'https://queue.taskcluster.net/v1/task/T3iwfYeoS_G3x-c55izpNg/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/535104a2d40dd52570202dd186519cb32a36b7a5287fd790077d9417fc57d3c54fd62faf60d29163a9ba3e2311682ae885f7bd900db5376d0341f6e7bd6d8a0d", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/f5d6ae95710aa46cddb9de4fa8faf1e0b19af7785454cebddf86773b8b53ba38b3692b2017690ba0ff7387d758e29c77d5287075a5e615d796b5117e9afb9a8d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Larch/sha512/2022f23e24504fc4ea1dd3640eaa488155e361e6cdc84a8a5d41e40bfc69bc424b1964e24e73be6ed238ac146b568bf82926fcae73dc1d740a60356ddfa01c40"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-09-06 14:46:37.035899) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:46:37.036225) ========= 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.KhKKum0j2v/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.Y22sob9nET/Listeners TMPDIR=/var/folders/20/njkrv4s17fqcj6nxkzq4cqm000000w/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.005278 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-09-06 14:46:37.085514) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 14:46:37.085843) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-09-06 14:46:37.086191) ========= ========= Total master_lag: 0.27 =========