builder: autoland_yosemite_r7_test-dromaeojs slave: t-yosemite-r7-0290 starttime: 1472266715.62 results: success (0) buildid: 20160826181212 builduid: a384e4c726984fea9171dcdfd594d05d revision: 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:35.621900) ========= master: http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:35.622378) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:35.622670) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:35.655401) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:35.655671) ========= 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.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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-08-26 19:58:35-- 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% 214M=0s 2016-08-26 19:58:36 (214 MB/s) - 'archiver_client.py' saved [12141/12141] program finished with exit code 0 elapsedTime=0.467764 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:36.146963) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:36.147258) ========= 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.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.025331 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:36.195532) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 19:58:36.195843) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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-08-26 19:58:36,283 truncating revision to first 12 chars 2016-08-26 19:58:36,283 Setting DEBUG logging. 2016-08-26 19:58:36,283 attempt 1/10 2016-08-26 19:58:36,283 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/1f302a3d9df0?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-08-26 19:58:37,161 unpacking tar archive at: autoland-1f302a3d9df0/testing/mozharness/ program finished with exit code 0 elapsedTime=1.135935 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-08-26 19:58:37.354311) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:37.354647) ========= script_repo_revision: 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:37.355111) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:37.355411) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-08-26 19:58:37.373950) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 19:58:37.374243) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:58:37 INFO - MultiFileLogger online at 20160826 19:58:37 in /builds/slave/test 19:58:37 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 19:58:37 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:58:37 INFO - {'append_to_log': False, 19:58:37 INFO - 'base_work_dir': '/builds/slave/test', 19:58:37 INFO - 'blob_upload_branch': 'Autoland', 19:58:37 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:58:37 INFO - 'branch': 'Autoland', 19:58:37 INFO - 'buildbot_json_path': 'buildprops.json', 19:58:37 INFO - 'config_files': ('talos/mac_config.py',), 19:58:37 INFO - 'default_actions': ('clobber', 19:58:37 INFO - 'read-buildbot-config', 19:58:37 INFO - 'download-and-extract', 19:58:37 INFO - 'populate-webroot', 19:58:37 INFO - 'create-virtualenv', 19:58:37 INFO - 'install', 19:58:37 INFO - 'run-tests'), 19:58:37 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:58:37 INFO - 'download_minidump_stackwalk': True, 19:58:37 INFO - 'download_symbols': 'ondemand', 19:58:37 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:58:37 INFO - 'tooltool.py': '/tools/tooltool.py', 19:58:37 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:58:37 INFO - '/tools/misc-python/virtualenv.py')}, 19:58:37 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:58:37 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:58:37 INFO - 'installer_path': 'installer.exe', 19:58:37 INFO - 'log_level': 'info', 19:58:37 INFO - 'log_name': 'talos', 19:58:37 INFO - 'log_to_console': True, 19:58:37 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:58:37 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:58:37 INFO - 'opt_config_files': (), 19:58:37 INFO - 'pip_index': False, 19:58:37 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:58:37 INFO - 'cmd': ('bash', 19:58:37 INFO - '-c', 19:58:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:58:37 INFO - 'enabled': True, 19:58:37 INFO - 'halt_on_failure': False, 19:58:37 INFO - 'name': 'check_screen_resolution'},), 19:58:37 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:58:37 INFO - 'cmd': ('bash', 19:58:37 INFO - '-c', 19:58:37 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:58:37 INFO - 'enabled': True, 19:58:37 INFO - 'halt_on_failure': False, 19:58:37 INFO - 'name': 'check_screen_resolution'},), 19:58:37 INFO - 'run_cmd_checks_enabled': True, 19:58:37 INFO - 'sps_profile': False, 19:58:37 INFO - 'sps_profile_interval': 0, 19:58:37 INFO - 'suite': 'dromaeojs', 19:58:37 INFO - 'system_bits': '32', 19:58:37 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:58:37 INFO - 'title': 't-yosemite-r7-0290', 19:58:37 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:58:37 INFO - 'use_talos_json': True, 19:58:37 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:58:37 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:58:37 INFO - 'work_dir': 'build'} 19:58:37 INFO - [mozharness: 2016-08-27 02:58:37.573382Z] Running clobber step. 19:58:37 INFO - Running pre-action listener: _resource_record_pre_action 19:58:37 INFO - Running main action method: clobber 19:58:37 INFO - rmtree: /builds/slave/test/build 19:58:37 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:58:39 INFO - Running post-action listener: _resource_record_post_action 19:58:39 INFO - [mozharness: 2016-08-27 02:58:39.501837Z] Finished clobber step (success) 19:58:39 INFO - [mozharness: 2016-08-27 02:58:39.502010Z] Running read-buildbot-config step. 19:58:39 INFO - Running pre-action listener: _resource_record_pre_action 19:58:39 INFO - Running main action method: read_buildbot_config 19:58:39 INFO - Using buildbot properties: 19:58:39 INFO - { 19:58:39 INFO - "project": "", 19:58:39 INFO - "product": "firefox", 19:58:39 INFO - "script_repo_revision": "production", 19:58:39 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs", 19:58:39 INFO - "repository": "", 19:58:39 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs", 19:58:39 INFO - "buildid": "20160826181212", 19:58:39 INFO - "pgo_build": "False", 19:58:39 INFO - "basedir": "/builds/slave/test", 19:58:39 INFO - "buildnumber": 141, 19:58:39 INFO - "slavename": "t-yosemite-r7-0290", 19:58:39 INFO - "master": "http://buildbot-master108.bb.releng.scl3.mozilla.com:8201/", 19:58:39 INFO - "platform": "yosemite_r7", 19:58:39 INFO - "scheduler": "tests-autoland-macosx64-talos", 19:58:39 INFO - "branch": "autoland", 19:58:39 INFO - "repo_path": "integration/autoland", 19:58:39 INFO - "revision": "1f302a3d9df01298f7e6f50333bdfdafe7dd0c00", 19:58:39 INFO - "stage_platform": "macosx64", 19:58:39 INFO - "builduid": "a384e4c726984fea9171dcdfd594d05d", 19:58:39 INFO - "slavebuilddir": "test" 19:58:39 INFO - } 19:58:39 INFO - Found installer url https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg. 19:58:39 INFO - Running post-action listener: _resource_record_post_action 19:58:39 INFO - [mozharness: 2016-08-27 02:58:39.505436Z] Finished read-buildbot-config step (success) 19:58:39 INFO - [mozharness: 2016-08-27 02:58:39.505578Z] Running download-and-extract step. 19:58:39 INFO - Running pre-action listener: _resource_record_pre_action 19:58:39 INFO - Running main action method: download_and_extract 19:58:39 INFO - mkdir: /builds/slave/test/build/tests 19:58:39 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:58:39 INFO - trying https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.test_packages.json 19:58:39 INFO - Downloading https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:39 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:40 INFO - Downloaded 1199 bytes. 19:58:40 INFO - Reading from file /builds/slave/test/build/firefox-51.0a1.en-US.mac.test_packages.json 19:58:40 INFO - Using the following test package requirements: 19:58:40 INFO - {u'common': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:58:40 INFO - u'cppunittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.cppunittest.tests.zip'], 19:58:40 INFO - u'gtest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.gtest.tests.zip'], 19:58:40 INFO - u'jittest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'jsshell-mac.zip'], 19:58:40 INFO - u'mochitest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.mochitest.tests.zip'], 19:58:40 INFO - u'mozbase': [u'firefox-51.0a1.en-US.mac.common.tests.zip'], 19:58:40 INFO - u'reftest': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.reftest.tests.zip'], 19:58:40 INFO - u'talos': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.talos.tests.zip'], 19:58:40 INFO - u'web-platform': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.web-platform.tests.zip'], 19:58:40 INFO - u'xpcshell': [u'firefox-51.0a1.en-US.mac.common.tests.zip', 19:58:40 INFO - u'firefox-51.0a1.en-US.mac.xpcshell.tests.zip']} 19:58:40 INFO - Downloading packages: [u'firefox-51.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:58:40 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:58:40 INFO - trying https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:58:40 INFO - Downloading https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:40 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:42 INFO - Downloaded 16369743 bytes. 19:58:42 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:58:49 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 19:58:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:58:49 INFO - trying https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.common.tests.zip 19:58:49 INFO - Downloading https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:51 INFO - Downloaded 16369743 bytes. 19:58:51 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.common.tests.zip to /builds/slave/test/build/tests 19:58:57 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')]} 19:58:57 INFO - trying https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.talos.tests.zip 19:58:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:58:58 INFO - Downloaded 14413998 bytes. 19:58:58 INFO - Using ZipFile to extract /builds/slave/test/build/firefox-51.0a1.en-US.mac.talos.tests.zip to /builds/slave/test/build/tests 19:58: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')]} 19:58:59 INFO - trying https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:58:59 INFO - Downloading https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-51.0a1.en-US.mac.dmg 19:58:59 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/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 19:59:02 INFO - Downloaded 111553256 bytes. 19:59:02 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:59:02 INFO - mkdir: /builds/slave/test/properties 19:59:02 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:59:02 INFO - Writing to file /builds/slave/test/properties/build_url 19:59:02 INFO - Contents: 19:59:02 INFO - build_url:https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg 19:59:03 INFO - Running post-action listener: _resource_record_post_action 19:59:03 INFO - Running post-action listener: set_extra_try_arguments 19:59:03 INFO - [mozharness: 2016-08-27 02:59:03.144792Z] Finished download-and-extract step (success) 19:59:03 INFO - [mozharness: 2016-08-27 02:59:03.144949Z] Running populate-webroot step. 19:59:03 INFO - Running pre-action listener: _resource_record_pre_action 19:59:03 INFO - Running main action method: populate_webroot 19:59:03 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:59:03 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:59:03 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:59:03 INFO - '--tppagecycles', 19:59:03 INFO - '7'], 19:59:03 INFO - 'tests': ['tsvgm']}}, 19:59:03 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:59:03 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:59:03 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:59:03 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:59:03 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:59:03 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:59:03 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['damp', 'tps']}, 19:59:03 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'tests': ['damp', 'tps']}, 19:59:03 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['dromaeo_dom']}, 19:59:03 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:59:03 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['basic_compositor_video']}, 19:59:03 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:59:03 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'other-e10s': {'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['a11yr', 19:59:03 INFO - 'ts_paint', 19:59:03 INFO - 'tpaint', 19:59:03 INFO - 'sessionrestore', 19:59:03 INFO - 'sessionrestore_no_auto_restore', 19:59:03 INFO - 'tabpaint']}, 19:59:03 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['tsvgx', 19:59:03 INFO - 'tsvgr_opacity', 19:59:03 INFO - 'tart', 19:59:03 INFO - 'tscrollx', 19:59:03 INFO - 'cart']}, 19:59:03 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:59:03 INFO - 'tsvgr_opacity', 19:59:03 INFO - 'tart', 19:59:03 INFO - 'tscrollx', 19:59:03 INFO - 'cart']}, 19:59:03 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'talos_options': ['--disable-e10s'], 19:59:03 INFO - 'tests': ['tp5o']}, 19:59:03 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'tests': ['tp5o']}, 19:59:03 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'talos_options': ['--disable-e10s', 19:59:03 INFO - '--xperf_path', 19:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:59:03 INFO - 'tests': ['tp5n']}, 19:59:03 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 19:59:03 INFO - 'talos_options': ['--xperf_path', 19:59:03 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:59:03 INFO - 'tests': ['tp5n']}}, 19:59:03 INFO - 'talos.zip': {'path': '', 19:59:03 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:59:03 INFO - Running post-action listener: _resource_record_post_action 19:59:03 INFO - [mozharness: 2016-08-27 02:59:03.164244Z] Finished populate-webroot step (success) 19:59:03 INFO - [mozharness: 2016-08-27 02:59:03.164372Z] Running create-virtualenv step. 19:59:03 INFO - Running pre-action listener: _resource_record_pre_action 19:59:03 INFO - Running main action method: create_virtualenv 19:59:03 INFO - Creating virtualenv /builds/slave/test/build/venv 19:59:03 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 19:59:03 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:59:03 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:59:03 INFO - Using real prefix '/tools/python27' 19:59:03 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:59:04 INFO - Installing distribute.............................................................................................................................................................................................done. 19:59:06 INFO - Installing pip.................done. 19:59:06 INFO - Return code: 0 19:59:06 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:59:06 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')]} 19:59:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:06 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')]} 19:59:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:06 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:06 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 19:59:06 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 19:59:06 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:06 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:06 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:06 INFO - 'HOME': '/Users/cltbld', 19:59:06 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:06 INFO - 'LOGNAME': 'cltbld', 19:59:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:06 INFO - 'MOZ_NO_REMOTE': '1', 19:59:06 INFO - 'NO_EM_RESTART': '1', 19:59:06 INFO - 'PAGER': '/bin/cat', 19:59:06 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:06 INFO - 'PWD': '/builds/slave/test', 19:59:06 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:06 INFO - 'SHELL': '/bin/bash', 19:59:06 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:06 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:06 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:06 INFO - 'USER': 'cltbld', 19:59:06 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:06 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:06 INFO - 'XPC_FLAGS': '0x0', 19:59:06 INFO - 'XPC_SERVICE_NAME': '0', 19:59:06 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:07 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 19:59:07 INFO - Cleaning up... 19:59:07 INFO - Return code: 0 19:59:07 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:59: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')]} 19:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59: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')]} 19:59:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:07 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:07 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 19:59:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 19:59:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:07 INFO - 'HOME': '/Users/cltbld', 19:59:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:07 INFO - 'LOGNAME': 'cltbld', 19:59:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:07 INFO - 'MOZ_NO_REMOTE': '1', 19:59:07 INFO - 'NO_EM_RESTART': '1', 19:59:07 INFO - 'PAGER': '/bin/cat', 19:59:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:07 INFO - 'PWD': '/builds/slave/test', 19:59:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:07 INFO - 'SHELL': '/bin/bash', 19:59:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:07 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:07 INFO - 'USER': 'cltbld', 19:59:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:07 INFO - 'XPC_FLAGS': '0x0', 19:59:07 INFO - 'XPC_SERVICE_NAME': '0', 19:59:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:07 INFO - Downloading/unpacking psutil>=3.1.1 19:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:59:11 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:59:11 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:59:11 INFO - Installing collected packages: psutil 19:59:11 INFO - Running setup.py install for psutil 19:59:11 INFO - building 'psutil._psutil_osx' extension 19:59:11 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 19:59:11 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 19:59:11 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 19:59:11 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 19:59:11 INFO - building 'psutil._psutil_posix' extension 19:59:11 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 19:59:11 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:59:11 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:59:11 INFO - ^ 19:59:11 INFO - 1 warning generated. 19:59:11 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 19:59:12 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:59:12 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:59:12 INFO - Successfully installed psutil 19:59:12 INFO - Cleaning up... 19:59:12 INFO - Return code: 0 19:59:12 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:59: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')]} 19:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59: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')]} 19:59:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:12 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:12 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 19:59:12 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 19:59:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:12 INFO - 'HOME': '/Users/cltbld', 19:59:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:12 INFO - 'LOGNAME': 'cltbld', 19:59:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:12 INFO - 'MOZ_NO_REMOTE': '1', 19:59:12 INFO - 'NO_EM_RESTART': '1', 19:59:12 INFO - 'PAGER': '/bin/cat', 19:59:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:12 INFO - 'PWD': '/builds/slave/test', 19:59:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:12 INFO - 'SHELL': '/bin/bash', 19:59:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:12 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:12 INFO - 'USER': 'cltbld', 19:59:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:12 INFO - 'XPC_FLAGS': '0x0', 19:59:12 INFO - 'XPC_SERVICE_NAME': '0', 19:59:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:12 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:14 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:59:14 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:59:15 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:59:15 INFO - Installing collected packages: mozsystemmonitor 19:59:15 INFO - Running setup.py install for mozsystemmonitor 19:59:15 INFO - Successfully installed mozsystemmonitor 19:59:15 INFO - Cleaning up... 19:59:15 INFO - Return code: 0 19:59:15 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:15 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:15 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:15 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:59:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:59:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:15 INFO - 'HOME': '/Users/cltbld', 19:59:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:15 INFO - 'LOGNAME': 'cltbld', 19:59:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:15 INFO - 'MOZ_NO_REMOTE': '1', 19:59:15 INFO - 'NO_EM_RESTART': '1', 19:59:15 INFO - 'PAGER': '/bin/cat', 19:59:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:15 INFO - 'PWD': '/builds/slave/test', 19:59:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:15 INFO - 'SHELL': '/bin/bash', 19:59:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:15 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:15 INFO - 'USER': 'cltbld', 19:59:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:15 INFO - 'XPC_FLAGS': '0x0', 19:59:15 INFO - 'XPC_SERVICE_NAME': '0', 19:59:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:15 INFO - Downloading/unpacking jsonschema==2.5.1 19:59:15 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 19:59:15 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 19:59:15 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 19:59:15 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 19:59:15 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 19:59:15 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 19:59:17 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:59:17 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:17 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:17 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:17 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:17 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:18 INFO - Downloading functools32-3.2.3-2.tar.gz 19:59:18 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:59:18 INFO - warning: no files found matching '*.txt' 19:59:18 INFO - no previously-included directories found matching 'build' 19:59:18 INFO - no previously-included directories found matching 'dist' 19:59:18 INFO - no previously-included directories found matching '.git*' 19:59:18 INFO - Installing collected packages: jsonschema, functools32 19:59:18 INFO - Running setup.py install for functools32 19:59:18 INFO - warning: no files found matching '*.txt' 19:59:18 INFO - no previously-included directories found matching 'build' 19:59:18 INFO - no previously-included directories found matching 'dist' 19:59:18 INFO - no previously-included directories found matching '.git*' 19:59:18 INFO - Successfully installed jsonschema functools32 19:59:18 INFO - Cleaning up... 19:59:18 INFO - Return code: 0 19:59:18 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:59: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')]} 19:59:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59: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')]} 19:59:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59: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', '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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59: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', 'functools32==3.2.3-2'] in /builds/slave/test/build 19:59: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 functools32==3.2.3-2 19:59:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:18 INFO - 'HOME': '/Users/cltbld', 19:59:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:18 INFO - 'LOGNAME': 'cltbld', 19:59:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:18 INFO - 'MOZ_NO_REMOTE': '1', 19:59:18 INFO - 'NO_EM_RESTART': '1', 19:59:18 INFO - 'PAGER': '/bin/cat', 19:59:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:18 INFO - 'PWD': '/builds/slave/test', 19:59:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:18 INFO - 'SHELL': '/bin/bash', 19:59:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:18 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:18 INFO - 'USER': 'cltbld', 19:59:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:18 INFO - 'XPC_FLAGS': '0x0', 19:59:18 INFO - 'XPC_SERVICE_NAME': '0', 19:59:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:19 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:59:19 INFO - Cleaning up... 19:59:19 INFO - Return code: 0 19:59:19 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:59:19 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')]} 19:59:19 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:19 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:19 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')]} 19:59:19 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:19 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:19 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:19 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 19:59:19 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 19:59:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:19 INFO - 'HOME': '/Users/cltbld', 19:59:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:19 INFO - 'LOGNAME': 'cltbld', 19:59:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:19 INFO - 'MOZ_NO_REMOTE': '1', 19:59:19 INFO - 'NO_EM_RESTART': '1', 19:59:19 INFO - 'PAGER': '/bin/cat', 19:59:19 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:19 INFO - 'PWD': '/builds/slave/test', 19:59:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:19 INFO - 'SHELL': '/bin/bash', 19:59:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:19 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:19 INFO - 'USER': 'cltbld', 19:59:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:19 INFO - 'XPC_FLAGS': '0x0', 19:59:19 INFO - 'XPC_SERVICE_NAME': '0', 19:59:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:19 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:19 INFO - Downloading/unpacking blobuploader==1.2.4 19:59:19 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 19:59:19 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 19:59:19 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 19:59:19 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 19:59:19 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 19:59:19 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 19:59:21 INFO - Downloading blobuploader-1.2.4.tar.gz 19:59:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:59:21 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:21 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:21 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:21 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:21 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:59:22 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:59:22 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 19:59:22 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 19:59:22 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 19:59:22 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 19:59:22 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 19:59:22 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 19:59:22 INFO - Downloading docopt-0.6.1.tar.gz 19:59:22 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:59:23 INFO - Installing collected packages: blobuploader, requests, docopt 19:59:23 INFO - Running setup.py install for blobuploader 19:59:23 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:59:23 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:59:23 INFO - Running setup.py install for requests 19:59:23 INFO - Running setup.py install for docopt 19:59:23 INFO - Successfully installed blobuploader requests docopt 19:59:23 INFO - Cleaning up... 19:59:23 INFO - Return code: 0 19:59:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:23 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:23 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 19:59:23 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 19:59:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:23 INFO - 'HOME': '/Users/cltbld', 19:59:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:23 INFO - 'LOGNAME': 'cltbld', 19:59:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:23 INFO - 'MOZ_NO_REMOTE': '1', 19:59:23 INFO - 'NO_EM_RESTART': '1', 19:59:23 INFO - 'PAGER': '/bin/cat', 19:59:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:23 INFO - 'PWD': '/builds/slave/test', 19:59:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:23 INFO - 'SHELL': '/bin/bash', 19:59:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:23 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:23 INFO - 'USER': 'cltbld', 19:59:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:23 INFO - 'XPC_FLAGS': '0x0', 19:59:23 INFO - 'XPC_SERVICE_NAME': '0', 19:59:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-ykH0DU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-wSvMsY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-edR7Z8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EMVq1c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-GtvOiZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Apa0X1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-m140uy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:59:24 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:59:24 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Jhz9Nr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-3a6ShR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-jorKCJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EIK2qM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-tKwNtO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Bqgolc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-czB_5H-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-ix2IPV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-RNDs7i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:59:25 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:59:25 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-OKWLTf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:59:25 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:59:25 INFO - Running setup.py install for manifestparser 19:59:26 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:59:26 INFO - Running setup.py install for mozcrash 19:59:26 INFO - Running setup.py install for mozdebug 19:59:26 INFO - Running setup.py install for mozdevice 19:59:26 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:59:26 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:59:26 INFO - Running setup.py install for mozfile 19:59:26 INFO - Running setup.py install for mozhttpd 19:59:26 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:59:26 INFO - Running setup.py install for mozinfo 19:59:26 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:59:26 INFO - Running setup.py install for mozInstall 19:59:27 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Running setup.py install for mozleak 19:59:27 INFO - Running setup.py install for mozlog 19:59:27 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Running setup.py install for moznetwork 19:59:27 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Running setup.py install for mozprocess 19:59:27 INFO - Running setup.py install for mozprofile 19:59:27 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:59:27 INFO - Running setup.py install for mozrunner 19:59:28 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:59:28 INFO - Running setup.py install for mozscreenshot 19:59:28 INFO - Running setup.py install for moztest 19:59:28 INFO - Running setup.py install for mozversion 19:59:28 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:59:28 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:59:28 INFO - Cleaning up... 19:59:28 INFO - Return code: 0 19:59:28 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:28 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:28 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 19:59:28 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 19:59:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:28 INFO - 'HOME': '/Users/cltbld', 19:59:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:28 INFO - 'LOGNAME': 'cltbld', 19:59:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:28 INFO - 'MOZ_NO_REMOTE': '1', 19:59:28 INFO - 'NO_EM_RESTART': '1', 19:59:28 INFO - 'PAGER': '/bin/cat', 19:59:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:28 INFO - 'PWD': '/builds/slave/test', 19:59:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:28 INFO - 'SHELL': '/bin/bash', 19:59:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:28 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:28 INFO - 'USER': 'cltbld', 19:59:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:28 INFO - 'XPC_FLAGS': '0x0', 19:59:28 INFO - 'XPC_SERVICE_NAME': '0', 19:59:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:28 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:59:28 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-FybuEv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:59:28 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)) 19:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:59:28 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-tTF6OM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:59:28 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)) 19:59:28 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:59:28 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-1FXB8c-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-QWitZA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-xJdhc6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-EugUpr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Nz6YIo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-ZJUCrC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-jp48V0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-T1KwUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-Dx_SKB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:59:29 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)) 19:59:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:59:29 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-WtYr0l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:59:30 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)) 19:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:59:30 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-THp783-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:59:30 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)) 19:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:59:30 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-MCy9zq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:59:30 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)) 19:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:59:30 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-5XFER1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:59:30 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)) 19:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:59:30 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-mVGJt2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:59:30 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)) 19:59:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:59:30 INFO - Running setup.py (path:/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/pip-GIZJAo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:59:30 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)) 19:59:30 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)) 19:59:30 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)) 19:59:30 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)) 19:59:30 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)) 19:59:30 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)) 19:59:30 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.3->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:30 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:30 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:59:30 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:59:30 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:59:33 INFO - Downloading blessings-1.6.tar.gz 19:59:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:59:33 INFO - Installing collected packages: blessings 19:59:33 INFO - Running setup.py install for blessings 19:59:33 INFO - Successfully installed blessings 19:59:33 INFO - Cleaning up... 19:59:33 INFO - Return code: 0 19:59:33 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:59:33 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:59:33 INFO - Reading from file tmpfile_stdout 19:59:33 INFO - Current package versions: 19:59:33 INFO - blessings == 1.6 19:59:33 INFO - blobuploader == 1.2.4 19:59:33 INFO - docopt == 0.6.1 19:59:33 INFO - functools32 == 3.2.3-2 19:59:33 INFO - jsonschema == 2.5.1 19:59:33 INFO - manifestparser == 1.1 19:59:33 INFO - mozInstall == 1.12 19:59:33 INFO - mozcrash == 0.17 19:59:33 INFO - mozdebug == 0.1 19:59:33 INFO - mozdevice == 0.48 19:59:33 INFO - mozfile == 1.2 19:59:33 INFO - mozhttpd == 0.7 19:59:33 INFO - mozinfo == 0.9 19:59:33 INFO - mozleak == 0.1 19:59:33 INFO - mozlog == 3.3 19:59:33 INFO - moznetwork == 0.27 19:59:33 INFO - mozprocess == 0.23 19:59:33 INFO - mozprofile == 0.28 19:59:33 INFO - mozrunner == 6.12 19:59:33 INFO - mozscreenshot == 0.1 19:59:33 INFO - mozsystemmonitor == 0.3 19:59:33 INFO - moztest == 0.7 19:59:33 INFO - mozversion == 1.4 19:59:33 INFO - psutil == 3.1.1 19:59:33 INFO - requests == 1.2.3 19:59:33 INFO - wsgiref == 0.1.2 19:59:33 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:33 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 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:33 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 19:59:33 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 19:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:33 INFO - 'HOME': '/Users/cltbld', 19:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:33 INFO - 'LOGNAME': 'cltbld', 19:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:33 INFO - 'MOZ_NO_REMOTE': '1', 19:59:33 INFO - 'NO_EM_RESTART': '1', 19:59:33 INFO - 'PAGER': '/bin/cat', 19:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:33 INFO - 'PWD': '/builds/slave/test', 19:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:33 INFO - 'SHELL': '/bin/bash', 19:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:33 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:33 INFO - 'USER': 'cltbld', 19:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:33 INFO - 'XPC_FLAGS': '0x0', 19:59:33 INFO - 'XPC_SERVICE_NAME': '0', 19:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:33 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 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)) 19:59:33 INFO - Cleaning up... 19:59:33 INFO - Return code: 0 19:59:33 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:33 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:59:33 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:33 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:59:33 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:59:33 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:59:33 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c83ca30>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c8d1618>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe8b3458350>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, '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.iHTWVpw1I4/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.izKepz8ax0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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 19:59:33 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:59:33 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:59:33 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 19:59:33 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:59:33 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:59:33 INFO - 'HOME': '/Users/cltbld', 19:59:33 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:59:33 INFO - 'LOGNAME': 'cltbld', 19:59:33 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:59:33 INFO - 'MOZ_NO_REMOTE': '1', 19:59:33 INFO - 'NO_EM_RESTART': '1', 19:59:33 INFO - 'PAGER': '/bin/cat', 19:59:33 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:59:33 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:59:33 INFO - 'PWD': '/builds/slave/test', 19:59:33 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:59:33 INFO - 'SHELL': '/bin/bash', 19:59:33 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 19:59:33 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 19:59:33 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:59:33 INFO - 'USER': 'cltbld', 19:59:33 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:59:33 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:59:33 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:59:33 INFO - 'XPC_FLAGS': '0x0', 19:59:33 INFO - 'XPC_SERVICE_NAME': '0', 19:59:33 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:59:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:59:34 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:59:34 INFO - Cleaning up... 19:59:34 INFO - Return code: 0 19:59:34 INFO - Running post-action listener: _resource_record_post_action 19:59:34 INFO - Running post-action listener: _start_resource_monitoring 19:59:34 INFO - Starting resource monitoring. 19:59:34 INFO - [mozharness: 2016-08-27 02:59:34.262937Z] Finished create-virtualenv step (success) 19:59:34 INFO - [mozharness: 2016-08-27 02:59:34.263944Z] Running install step. 19:59:34 INFO - Running pre-action listener: _resource_record_pre_action 19:59:34 INFO - Running main action method: install 19:59:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:59:34 INFO - Reading from file tmpfile_stdout 19:59:34 INFO - Detecting whether we're running mozinstall >=1.0... 19:59:34 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:59:34 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:59:34 INFO - Reading from file tmpfile_stdout 19:59:34 INFO - Output received: 19:59:34 INFO - Usage: mozinstall [options] installer 19:59:34 INFO - Options: 19:59:34 INFO - -h, --help show this help message and exit 19:59:34 INFO - -d DEST, --destination=DEST 19:59:34 INFO - Directory to install application into. [default: 19:59:34 INFO - "/builds/slave/test"] 19:59:34 INFO - --app=APP Application being installed. [default: firefox] 19:59:34 INFO - mkdir: /builds/slave/test/build/application 19:59:34 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'] 19:59:34 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 20:00:08 INFO - Reading from file tmpfile_stdout 20:00:08 INFO - Output received: 20:00:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 20:00:08 INFO - Running post-action listener: _resource_record_post_action 20:00:08 INFO - [mozharness: 2016-08-27 03:00:08.794966Z] Finished install step (success) 20:00:08 INFO - [mozharness: 2016-08-27 03:00:08.795102Z] Running run-tests step. 20:00:08 INFO - Running pre-action listener: _resource_record_pre_action 20:00:08 INFO - Running main action method: run_tests 20:00:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:00:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:00:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:00:08 INFO - 2016-08-26 20:00:08.809 screenresolution[1641:8733] starting screenresolution argv=screenresolution get 20:00:08 INFO - 2016-08-26 20:00:08.812 screenresolution[1641:8733] Display 0: 1600x1200x32@60 20:00:08 INFO - 2016-08-26 20:00:08.818 screenresolution[1642:8736] starting screenresolution argv=screenresolution list 20:00:08 INFO - Available Modes on Display 0 20:00:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:00:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:00:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:00:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:00:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:00:09 INFO - Intel Iris: 20:00:09 INFO - Chipset Model: Intel Iris 20:00:09 INFO - Type: GPU 20:00:09 INFO - Bus: Built-In 20:00:09 INFO - VRAM (Dynamic, Max): 1536 MB 20:00:09 INFO - Vendor: Intel (0x8086) 20:00:09 INFO - Device ID: 0x0a2e 20:00:09 INFO - Revision ID: 0x0009 20:00:09 INFO - Displays: 20:00:09 INFO - Display: 20:00:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:00:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:00:09 INFO - Display Serial Number: 1600x1200 60 20:00:09 INFO - Main Display: Yes 20:00:09 INFO - Mirror: Off 20:00:09 INFO - Online: Yes 20:00:09 INFO - Rotation: Supported 20:00:09 INFO - Return code: 0 20:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:00:09 INFO - Python 2.7.3 20:00:09 INFO - Return code: 0 20:00:09 INFO - grabbing minidump binary from tooltool 20:00:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:00:09 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 0x10c7a0d30>, '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 0x10c505db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10c798b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:00:09 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 20:00:09 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 20:00:09 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 20:00:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:00:09 INFO - Return code: 0 20:00:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:00:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:00:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:00:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:00:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:00:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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 20:00:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0290 --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 20:00:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.iHTWVpw1I4/Render', 20:00:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:00:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:00:09 INFO - 'HOME': '/Users/cltbld', 20:00:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:00:09 INFO - 'LOGNAME': 'cltbld', 20:00:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:00:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:00:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:00:09 INFO - 'MOZ_NO_REMOTE': '1', 20:00:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:00:09 INFO - 'NO_EM_RESTART': '1', 20:00:09 INFO - 'PAGER': '/bin/cat', 20:00:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:00:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:00:09 INFO - 'PWD': '/builds/slave/test', 20:00:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:00:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:00:09 INFO - 'SHELL': '/bin/bash', 20:00:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.izKepz8ax0/Listeners', 20:00:09 INFO - 'TMPDIR': '/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/', 20:00:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:00:09 INFO - 'USER': 'cltbld', 20:00:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:00:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:00:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:00:09 INFO - 'XPC_FLAGS': '0x0', 20:00:09 INFO - 'XPC_SERVICE_NAME': '0', 20:00:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:00:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0290', '--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 20:00:09 INFO - mozversion application_buildid: 20160826181212 20:00:09 INFO - mozversion application_changeset: 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 20:00:09 INFO - mozversion application_display_name: Nightly 20:00:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:00:09 INFO - mozversion application_name: Firefox 20:00:09 INFO - mozversion application_remotingname: firefox 20:00:09 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 20:00:09 INFO - mozversion application_vendor: Mozilla 20:00:09 INFO - mozversion application_version: 51.0a1 20:00:09 INFO - mozversion platform_buildid: 20160826181212 20:00:09 INFO - mozversion platform_changeset: 1f302a3d9df01298f7e6f50333bdfdafe7dd0c00 20:00:09 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 20:00:09 INFO - mozversion platform_version: 51.0a1 20:00:09 INFO - using testdate: 1472266809 20:00:09 INFO - actual date: 1472266809 20:00:09 INFO - starting webserver on 'localhost:49267' 20:00:09 INFO - SUITE-START | Running 2 tests 20:00:09 INFO - TEST-START | dromaeo_css 20:00:09 INFO - operating with platform_type : mac_ 20:00:09 INFO - Initialising browser for dromaeo_css test... 20:00:09 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpUT0_Cz/profile http://localhost:49267/getInfo.html) 20:00:11 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 20:00:11 INFO - PROCESS | 1652 | colorDepth:24 20:00:11 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 20:00:11 INFO - PROCESS | 1652 | __metrics 20:00:11 INFO - PROCESS | 1652 | 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" 20:00:11 INFO - PROCESS | 1652 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:00:12 INFO - TEST-INFO | 1652: exit 0 20:00:12 INFO - Browser initialized. 20:00:12 INFO - Running cycle 1/1 for dromaeo_css test... 20:00:12 INFO - TEST-INFO | started process 1655 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpUT0_Cz/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:00:24 INFO - PROCESS | 1655 | RSS: Main: 233160704 20:00:24 INFO - PROCESS | 1655 | 20:01:15 INFO - PROCESS | 1655 | 1472266875704 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] 20:03:27 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49267/tests/dromaeo/cssquery-ext.html) 20:03:28 INFO - PROCESS | 1655 | RSS: Main: 375668736 20:03:28 INFO - PROCESS | 1655 | 20:06:31 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-ext.html (next: http://localhost:49267/tests/dromaeo/cssquery-jquery.html) 20:06:32 INFO - PROCESS | 1655 | RSS: Main: 375472128 20:06:32 INFO - PROCESS | 1655 | 20:09:34 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49267/tests/dromaeo/cssquery-mootools.html) 20:09:35 INFO - PROCESS | 1655 | RSS: Main: 342892544 20:09:35 INFO - PROCESS | 1655 | 20:12:37 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49267/tests/dromaeo/cssquery-prototype.html) 20:12:38 INFO - PROCESS | 1655 | RSS: Main: 339308544 20:12:38 INFO - PROCESS | 1655 | 20:15:41 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49267/tests/dromaeo/cssquery-yui.html) 20:15:42 INFO - PROCESS | 1655 | RSS: Main: 328867840 20:15:42 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | Cycle 1(1): loaded http://localhost:49267/tests/dromaeo/cssquery-yui.html (next: http://localhost:49267/tests/dromaeo/cssquery-dojo.html) 20:18:45 INFO - PROCESS | 1655 | RSS: Main: 446803968 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | __start_tp_report 20:18:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load 20:18:45 INFO - PROCESS | 1655 | _x_x_mozilla_page_load_details 20:18:45 INFO - PROCESS | 1655 | |i|pagename|runs| 20:18:45 INFO - PROCESS | 1655 | |0;dojo.html;3333.89998300051;3363.7309015278774;3370.915727106822;3417.5386322846407;3438.8796392126283;5651.886962260753;5684.65892046477;5768.278965129359;5782.942170578293;5786.913196302054;5448.45515448455;5720.170575266584;5749.770009199638;5809.302883653967;5823.708814559276;5176.689398636094;5212.244224587433;5242.711650859202;5268.1834315681135;5388.110961691316;5342.732863356836;5364.168553874136;5388.91916621251;5397.217403476494;5428.972855135738;5201.869953251161;5224.164133738584;5259.526642602146;5262.236975638511;5306.230596563477;5227.895442091137;5244.344456942882;5267.473252674702;5273.499017593323;5356.91964620531;5227.581793456515;5262.473752624745;5275.947240527584;5286.577073834084;5312.601554883399;5632.971835140839;5678.949394362015;5800.622959507661;5810.7385167667135;5815.941840581582;6188.071789231606;6254.6247225166635;6261.279952805395;6288.779892703779;6311.400416960381;6262.812115636538;6333.113364129025;6346.809595712136;6353.047042943549;6359.4594459471;5722.856928576819;5862.179294898675;5865.35481097079;5877.147813567009;5881.705914704247;6258.874822503615;6286.811395658137;6319.96840015797;6322.146510221061;6356.110144579762;6175.938240617536;6183.598066125777;6202.069689546604;6202.906956395657;6204.565680402328;6228.5951413158;6258.718357673915;6314.210723659543;6325.304216536178;6327.209098862642;6040.637561746222;6146.6004709693725;6148.8770224595255;6215.160953271341;6228.968855155696;5151.5106064923775;6272.153259310026;6279.183706118175;6300.684965751786;6319.58922670025;4997.650164488451;5019.096562618691;5019.974900125476;5031.396232452056;5051.823186188466;4402.119576084796;4416.977915110404;4494.842680506166;4527.728336299832;4559.338895860148;4834.758262086882;5698.1737648041635;5734.856628584235;5776.220210271644;5777.653340799566;6216.129741836146;6256.655883926337;6276.968615156895;6286.8428289292215;6306.716197771019;5811.506021988093;5825.533957283407;5832.62087964281;5833.300003999547;5849.707514624251;5489.286392768914;5500.3949565547755;5502.8349149525575;5512.641678290898;5514.338279406497;6269.0909818076125;6274.152989346379;6286.811395658137;6294.905576416266;6379.075034120119;6252.718627661857;6300.621962682254;6329.145565348619;6330.3669633036325;6348.619082854859;6384.425401713868;6388.552801303864;6392.488600911823;6397.904031439625;6404.839879003062;6116.305337585956;6116.755329786936;6124.472479235402;6135.477655820289;6221.06683997404;6294.0558916162945;6295.496360291074;6300.873982520416;6301.527385446201;6308.558400911892;6130.63216207029;6143.324234334309;6144.662043587646;6153.907691384543;6155.969220153871;5906.320773111147;6065.969670151621;6067.211262535842;6095.695215239309;6115.266168059861;5918.319393269829;5956.374580668968;5986.55100867428;5991.6405015697455;6009.098635204579;6127.417895299939;6152.876942461036;6156.445919867234;6189.288231853395;6190.442860142609;5724.141378793048;5790.884182316414;5870.735816888165;5893.263342082239;5949.494292985145 20:18:45 INFO - PROCESS | 1655 | |1;ext.html;467.2850538675827;475.33215831756394;510.2626704412124;526.4524894110126;533.3839415475127;31719.841400792993;32183.67816321836;40331;41789.7910510447;41793;43090.569094309125;43361.783191084076;43487;43520.347194792106;43536.56463435365;9812.362196457225;10052.296339256254;10055.446950417723;10061.396316221013;10061.849072263922;8814.735557933273;9045.683401080963;12054.758904821898;12377.25736455811;12413.44139513724;12261.080418968568;12362.629121126336;12363.938180309086;12379.938100309486;12380.442880070419;12250.081243906698;12347.567835125728;12357.814632780515;12361.567345142921;12379.195352302071;10525.894741052569;11352.48913798881;11745.588904388305;11765.705857353549;11825.526978920832;40197.39703904444;40436.79781601103;40479;40479.19041619151;40492;3365.0914253151755;3431.262278610086;3705.4997575327257;3855.768653880748;4052.128792309638;30918.845405773052;31166;31813.52279715806;31843.681563184302;32123.83938080318;3197.9200519987185;3266.738660907122;3328.284418849935;3335.8332083395735;3367.478040903664;500092;500255;500859.99999999633;501938;506408.99999999633;53295.739160430196;53426;53512.19731704028;53537.73231133819;53538.73230633822;61120;61212.08181877276;61213.693931530055;61291.693541532004;61462.38537614656;15981.44064957721;16168.595785105466;16188.75716864248;16209.594760130854;16241.2691428886;11345.31928084301;11559.017483513828;12612.81080783789;12673.366331683379;12673.49306027767;14874.776878346833;15563.533094007198;15779.368825246862;15988;16095.839041609668;6226.688665566703;6267.5299352548745;6284.3154744206995;6342.651154186564;6368.522360822957;869.4001139213922;940.238406890421;965.8502932045544;1025.1542477456128;1056.366180291834;28627.856860715565;29565.704342956724;29717.257068573457;29879.253018674706;30363;909.7543663210896;991.3902949685892;1074.2050882347007;1086.2070688397357;1124.0951034417374;15010.624734381729;15522.456714014956;15610.45363412275;15938.68122637564;15951.122688252026;658641;915264;928282;928600;930511;55935.44064559302;56758;56766.43233567694;56874.14688779671;56934.430655692915;1220.4812954494128;1252.0797214047486;1342.4294674763187;1365.7746471832236;1387.6322774464775;26998.730012699623;28992.565111522923;29101.399423031937;31207.375852483277;31938.840305798323;52475.425737227946;52978;53006;53061.20408193958;53071;562239;567183;567423.4886476789;567563;568252;22910.198143064914;25332.746672533776;25737.227883163534;25753.356166095997;26158.86920565385;37323.62676373201;43771.56228437675;46126.30810537912;47379;48861.022779544925;34385.656143438246;34890;34999.950001499994;37819.59623633776;37899.621003790715;38082.2383552333;38100.809495952344;39042.316814380225;39218.411723824735;39506.802465987486;38023.42964855474;38365.424518632804;38626;38766;38858.4171237426;32231.194220144684;33601.15997100092;34261.657383425845;34372.96881093571;34375.484367734;24242.515149696555;25090;25744;25907.611385829605;26514.734852651225 20:18:45 INFO - PROCESS | 1655 | |2;jquery.html;363.9599644039157;368.4638850472562;378.5419642232898;385.4026259298087;397.7414680457702;19814;21890.154279943585;22235.888820555916;22436.55126897461;22538.54922901543;17817.821821781778;21214.469638259052;21269;21491;21783.45541361467;5819.62172458791;5961.612495187821;6115.327313995457;6192.938070619303;6218.906716399258;5852.76588936442;6147.661878596676;6192.690365481731;6201.627902325852;6202.255729312489;5572.5511367044655;5937.554683398741;6176.227971503562;6240.5319601030105;6251.906221406683;5962.582619216678;6124.050772130327;6200.658963756991;6215.254169499666;6227.626342419469;6007.939920600782;6009.038553831409;6022.6687532185715;6025.879482410327;6037.637741735466;12872.742545149138;13027.348632568333;13154.934225328812;13166.473341066347;13358.39887205078;2123.235635171337;2225.410266279437;2462.4336402627496;2937.221636266391;3013.668496465387;10212;12563.994880409546;14876.256187190596;15359.308831102515;15535.990160639521;1799.973000404995;1859.2562974810048;1884.660761063008;1905.9803005392048;1909.93043895419;110676.4466177664;118810.43405634598;120705;126576;134999.32500337533;10983.835242471368;11251.156163287786;11410.429478526206;11420.94289528547;11588.88411115878;14403.639909002359;14404.927975360057;14425.350859211361;14431.567052988426;14444.133351998913;8143.75568732939;8376.188903078186;8410.621522031523;8493.895793546923;8546.273566746771;8346.332293416634;8348.749537513884;8358.038825535019;8505.82988340242;8508.234258916727;4375.16871794358;4447.221736196153;4462.821487140543;4476.306172543196;4534.166146158651;1600.5198440467814;1740;1875.9624807503774;1881.3697411367345;1884.8020957799508;851.3061854588491;864.7103220421229;933.0203286549096;952.7951490429591;965.8454647256406;20703;21788.346349609536;21798.782012179992;21919.342419727433;22005.559888802134;958.2238386906628;967.3180407812475;983.6114734679761;993.980120397588;1000.6947880896397;10142.847857282146;10171.64399246023;10997.065249453886;11532.538698452136;11576.594819181457;470265;472013;488312.55843721266;572646;585977;87557;92108.08974506229;130776;132386;135394;969.8642190093393;1017.0947856407803;1029.1098200057047;1036.124474818777;1036.233187441288;18591.72112418342;20024.599508009465;20036.098375573427;20053.498662533555;20175.59648807045;52520.47479525156;57768.422315776304;57924.393402296155;58512.70743646425;58912.23263302108;77417;80743.59628201822;85101.40522598034;85348;85983;7546.434017448603;7789.922100779147;7866.685332586632;8203.71287004952;8237.670493180205;10806.621768238074;12102.273863568214;12622.684432889251;12641.051921106138;12817.615471535868;10121.898781012096;10651.573937042742;10802.297850639683;10851.294665846695;10853.837192442277;10684.83972740394;10958.12335013213;10989.835152472879;11131.27646702994;11170.776584468103;9800.921898591007;10648.680539583826;10998.560057597926;11064.723381915195;11231.943840280746;7825.686972521035;7957.442978991647;7977.7207797726805;8257.298129659046;8324.625391857502;9858.507074646383;10993.505292262027;11254.987051165106;11258.38078905668;11317.320960742381 20:18:45 INFO - PROCESS | 1655 | |3;mootools.html;1288.8066789981501;1535.6851845371696;1668.6746084513518;1864.4593068010279;1893.3941138835578;8176.097485392682;8667.176618221267;8676.609552570133;8692.304615630734;8698.478091314517;7599.582022988733;7829.725959591416;8282.254597086261;8463.153684631534;8524.701635442761;2343.753905839888;2378.2508509819436;2379.892904819287;2380.1550449590404;2385.3678775124604;1974.427416049344;2339.1929784224417;2344.6951896253463;2365.077619728307;2388.6297623868327;2363.7399886012527;2374.845635033726;2390.091765129257;2397.652340410639;2402.651615515749;1976.5849171673822;2351.494428697821;2351.6354964980455;2357.116081469449;2358.752331005237;1944.6694062009492;2303.3550605830396;2355.4229213842546;2364.309518694142;2365.727941286757;8566.48601083937;8657.134286571356;8658.177473140104;8658.523781192032;8674.392792504526;5086;5249.632525723201;5261.263423120766;5266.868328291824;5274.314339135926;2819.887204511817;3512.127097963031;3642.9817850910576;3653;3664.303782281358;1035.8964103589656;1078.0567003871613;1080.9405482698448;1099.0383414512303;1100.3178029621686;11072.723181920435;11191.832122518168;11202.551897924073;11228.214025018253;11232.943835280772;4462.509123996358;4519.367288579601;4712.949866807881;4727.929081063787;4730.172219861511;9669.371490853073;9676.951615241878;9679;9683.612655493702;9689.031096890254;5251.343582052244;5259.395169555473;5261.447548007481;5261.552768014685;5262.421133675292;3927.6857851371824;4337.501187363494;4489.461264648263;4832.903341933142;4837.637177211724;3824.0057585028094;3828.6362795534938;3936.3111455495173;3965.980170099131;3984.900377490586;1256.9874301257053;1272.4592048379402;1276.2980360801523;1285.9228446293253;1290.161395093197;845.7674139611534;857.1813917708573;859.3254295378098;861.8707193920836;960.706984369774;5461.563074953994;5666.943330566724;5859.824205273849;5882.764689412461;5987.670678112659;350.3466035843134;423.57642357642357;436.2409407630763;444.3090993505066;445.3675780391787;3914.628110329514;3917.1186483041656;4013.4983127109113;4025.295573274665;4067.7966101694797;9993.350432221881;10392.688219353431;10403.739906502398;10417.791644167226;10418;10360.689179324632;10379.065884070318;10384.169266458664;10393.792124157475;10400.58397664085;7388.408927285805;7673.34776544;7848.489848159966;7909.52542847431;7984.560849153352;5852.092925596539;5859.882802344015;5957.582969192288;6001.789937352171;6071.453569178795;9615.951920240354;9747.561359738687;9763.658271960447;9771.706848794547;9784.853227201455;9417.764555885884;9712.417254964725;9801.9019809801;9852.408855468695;9952.850707239542;6744.494162937897;6983.231844497;7062.15254169503;7161.928380716335;7249.166345870081;8126.106128325761;8176.591170441573;8280.213379729157;8297.128801475757;8308.252257296872;7275.054242948383;7361.889571656537;7404.185539590749;7446.627668616656;7522.548647081193;7151.928480715126;7208.747693830691;7211.459140564583;7242.058532390755;7282.12614486265;6859.862802744017;6875.656217189221;6919.896201557081;6930.7227710891;6942.757003504853;4641.236330194504;4654.48800631937;4664.626829853536;4715.316279139574;4800.303955926302;3679.3181648266113;4885.4870238624435;5026.572741317029;5074.797008119634;5203.19350500673 20:18:45 INFO - PROCESS | 1655 | |4;prototype.html;240.70032809152605;261.419648380595;296.6899589928526;308.9057837359604;312.85764976935496;4408.911821763563;5271.736413179345;5281.498257665526;5321.164198351572;5321.680699158049;4310.482742070948;4542.182407166709;4944.736473927209;5227.764750586223;5293.612088263514;4059.776712280816;4084;4103.604774376711;4107.260693075245;4122.4640796696385;4074.775887326196;4092.7517107282265;4100.876973690794;4104.384342348641;4109.815058322368;3352.849121789513;3751.6248375162404;3795.6394142556414;4054.574269701683;4078.8572399966006;3342.498625206214;4017.578154293786;4032.9395059074136;4041.939370909439;4064.3539366556442;2884.6971068037765;2887.5091234490187;3112.8754849805982;3521.5774107107054;3521.8239088045493;4245.448091748084;4462.776861156962;4866.46468888422;5099.515546023159;5161.406438259611;4604.815807367701;4607.976960115211;4630.333080091164;4630.629549636021;4641.559051890065;5004.84694642373;5491.203775452537;5519.475649813261;5553.16702494625;5574.2441131044;3866.9226615467533;3868.168343806095;3901.375779875235;3906.3554513505237;3912.5304963404574;5142.794288228466;5494.2857428534435;5674.460926212003;5784.566157538202;5814.447627475383;3903.512060992376;3933.193695292458;3933.370660694275;3954.4661470701662;3954.9011274718364;3964.306246406867;4011.8395264189107;4020.778857162884;4022.6379625833815;4055.229506393776;3935.5670876203594;4157.625813676784;4561.2473941799235;4591.862244132682;4646.326282688991;4622.6301895848255;5023.246513023003;5428.4028756836715;5578.716895114147;5580.246666700024;1134.4157758754247;1335.9666008349868;1340.6514306280242;1341.557286095586;1343.1605246720799;804.9053287528956;830.1449507007792;860.2644738748405;861.3152543727751;861.7888617288679;4663.347131401607;4781.115493633689;4783.115123702126;5304.363476382859;5396.946030539723;5042.571381432619;5747.5689323300085;5791.291568987074;5804.767809287666;5811.418858114239;4619;5094.001209050877;5102.489751024942;5294.867628309246;5331.280277162611;2529.5455113309554;3004.7145521175453;3016.3967206558777;3032.6815684352823;3043.3304672971913;5016.673916195436;5023.497650234947;5718.542516598662;5947;5953.732082056318;4975.676581022222;5817;5833.854153646108;5864.589478736533;5866.911996320058;4838.491958344393;5936.703164841827;5950.553708471882;5974.850628734316;5978.252718410199;4837.7581120944515;4992.675476094042;5076.670016449067;5444.972775136257;5671.0359238930305;5426.620136590401;5606.915896261477;5642.492175704207;5659.377468478382;5668.376478587435;4900.901981960412;5143.884892086241;5943.851403714942;5950.732217050157;5973.581849270509;2907.447584958851;3481.9728180187412;3806.9619303807717;3895.6299151580556;3921.31377009028;5265.368155821326;5624.521915637217;5653.773849045992;5740.703718512628;5800.4489573490455;4863.756812159449;5650.63270887391;5669.489745922887;5686.09022556389;5693.5160511357;4703.165765351448;5024.397072351341;5558.499735023867;5584.86037849057;5598.160275958476;4232.64022558086;5212.270282160576;5308.973455132699;5446.673199608036;5473.425290344457;4745.667803253739;5050.419201791694;5203.479652034766;5207.3490813648295;5447.863803404788;4530.70550414222;4586.518415566451;5146.7390489330355;5430.185472179205;5486.369067557282 20:18:45 INFO - PROCESS | 1655 | |5;yui.html;718.8957601147833;722.4215090028285;769.3960241210651;800.6637212370804;809.5062012172574;15309.54071377859;15865.762013569805;15877.603059923478;15900.204989750524;15905.920470397647;14208.076475029116;14304.35630396632;14304.998650094472;14320.212388318638;14327.498537551188;651.9217693876741;669.6183175589915;822.596927505521;829.5852073963003;854.9230569248766;641.8780431717983;839.5508403004394;843.0936743001298;843.5360551696542;848.0417128644654;894.4812009034745;895.6596493332524;897.1656359585583;897.2507955856835;897.3583887520422;890.4657205676607;890.6838072484304;892.1658249536704;893.5934149961752;893.6916763716507;515.9045576568346;657.7829316325601;671.0437626382386;675.3415419965544;678.6572780745742;14735.336909838976;14773.261336933218;14784.556463306119;14807.777883331759;14832.332545035499;11958.342291173982;12526.872581467711;13368.933155334258;13850.515231966832;14108.012439129265;12474.688132796662;12880.677983050498;12897.742045159044;12979.41592628334;13510.392032358568;4446.555344465528;4502;4558.931616025762;4623.214053610895;4645.233536466479;98153;113117;118304.40847795705;123297;123387;10734.409607471664;10783.784324313627;11163.441827908571;11393.544258229744;11412.885871141349;22301.219457318915;22312.88843555804;22333.88833055857;22387.104515819185;22440.66339004916;7690.230976902265;7986.800329991681;8022.759317220492;8097.392695547857;8108.108108108104;4222.577742225814;4278.465191851019;4417.757023363746;4466.865994020184;4514.503404625489;3456.4469684850305;3528.770629909048;3569.39320315547;3571.357155711945;3575.856965721394;541.4071591607184;638.084348959245;653.9640319782362;707.0737334092355;709.3544874164486;624.865653884417;645.2901808011151;664.8437617159964;674.2381109346505;683.2975581574859;8515.318774498026;9057.77355566116;9153.496557689392;9384.296177786557;9724.513774311255;490.45314474360833;593.8812237552506;639.1563136659657;676.681959479044;685.4995853027218;4674.883127921829;4677.976610116995;4776.665633405628;4813.307731338213;4836.951630483721;108224;118235;160343;167076.16461917857;171652;112564.43717781358;141321;142729;146824.26587866992;149566.25216874064;121262;124738;124891.75541223084;125411;127968.36015819861;8910.777230569287;9172.44965302084;9203.40177888462;9248.95375523145;9309.146586994206;47458.76270618625;55292.44707552873;65560.01659975009;65604;67631;96926;102545.4363640915;102938.48530757298;102969;107574.92425075649;11047.337159770439;11391.658250252505;11717.296962182296;12103.515859365527;12314.076444266537;12657.493700251887;12705;13172.341382930623;14586.416543338572;15287.694246115238;13898.861011390161;14125;14395.280235988368;14412.207328597027;14925.253737312874;11449.828252576384;14080.15519068859;14413.85586144125;14652.706945861237;14875.702485950436;12964.675883102998;13578.524751633646;13627.114237574526;14033.929830350782;14476.348564314421;10997.61508347204;11286.322820630789;11467.139964502863;11575.536978521102;11731.824022639497;11344.291602522982;11647.71023647221;11729.178957472895;11799.292042477478;11823.52705891789 20:18:45 INFO - PROCESS | 1655 | __end_tp_report 20:18:45 INFO - PROCESS | 1655 | __start_cc_report 20:18:45 INFO - PROCESS | 1655 | _x_x_mozilla_cycle_collect,1214 20:18:45 INFO - PROCESS | 1655 | __end_cc_report 20:18:45 INFO - PROCESS | 1655 | __startTimestamp1472267925975__endTimestamp 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | ------- Summary: start ------- 20:18:45 INFO - PROCESS | 1655 | Number of tests: 6 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#0] dojo.html Cycles:165 Average:5780.73 Median:5949.49 stddev:634.65 (10.7%) stddev-sans-first:606.88 20:18:45 INFO - PROCESS | 1655 | Values: 3333.9 3363.7 3370.9 3417.5 3438.9 5651.9 5684.7 5768.3 5782.9 5786.9 5448.5 5720.2 5749.8 5809.3 5823.7 5176.7 5212.2 5242.7 5268.2 5388.1 5342.7 5364.2 5388.9 5397.2 5429.0 5201.9 5224.2 5259.5 5262.2 5306.2 5227.9 5244.3 5267.5 5273.5 5356.9 5227.6 5262.5 5275.9 5286.6 5312.6 5633.0 5678.9 5800.6 5810.7 5815.9 6188.1 6254.6 6261.3 6288.8 6311.4 6262.8 6333.1 6346.8 6353.0 6359.5 5722.9 5862.2 5865.4 5877.1 5881.7 6258.9 6286.8 6320.0 6322.1 6356.1 6175.9 6183.6 6202.1 6202.9 6204.6 6228.6 6258.7 6314.2 6325.3 6327.2 6040.6 6146.6 6148.9 6215.2 6229.0 5151.5 6272.2 6279.2 6300.7 6319.6 4997.7 5019.1 5020.0 5031.4 5051.8 4402.1 4417.0 4494.8 4527.7 4559.3 4834.8 5698.2 5734.9 5776.2 5777.7 6216.1 6256.7 6277.0 6286.8 6306.7 5811.5 5825.5 5832.6 5833.3 5849.7 5489.3 5500.4 5502.8 5512.6 5514.3 6269.1 6274.2 6286.8 6294.9 6379.1 6252.7 6300.6 6329.1 6330.4 6348.6 6384.4 6388.6 6392.5 6397.9 6404.8 6116.3 6116.8 6124.5 6135.5 6221.1 6294.1 6295.5 6300.9 6301.5 6308.6 6130.6 6143.3 6144.7 6153.9 6156.0 5906.3 6066.0 6067.2 6095.7 6115.3 5918.3 5956.4 5986.6 5991.6 6009.1 6127.4 6152.9 6156.4 6189.3 6190.4 5724.1 5790.9 5870.7 5893.3 5949.5 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#1] ext.html Cycles:180 Average:76485.20 Median:34380.57 stddev:180412.35 (524.8%) stddev-sans-first:180828.17 20:18:45 INFO - PROCESS | 1655 | Values: 467.3 475.3 510.3 526.5 533.4 31719.8 32183.7 40331.0 41789.8 41793.0 43090.6 43361.8 43487.0 43520.3 43536.6 9812.4 10052.3 10055.4 10061.4 10061.8 8814.7 9045.7 12054.8 12377.3 12413.4 12261.1 12362.6 12363.9 12379.9 12380.4 12250.1 12347.6 12357.8 12361.6 12379.2 10525.9 11352.5 11745.6 11765.7 11825.5 40197.4 40436.8 40479.0 40479.2 40492.0 3365.1 3431.3 3705.5 3855.8 4052.1 30918.8 31166.0 31813.5 31843.7 32123.8 3197.9 3266.7 3328.3 3335.8 3367.5 500092.0 500255.0 500860.0 501938.0 506409.0 53295.7 53426.0 53512.2 53537.7 53538.7 61120.0 61212.1 61213.7 61291.7 61462.4 15981.4 16168.6 16188.8 16209.6 16241.3 11345.3 11559.0 12612.8 12673.4 12673.5 14874.8 15563.5 15779.4 15988.0 16095.8 6226.7 6267.5 6284.3 6342.7 6368.5 869.4 940.2 965.9 1025.2 1056.4 28627.9 29565.7 29717.3 29879.3 30363.0 909.8 991.4 1074.2 1086.2 1124.1 15010.6 15522.5 15610.5 15938.7 15951.1 658641.0 915264.0 928282.0 928600.0 930511.0 55935.4 56758.0 56766.4 56874.1 56934.4 1220.5 1252.1 1342.4 1365.8 1387.6 26998.7 28992.6 29101.4 31207.4 31938.8 52475.4 52978.0 53006.0 53061.2 53071.0 562239.0 567183.0 567423.5 567563.0 568252.0 22910.2 25332.7 25737.2 25753.4 26158.9 37323.6 43771.6 46126.3 47379.0 48861.0 34385.7 34890.0 35000.0 37819.6 37899.6 38082.2 38100.8 39042.3 39218.4 39506.8 38023.4 38365.4 38626.0 38766.0 38858.4 32231.2 33601.2 34261.7 34373.0 34375.5 24242.5 25090.0 25744.0 25907.6 26514.7 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#2] jquery.html Cycles:180 Average:32665.35 Median:12330.98 stddev:87650.02 (710.8%) stddev-sans-first:87862.35 20:18:45 INFO - PROCESS | 1655 | Values: 364.0 368.5 378.5 385.4 397.7 19814.0 21890.2 22235.9 22436.6 22538.5 17817.8 21214.5 21269.0 21491.0 21783.5 5819.6 5961.6 6115.3 6192.9 6218.9 5852.8 6147.7 6192.7 6201.6 6202.3 5572.6 5937.6 6176.2 6240.5 6251.9 5962.6 6124.1 6200.7 6215.3 6227.6 6007.9 6009.0 6022.7 6025.9 6037.6 12872.7 13027.3 13154.9 13166.5 13358.4 2123.2 2225.4 2462.4 2937.2 3013.7 10212.0 12564.0 14876.3 15359.3 15536.0 1800.0 1859.3 1884.7 1906.0 1909.9 110676.4 118810.4 120705.0 126576.0 134999.3 10983.8 11251.2 11410.4 11420.9 11588.9 14403.6 14404.9 14425.4 14431.6 14444.1 8143.8 8376.2 8410.6 8493.9 8546.3 8346.3 8348.7 8358.0 8505.8 8508.2 4375.2 4447.2 4462.8 4476.3 4534.2 1600.5 1740.0 1876.0 1881.4 1884.8 851.3 864.7 933.0 952.8 965.8 20703.0 21788.3 21798.8 21919.3 22005.6 958.2 967.3 983.6 994.0 1000.7 10142.8 10171.6 10997.1 11532.5 11576.6 470265.0 472013.0 488312.6 572646.0 585977.0 87557.0 92108.1 130776.0 132386.0 135394.0 969.9 1017.1 1029.1 1036.1 1036.2 18591.7 20024.6 20036.1 20053.5 20175.6 52520.5 57768.4 57924.4 58512.7 58912.2 77417.0 80743.6 85101.4 85348.0 85983.0 7546.4 7789.9 7866.7 8203.7 8237.7 10806.6 12102.3 12622.7 12641.1 12817.6 10121.9 10651.6 10802.3 10851.3 10853.8 10684.8 10958.1 10989.8 11131.3 11170.8 9800.9 10648.7 10998.6 11064.7 11231.9 7825.7 7957.4 7977.7 8257.3 8324.6 9858.5 10993.5 11255.0 11258.4 11317.3 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#3] mootools.html Cycles:180 Average:5556.25 Median:4956.03 stddev:3109.46 (62.7%) stddev-sans-first:3101.64 20:18:45 INFO - PROCESS | 1655 | Values: 1288.8 1535.7 1668.7 1864.5 1893.4 8176.1 8667.2 8676.6 8692.3 8698.5 7599.6 7829.7 8282.3 8463.2 8524.7 2343.8 2378.3 2379.9 2380.2 2385.4 1974.4 2339.2 2344.7 2365.1 2388.6 2363.7 2374.8 2390.1 2397.7 2402.7 1976.6 2351.5 2351.6 2357.1 2358.8 1944.7 2303.4 2355.4 2364.3 2365.7 8566.5 8657.1 8658.2 8658.5 8674.4 5086.0 5249.6 5261.3 5266.9 5274.3 2819.9 3512.1 3643.0 3653.0 3664.3 1035.9 1078.1 1080.9 1099.0 1100.3 11072.7 11191.8 11202.6 11228.2 11232.9 4462.5 4519.4 4712.9 4727.9 4730.2 9669.4 9677.0 9679.0 9683.6 9689.0 5251.3 5259.4 5261.4 5261.6 5262.4 3927.7 4337.5 4489.5 4832.9 4837.6 3824.0 3828.6 3936.3 3966.0 3984.9 1257.0 1272.5 1276.3 1285.9 1290.2 845.8 857.2 859.3 861.9 960.7 5461.6 5666.9 5859.8 5882.8 5987.7 350.3 423.6 436.2 444.3 445.4 3914.6 3917.1 4013.5 4025.3 4067.8 9993.4 10392.7 10403.7 10417.8 10418.0 10360.7 10379.1 10384.2 10393.8 10400.6 7388.4 7673.3 7848.5 7909.5 7984.6 5852.1 5859.9 5957.6 6001.8 6071.5 9616.0 9747.6 9763.7 9771.7 9784.9 9417.8 9712.4 9801.9 9852.4 9952.9 6744.5 6983.2 7062.2 7161.9 7249.2 8126.1 8176.6 8280.2 8297.1 8308.3 7275.1 7361.9 7404.2 7446.6 7522.5 7151.9 7208.7 7211.5 7242.1 7282.1 6859.9 6875.7 6919.9 6930.7 6942.8 4641.2 4654.5 4664.6 4715.3 4800.3 3679.3 4885.5 5026.6 5074.8 5203.2 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#4] prototype.html Cycles:180 Average:4462.72 Median:4960.21 stddev:1371.01 (27.6%) stddev-sans-first:1337.73 20:18:45 INFO - PROCESS | 1655 | Values: 240.7 261.4 296.7 308.9 312.9 4408.9 5271.7 5281.5 5321.2 5321.7 4310.5 4542.2 4944.7 5227.8 5293.6 4059.8 4084.0 4103.6 4107.3 4122.5 4074.8 4092.8 4100.9 4104.4 4109.8 3352.8 3751.6 3795.6 4054.6 4078.9 3342.5 4017.6 4032.9 4041.9 4064.4 2884.7 2887.5 3112.9 3521.6 3521.8 4245.4 4462.8 4866.5 5099.5 5161.4 4604.8 4608.0 4630.3 4630.6 4641.6 5004.8 5491.2 5519.5 5553.2 5574.2 3866.9 3868.2 3901.4 3906.4 3912.5 5142.8 5494.3 5674.5 5784.6 5814.4 3903.5 3933.2 3933.4 3954.5 3954.9 3964.3 4011.8 4020.8 4022.6 4055.2 3935.6 4157.6 4561.2 4591.9 4646.3 4622.6 5023.2 5428.4 5578.7 5580.2 1134.4 1336.0 1340.7 1341.6 1343.2 804.9 830.1 860.3 861.3 861.8 4663.3 4781.1 4783.1 5304.4 5396.9 5042.6 5747.6 5791.3 5804.8 5811.4 4619.0 5094.0 5102.5 5294.9 5331.3 2529.5 3004.7 3016.4 3032.7 3043.3 5016.7 5023.5 5718.5 5947.0 5953.7 4975.7 5817.0 5833.9 5864.6 5866.9 4838.5 5936.7 5950.6 5974.9 5978.3 4837.8 4992.7 5076.7 5445.0 5671.0 5426.6 5606.9 5642.5 5659.4 5668.4 4900.9 5143.9 5943.9 5950.7 5973.6 2907.4 3482.0 3807.0 3895.6 3921.3 5265.4 5624.5 5653.8 5740.7 5800.4 4863.8 5650.6 5669.5 5686.1 5693.5 4703.2 5024.4 5558.5 5584.9 5598.2 4232.6 5212.3 5309.0 5446.7 5473.4 4745.7 5050.4 5203.5 5207.3 5447.9 4530.7 4586.5 5146.7 5430.2 5486.4 20:18:45 INFO - PROCESS | 1655 | 20:18:45 INFO - PROCESS | 1655 | [#5] yui.html Cycles:180 Average:25883.97 Median:22360.50 stddev:42066.92 (188.1%) stddev-sans-first:42142.49 20:18:45 INFO - PROCESS | 1655 | Values: 718.9 722.4 769.4 800.7 809.5 15309.5 15865.8 15877.6 15900.2 15905.9 14208.1 14304.4 14305.0 14320.2 14327.5 651.9 669.6 822.6 829.6 854.9 641.9 839.6 843.1 843.5 848.0 894.5 895.7 897.2 897.3 897.4 890.5 890.7 892.2 893.6 893.7 515.9 657.8 671.0 675.3 678.7 14735.3 14773.3 14784.6 14807.8 14832.3 11958.3 12526.9 13368.9 13850.5 14108.0 12474.7 12880.7 12897.7 12979.4 13510.4 4446.6 4502.0 4558.9 4623.2 4645.2 98153.0 113117.0 118304.4 123297.0 123387.0 10734.4 10783.8 11163.4 11393.5 11412.9 22301.2 22312.9 22333.9 22387.1 22440.7 7690.2 7986.8 8022.8 8097.4 8108.1 4222.6 4278.5 4417.8 4466.9 4514.5 3456.4 3528.8 3569.4 3571.4 3575.9 541.4 638.1 654.0 707.1 709.4 624.9 645.3 664.8 674.2 683.3 8515.3 9057.8 9153.5 9384.3 9724.5 490.5 593.9 639.2 676.7 685.5 4674.9 4678.0 4776.7 4813.3 4837.0 108224.0 118235.0 160343.0 167076.2 171652.0 112564.4 141321.0 142729.0 146824.3 149566.3 121262.0 124738.0 124891.8 125411.0 127968.4 8910.8 9172.4 9203.4 9249.0 9309.1 47458.8 55292.4 65560.0 65604.0 67631.0 96926.0 102545.4 102938.5 102969.0 107574.9 11047.3 11391.7 11717.3 12103.5 12314.1 12657.5 12705.0 13172.3 14586.4 15287.7 13898.9 14125.0 14395.3 14412.2 14925.3 11449.8 14080.2 14413.9 14652.7 14875.7 12964.7 13578.5 13627.1 14033.9 14476.3 10997.6 11286.3 11467.1 11575.5 11731.8 11344.3 11647.7 11729.2 11799.3 11823.5 20:18:45 INFO - PROCESS | 1655 | -------- Summary: end -------- 20:18:45 INFO - PROCESS | 1655 | 20:18:46 INFO - TEST-INFO | 1655: exit 0 20:18:46 INFO - TEST-OK | dromaeo_css | took 1117205ms 20:18:46 INFO - TEST-START | kraken 20:18:46 INFO - operating with platform_type : mac_ 20:18:46 INFO - Initialising browser for kraken test... 20:18:46 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpV1gnJ6/profile http://localhost:49267/getInfo.html) 20:18:48 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 20:18:48 INFO - PROCESS | 1661 | colorDepth:24 20:18:48 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 20:18:48 INFO - PROCESS | 1661 | __metrics 20:18:48 INFO - PROCESS | 1661 | 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" 20:18:48 INFO - PROCESS | 1661 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 155: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 20:18:49 INFO - TEST-INFO | 1661: exit 0 20:18:49 INFO - Browser initialized. 20:18:49 INFO - Running cycle 1/1 for kraken test... 20:18:49 INFO - TEST-INFO | started process 1662 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/T/tmpV1gnJ6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:19:01 INFO - PROCESS | 1662 | RSS: Main: 232955904 20:19:01 INFO - PROCESS | 1662 | 20:19:04 INFO - PROCESS | 1662 | 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) 20:19:05 INFO - PROCESS | 1662 | RSS: Main: 290689024 20:19:05 INFO - PROCESS | 1662 | 20:19:07 INFO - PROCESS | 1662 | 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) 20:19:08 INFO - PROCESS | 1662 | RSS: Main: 286498816 20:19:08 INFO - PROCESS | 1662 | 20:19:11 INFO - PROCESS | 1662 | 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) 20:19:12 INFO - PROCESS | 1662 | RSS: Main: 378167296 20:19:12 INFO - PROCESS | 1662 | 20:19:14 INFO - PROCESS | 1662 | 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) 20:19:15 INFO - PROCESS | 1662 | RSS: Main: 280518656 20:19:15 INFO - PROCESS | 1662 | 20:19:17 INFO - PROCESS | 1662 | 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) 20:19:18 INFO - PROCESS | 1662 | RSS: Main: 281976832 20:19:18 INFO - PROCESS | 1662 | 20:19:20 INFO - PROCESS | 1662 | 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) 20:19:21 INFO - PROCESS | 1662 | RSS: Main: 319193088 20:19:21 INFO - PROCESS | 1662 | 20:19:24 INFO - PROCESS | 1662 | 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) 20:19:24 INFO - PROCESS | 1662 | RSS: Main: 320159744 20:19:24 INFO - PROCESS | 1662 | 20:19:27 INFO - PROCESS | 1662 | 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) 20:19:28 INFO - PROCESS | 1662 | RSS: Main: 319127552 20:19:28 INFO - PROCESS | 1662 | 20:19:29 INFO - PROCESS | 1662 | 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) 20:19:30 INFO - PROCESS | 1662 | RSS: Main: 296378368 20:19:30 INFO - PROCESS | 1662 | 20:19:32 INFO - PROCESS | 1662 | 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) 20:19:33 INFO - PROCESS | 1662 | RSS: Main: 301494272 20:19:33 INFO - PROCESS | 1662 | 20:19:35 INFO - PROCESS | 1662 | 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) 20:19:35 INFO - PROCESS | 1662 | RSS: Main: 304799744 20:19:35 INFO - PROCESS | 1662 | 20:19:38 INFO - PROCESS | 1662 | 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) 20:19:39 INFO - PROCESS | 1662 | RSS: Main: 321425408 20:19:39 INFO - PROCESS | 1662 | 20:19:42 INFO - PROCESS | 1662 | 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) 20:19:43 INFO - PROCESS | 1662 | RSS: Main: 317558784 20:19:43 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | 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) 20:19:44 INFO - PROCESS | 1662 | RSS: Main: 360820736 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | __start_tp_report 20:19:44 INFO - PROCESS | 1662 | _x_x_mozilla_page_load 20:19:44 INFO - PROCESS | 1662 | _x_x_mozilla_page_load_details 20:19:44 INFO - PROCESS | 1662 | |i|pagename|runs| 20:19:44 INFO - PROCESS | 1662 | |0;ai-astar;110;110;129;110;112;112;109;110;110;110 20:19:44 INFO - PROCESS | 1662 | |1;audio-beat-detection;148;114;131;187;137;137;128;108;160;107 20:19:44 INFO - PROCESS | 1662 | |2;audio-dft;185;185;183;183;185;183;182;182;185;184 20:19:44 INFO - PROCESS | 1662 | |3;audio-fft;77;73;75;150;59;57;58;70;142;58 20:19:44 INFO - PROCESS | 1662 | |4;audio-oscillator;81;82;133;69;80;83;127;112;192;115 20:19:44 INFO - PROCESS | 1662 | |5;imaging-gaussian-blur;87;84;87;85;88;84;89;85;88;84 20:19:44 INFO - PROCESS | 1662 | |6;imaging-darkroom;94;93;94;95;93;92;94;95;93;93 20:19:44 INFO - PROCESS | 1662 | |7;imaging-desaturate;86;86;86;84;85;86;84;85;86;86 20:19:44 INFO - PROCESS | 1662 | |8;json-parse-financial;55;57;54;54;54;54;56;55;53;55 20:19:44 INFO - PROCESS | 1662 | |9;json-stringify-tinderbox;66;69;75;79;78;74;72;72;77;105 20:19:44 INFO - PROCESS | 1662 | |10;stanford-crypto-aes;86;88;85;87;85;88;84;90;90;90 20:19:44 INFO - PROCESS | 1662 | |11;stanford-crypto-ccm;138;137;137;134;141;136;146;150;190;140 20:19:44 INFO - PROCESS | 1662 | |12;stanford-crypto-pbkdf2;169;171;168;169;168;169;173;233;210;168 20:19:44 INFO - PROCESS | 1662 | |13;stanford-crypto-sha256-iterative;67;68;68;67;67;66;67;66;66;68 20:19:44 INFO - PROCESS | 1662 | __end_tp_report 20:19:44 INFO - PROCESS | 1662 | __start_cc_report 20:19:44 INFO - PROCESS | 1662 | _x_x_mozilla_cycle_collect,2095 20:19:44 INFO - PROCESS | 1662 | __end_cc_report 20:19:44 INFO - PROCESS | 1662 | __startTimestamp1472267984966__endTimestamp 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | ------- Summary: start ------- 20:19:44 INFO - PROCESS | 1662 | Number of tests: 14 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#0] ai-astar Cycles:10 Average:112.20 Median:110.00 stddev:5.98 (5.4%) stddev-sans-first:6.29 20:19:44 INFO - PROCESS | 1662 | Values: 110.0 110.0 129.0 110.0 112.0 112.0 109.0 110.0 110.0 110.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#1] audio-beat-detection Cycles:10 Average:135.70 Median:137.00 stddev:24.77 (18.1%) stddev-sans-first:25.87 20:19:44 INFO - PROCESS | 1662 | Values: 148.0 114.0 131.0 187.0 137.0 137.0 128.0 108.0 160.0 107.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#2] audio-dft Cycles:10 Average:183.70 Median:184.50 stddev:1.25 (0.7%) stddev-sans-first:1.24 20:19:44 INFO - PROCESS | 1662 | Values: 185.0 185.0 183.0 183.0 185.0 183.0 182.0 182.0 185.0 184.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#3] audio-fft Cycles:10 Average:81.90 Median:64.50 stddev:34.69 (53.8%) stddev-sans-first:36.75 20:19:44 INFO - PROCESS | 1662 | Values: 77.0 73.0 75.0 150.0 59.0 57.0 58.0 70.0 142.0 58.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#4] audio-oscillator Cycles:10 Average:107.40 Median:74.50 stddev:37.18 (49.9%) stddev-sans-first:38.18 20:19:44 INFO - PROCESS | 1662 | Values: 81.0 82.0 133.0 69.0 80.0 83.0 127.0 112.0 192.0 115.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#5] imaging-gaussian-blur Cycles:10 Average:86.10 Median:87.00 stddev:1.91 (2.2%) stddev-sans-first:2.00 20:19:44 INFO - PROCESS | 1662 | Values: 87.0 84.0 87.0 85.0 88.0 84.0 89.0 85.0 88.0 84.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#6] imaging-darkroom Cycles:10 Average:93.60 Median:94.00 stddev:0.97 (1.0%) stddev-sans-first:1.01 20:19:44 INFO - PROCESS | 1662 | Values: 94.0 93.0 94.0 95.0 93.0 92.0 94.0 95.0 93.0 93.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#7] imaging-desaturate Cycles:10 Average:85.40 Median:86.00 stddev:0.84 (1.0%) stddev-sans-first:0.87 20:19:44 INFO - PROCESS | 1662 | Values: 86.0 86.0 86.0 84.0 85.0 86.0 84.0 85.0 86.0 86.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#8] json-parse-financial Cycles:10 Average:54.70 Median:55.00 stddev:1.16 (2.1%) stddev-sans-first:1.22 20:19:44 INFO - PROCESS | 1662 | Values: 55.0 57.0 54.0 54.0 54.0 54.0 56.0 55.0 53.0 55.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#9] json-stringify-tinderbox Cycles:10 Average:76.70 Median:74.50 stddev:10.73 (14.4%) stddev-sans-first:10.66 20:19:44 INFO - PROCESS | 1662 | Values: 66.0 69.0 75.0 79.0 78.0 74.0 72.0 72.0 77.0 105.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#10] stanford-crypto-aes Cycles:10 Average:87.30 Median:88.00 stddev:2.26 (2.6%) stddev-sans-first:2.35 20:19:44 INFO - PROCESS | 1662 | Values: 86.0 88.0 85.0 87.0 85.0 88.0 84.0 90.0 90.0 90.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:140.50 stddev:16.57 (11.8%) stddev-sans-first:17.39 20:19:44 INFO - PROCESS | 1662 | Values: 138.0 137.0 137.0 134.0 141.0 136.0 146.0 150.0 190.0 140.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:179.80 Median:170.00 stddev:22.69 (13.3%) stddev-sans-first:23.73 20:19:44 INFO - PROCESS | 1662 | Values: 169.0 171.0 168.0 169.0 168.0 169.0 173.0 233.0 210.0 168.0 20:19:44 INFO - PROCESS | 1662 | 20:19:44 INFO - PROCESS | 1662 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:67.00 Median:67.00 stddev:0.82 (1.2%) stddev-sans-first:0.87 20:19:44 INFO - PROCESS | 1662 | Values: 67.0 68.0 68.0 67.0 67.0 66.0 67.0 66.0 66.0 68.0 20:19:44 INFO - PROCESS | 1662 | -------- Summary: end -------- 20:19:44 INFO - PROCESS | 1662 | 20:19:45 INFO - TEST-INFO | 1662: exit 0 20:19:45 INFO - TEST-OK | kraken | took 59369ms 20:19:45 INFO - SUITE-END | took 1176s 20:19:46 INFO - Completed test suite (00:19:36) 20:19:46 INFO - javascript benchmark 20:19:46 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"lowerIsBetter": false, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3333.89998300051, 3363.7309015278774, 3370.915727106822, 3417.5386322846407, 3438.8796392126283, 5651.886962260753, 5684.65892046477, 5768.278965129359, 5782.942170578293, 5786.913196302054, 5448.45515448455, 5720.170575266584, 5749.770009199638, 5809.302883653967, 5823.708814559276, 5176.689398636094, 5212.244224587433, 5242.711650859202, 5268.1834315681135, 5388.110961691316, 5342.732863356836, 5364.168553874136, 5388.91916621251, 5397.217403476494, 5428.972855135738, 5201.869953251161, 5224.164133738584, 5259.526642602146, 5262.236975638511, 5306.230596563477, 5227.895442091137, 5244.344456942882, 5267.473252674702, 5273.499017593323, 5356.91964620531, 5227.581793456515, 5262.473752624745, 5275.947240527584, 5286.577073834084, 5312.601554883399, 5632.971835140839, 5678.949394362015, 5800.622959507661, 5810.7385167667135, 5815.941840581582, 6188.071789231606, 6254.6247225166635, 6261.279952805395, 6288.779892703779, 6311.400416960381, 6262.812115636538, 6333.113364129025, 6346.809595712136, 6353.047042943549, 6359.4594459471, 5722.856928576819, 5862.179294898675, 5865.35481097079, 5877.147813567009, 5881.705914704247, 6258.874822503615, 6286.811395658137, 6319.96840015797, 6322.146510221061, 6356.110144579762, 6175.938240617536, 6183.598066125777, 6202.069689546604, 6202.906956395657, 6204.565680402328, 6228.5951413158, 6258.718357673915, 6314.210723659543, 6325.304216536178, 6327.209098862642, 6040.637561746222, 6146.6004709693725, 6148.8770224595255, 6215.160953271341, 6228.968855155696, 5151.5106064923775, 6272.153259310026, 6279.183706118175, 6300.684965751786, 6319.58922670025, 4997.650164488451, 5019.096562618691, 5019.974900125476, 5031.396232452056, 5051.823186188466, 4402.119576084796, 4416.977915110404, 4494.842680506166, 4527.728336299832, 4559.338895860148, 4834.758262086882, 5698.1737648041635, 5734.856628584235, 5776.220210271644, 5777.653340799566, 6216.129741836146, 6256.655883926337, 6276.968615156895, 6286.8428289292215, 6306.716197771019, 5811.506021988093, 5825.533957283407, 5832.62087964281, 5833.300003999547, 5849.707514624251, 5489.286392768914, 5500.3949565547755, 5502.8349149525575, 5512.641678290898, 5514.338279406497, 6269.0909818076125, 6274.152989346379, 6286.811395658137, 6294.905576416266, 6379.075034120119, 6252.718627661857, 6300.621962682254, 6329.145565348619, 6330.3669633036325, 6348.619082854859, 6384.425401713868, 6388.552801303864, 6392.488600911823, 6397.904031439625, 6404.839879003062, 6116.305337585956, 6116.755329786936, 6124.472479235402, 6135.477655820289, 6221.06683997404, 6294.0558916162945, 6295.496360291074, 6300.873982520416, 6301.527385446201, 6308.558400911892, 6130.63216207029, 6143.324234334309, 6144.662043587646, 6153.907691384543, 6155.969220153871, 5906.320773111147, 6065.969670151621, 6067.211262535842, 6095.695215239309, 6115.266168059861, 5918.319393269829, 5956.374580668968, 5986.55100867428, 5991.6405015697455, 6009.098635204579, 6127.417895299939, 6152.876942461036, 6156.445919867234, 6189.288231853395, 6190.442860142609, 5724.141378793048, 5790.884182316414, 5870.735816888165, 5893.263342082239, 5949.494292985145], "value": 5740.559377415044, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [467.2850538675827, 475.33215831756394, 510.2626704412124, 526.4524894110126, 533.3839415475127, 31719.841400792993, 32183.67816321836, 40331.0, 41789.7910510447, 41793.0, 43090.569094309125, 43361.783191084076, 43487.0, 43520.347194792106, 43536.56463435365, 9812.362196457225, 10052.296339256254, 10055.446950417723, 10061.396316221013, 10061.849072263922, 8814.735557933273, 9045.683401080963, 12054.758904821898, 12377.25736455811, 12413.44139513724, 12261.080418968568, 12362.629121126336, 12363.938180309086, 12379.938100309486, 12380.442880070419, 12250.081243906698, 12347.567835125728, 12357.814632780515, 12361.567345142921, 12379.195352302071, 10525.894741052569, 11352.48913798881, 11745.588904388305, 11765.705857353549, 11825.526978920832, 40197.39703904444, 40436.79781601103, 40479.0, 40479.19041619151, 40492.0, 3365.0914253151755, 3431.262278610086, 3705.4997575327257, 3855.768653880748, 4052.128792309638, 30918.845405773052, 31166.0, 31813.52279715806, 31843.681563184302, 32123.83938080318, 3197.9200519987185, 3266.738660907122, 3328.284418849935, 3335.8332083395735, 3367.478040903664, 500092.0, 500255.0, 500859.99999999633, 501938.0, 506408.99999999633, 53295.739160430196, 53426.0, 53512.19731704028, 53537.73231133819, 53538.73230633822, 61120.0, 61212.08181877276, 61213.693931530055, 61291.693541532004, 61462.38537614656, 15981.44064957721, 16168.595785105466, 16188.75716864248, 16209.594760130854, 16241.2691428886, 11345.31928084301, 11559.017483513828, 12612.81080783789, 12673.366331683379, 12673.49306027767, 14874.776878346833, 15563.533094007198, 15779.368825246862, 15988.0, 16095.839041609668, 6226.688665566703, 6267.5299352548745, 6284.3154744206995, 6342.651154186564, 6368.522360822957, 869.4001139213922, 940.238406890421, 965.8502932045544, 1025.1542477456128, 1056.366180291834, 28627.856860715565, 29565.704342956724, 29717.257068573457, 29879.253018674706, 30363.0, 909.7543663210896, 991.3902949685892, 1074.2050882347007, 1086.2070688397357, 1124.0951034417374, 15010.624734381729, 15522.456714014956, 15610.45363412275, 15938.68122637564, 15951.122688252026, 658641.0, 915264.0, 928282.0, 928600.0, 930511.0, 55935.44064559302, 56758.0, 56766.43233567694, 56874.14688779671, 56934.430655692915, 1220.4812954494128, 1252.0797214047486, 1342.4294674763187, 1365.7746471832236, 1387.6322774464775, 26998.730012699623, 28992.565111522923, 29101.399423031937, 31207.375852483277, 31938.840305798323, 52475.425737227946, 52978.0, 53006.0, 53061.20408193958, 53071.0, 562239.0, 567183.0, 567423.4886476789, 567563.0, 568252.0, 22910.198143064914, 25332.746672533776, 25737.227883163534, 25753.356166095997, 26158.86920565385, 37323.62676373201, 43771.56228437675, 46126.30810537912, 47379.0, 48861.022779544925, 34385.656143438246, 34890.0, 34999.950001499994, 37819.59623633776, 37899.621003790715, 38082.2383552333, 38100.809495952344, 39042.316814380225, 39218.411723824735, 39506.802465987486, 38023.42964855474, 38365.424518632804, 38626.0, 38766.0, 38858.4171237426, 32231.194220144684, 33601.15997100092, 34261.657383425845, 34372.96881093571, 34375.484367734, 24242.515149696555, 25090.0, 25744.0, 25907.611385829605, 26514.734852651225], "value": 20257.414173408783, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [363.9599644039157, 368.4638850472562, 378.5419642232898, 385.4026259298087, 397.7414680457702, 19814.0, 21890.154279943585, 22235.888820555916, 22436.55126897461, 22538.54922901543, 17817.821821781778, 21214.469638259052, 21269.0, 21491.0, 21783.45541361467, 5819.62172458791, 5961.612495187821, 6115.327313995457, 6192.938070619303, 6218.906716399258, 5852.76588936442, 6147.661878596676, 6192.690365481731, 6201.627902325852, 6202.255729312489, 5572.5511367044655, 5937.554683398741, 6176.227971503562, 6240.5319601030105, 6251.906221406683, 5962.582619216678, 6124.050772130327, 6200.658963756991, 6215.254169499666, 6227.626342419469, 6007.939920600782, 6009.038553831409, 6022.6687532185715, 6025.879482410327, 6037.637741735466, 12872.742545149138, 13027.348632568333, 13154.934225328812, 13166.473341066347, 13358.39887205078, 2123.235635171337, 2225.410266279437, 2462.4336402627496, 2937.221636266391, 3013.668496465387, 10212.0, 12563.994880409546, 14876.256187190596, 15359.308831102515, 15535.990160639521, 1799.973000404995, 1859.2562974810048, 1884.660761063008, 1905.9803005392048, 1909.93043895419, 110676.4466177664, 118810.43405634598, 120705.0, 126576.0, 134999.32500337533, 10983.835242471368, 11251.156163287786, 11410.429478526206, 11420.94289528547, 11588.88411115878, 14403.639909002359, 14404.927975360057, 14425.350859211361, 14431.567052988426, 14444.133351998913, 8143.75568732939, 8376.188903078186, 8410.621522031523, 8493.895793546923, 8546.273566746771, 8346.332293416634, 8348.749537513884, 8358.038825535019, 8505.82988340242, 8508.234258916727, 4375.16871794358, 4447.221736196153, 4462.821487140543, 4476.306172543196, 4534.166146158651, 1600.5198440467814, 1740.0, 1875.9624807503774, 1881.3697411367345, 1884.8020957799508, 851.3061854588491, 864.7103220421229, 933.0203286549096, 952.7951490429591, 965.8454647256406, 20703.0, 21788.346349609536, 21798.782012179992, 21919.342419727433, 22005.559888802134, 958.2238386906628, 967.3180407812475, 983.6114734679761, 993.980120397588, 1000.6947880896397, 10142.847857282146, 10171.64399246023, 10997.065249453886, 11532.538698452136, 11576.594819181457, 470265.0, 472013.0, 488312.55843721266, 572646.0, 585977.0, 87557.0, 92108.08974506229, 130776.0, 132386.0, 135394.0, 969.8642190093393, 1017.0947856407803, 1029.1098200057047, 1036.124474818777, 1036.233187441288, 18591.72112418342, 20024.599508009465, 20036.098375573427, 20053.498662533555, 20175.59648807045, 52520.47479525156, 57768.422315776304, 57924.393402296155, 58512.70743646425, 58912.23263302108, 77417.0, 80743.59628201822, 85101.40522598034, 85348.0, 85983.0, 7546.434017448603, 7789.922100779147, 7866.685332586632, 8203.71287004952, 8237.670493180205, 10806.621768238074, 12102.273863568214, 12622.684432889251, 12641.051921106138, 12817.615471535868, 10121.898781012096, 10651.573937042742, 10802.297850639683, 10851.294665846695, 10853.837192442277, 10684.83972740394, 10958.12335013213, 10989.835152472879, 11131.27646702994, 11170.776584468103, 9800.921898591007, 10648.680539583826, 10998.560057597926, 11064.723381915195, 11231.943840280746, 7825.686972521035, 7957.442978991647, 7977.7207797726805, 8257.298129659046, 8324.625391857502, 9858.507074646383, 10993.505292262027, 11254.987051165106, 11258.38078905668, 11317.320960742381], "value": 9533.668895310846, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.8066789981501, 1535.6851845371696, 1668.6746084513518, 1864.4593068010279, 1893.3941138835578, 8176.097485392682, 8667.176618221267, 8676.609552570133, 8692.304615630734, 8698.478091314517, 7599.582022988733, 7829.725959591416, 8282.254597086261, 8463.153684631534, 8524.701635442761, 2343.753905839888, 2378.2508509819436, 2379.892904819287, 2380.1550449590404, 2385.3678775124604, 1974.427416049344, 2339.1929784224417, 2344.6951896253463, 2365.077619728307, 2388.6297623868327, 2363.7399886012527, 2374.845635033726, 2390.091765129257, 2397.652340410639, 2402.651615515749, 1976.5849171673822, 2351.494428697821, 2351.6354964980455, 2357.116081469449, 2358.752331005237, 1944.6694062009492, 2303.3550605830396, 2355.4229213842546, 2364.309518694142, 2365.727941286757, 8566.48601083937, 8657.134286571356, 8658.177473140104, 8658.523781192032, 8674.392792504526, 5086.0, 5249.632525723201, 5261.263423120766, 5266.868328291824, 5274.314339135926, 2819.887204511817, 3512.127097963031, 3642.9817850910576, 3653.0, 3664.303782281358, 1035.8964103589656, 1078.0567003871613, 1080.9405482698448, 1099.0383414512303, 1100.3178029621686, 11072.723181920435, 11191.832122518168, 11202.551897924073, 11228.214025018253, 11232.943835280772, 4462.509123996358, 4519.367288579601, 4712.949866807881, 4727.929081063787, 4730.172219861511, 9669.371490853073, 9676.951615241878, 9679.0, 9683.612655493702, 9689.031096890254, 5251.343582052244, 5259.395169555473, 5261.447548007481, 5261.552768014685, 5262.421133675292, 3927.6857851371824, 4337.501187363494, 4489.461264648263, 4832.903341933142, 4837.637177211724, 3824.0057585028094, 3828.6362795534938, 3936.3111455495173, 3965.980170099131, 3984.900377490586, 1256.9874301257053, 1272.4592048379402, 1276.2980360801523, 1285.9228446293253, 1290.161395093197, 845.7674139611534, 857.1813917708573, 859.3254295378098, 861.8707193920836, 960.706984369774, 5461.563074953994, 5666.943330566724, 5859.824205273849, 5882.764689412461, 5987.670678112659, 350.3466035843134, 423.57642357642357, 436.2409407630763, 444.3090993505066, 445.3675780391787, 3914.628110329514, 3917.1186483041656, 4013.4983127109113, 4025.295573274665, 4067.7966101694797, 9993.350432221881, 10392.688219353431, 10403.739906502398, 10417.791644167226, 10418.0, 10360.689179324632, 10379.065884070318, 10384.169266458664, 10393.792124157475, 10400.58397664085, 7388.408927285805, 7673.34776544, 7848.489848159966, 7909.52542847431, 7984.560849153352, 5852.092925596539, 5859.882802344015, 5957.582969192288, 6001.789937352171, 6071.453569178795, 9615.951920240354, 9747.561359738687, 9763.658271960447, 9771.706848794547, 9784.853227201455, 9417.764555885884, 9712.417254964725, 9801.9019809801, 9852.408855468695, 9952.850707239542, 6744.494162937897, 6983.231844497, 7062.15254169503, 7161.928380716335, 7249.166345870081, 8126.106128325761, 8176.591170441573, 8280.213379729157, 8297.128801475757, 8308.252257296872, 7275.054242948383, 7361.889571656537, 7404.185539590749, 7446.627668616656, 7522.548647081193, 7151.928480715126, 7208.747693830691, 7211.459140564583, 7242.058532390755, 7282.12614486265, 6859.862802744017, 6875.656217189221, 6919.896201557081, 6930.7227710891, 6942.757003504853, 4641.236330194504, 4654.48800631937, 4664.626829853536, 4715.316279139574, 4800.303955926302, 3679.3181648266113, 4885.4870238624435, 5026.572741317029, 5074.797008119634, 5203.19350500673], "value": 4400.673226934916, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [240.70032809152605, 261.419648380595, 296.6899589928526, 308.9057837359604, 312.85764976935496, 4408.911821763563, 5271.736413179345, 5281.498257665526, 5321.164198351572, 5321.680699158049, 4310.482742070948, 4542.182407166709, 4944.736473927209, 5227.764750586223, 5293.612088263514, 4059.776712280816, 4084.0, 4103.604774376711, 4107.260693075245, 4122.4640796696385, 4074.775887326196, 4092.7517107282265, 4100.876973690794, 4104.384342348641, 4109.815058322368, 3352.849121789513, 3751.6248375162404, 3795.6394142556414, 4054.574269701683, 4078.8572399966006, 3342.498625206214, 4017.578154293786, 4032.9395059074136, 4041.939370909439, 4064.3539366556442, 2884.6971068037765, 2887.5091234490187, 3112.8754849805982, 3521.5774107107054, 3521.8239088045493, 4245.448091748084, 4462.776861156962, 4866.46468888422, 5099.515546023159, 5161.406438259611, 4604.815807367701, 4607.976960115211, 4630.333080091164, 4630.629549636021, 4641.559051890065, 5004.84694642373, 5491.203775452537, 5519.475649813261, 5553.16702494625, 5574.2441131044, 3866.9226615467533, 3868.168343806095, 3901.375779875235, 3906.3554513505237, 3912.5304963404574, 5142.794288228466, 5494.2857428534435, 5674.460926212003, 5784.566157538202, 5814.447627475383, 3903.512060992376, 3933.193695292458, 3933.370660694275, 3954.4661470701662, 3954.9011274718364, 3964.306246406867, 4011.8395264189107, 4020.778857162884, 4022.6379625833815, 4055.229506393776, 3935.5670876203594, 4157.625813676784, 4561.2473941799235, 4591.862244132682, 4646.326282688991, 4622.6301895848255, 5023.246513023003, 5428.4028756836715, 5578.716895114147, 5580.246666700024, 1134.4157758754247, 1335.9666008349868, 1340.6514306280242, 1341.557286095586, 1343.1605246720799, 804.9053287528956, 830.1449507007792, 860.2644738748405, 861.3152543727751, 861.7888617288679, 4663.347131401607, 4781.115493633689, 4783.115123702126, 5304.363476382859, 5396.946030539723, 5042.571381432619, 5747.5689323300085, 5791.291568987074, 5804.767809287666, 5811.418858114239, 4619.0, 5094.001209050877, 5102.489751024942, 5294.867628309246, 5331.280277162611, 2529.5455113309554, 3004.7145521175453, 3016.3967206558777, 3032.6815684352823, 3043.3304672971913, 5016.673916195436, 5023.497650234947, 5718.542516598662, 5947.0, 5953.732082056318, 4975.676581022222, 5817.0, 5833.854153646108, 5864.589478736533, 5866.911996320058, 4838.491958344393, 5936.703164841827, 5950.553708471882, 5974.850628734316, 5978.252718410199, 4837.7581120944515, 4992.675476094042, 5076.670016449067, 5444.972775136257, 5671.0359238930305, 5426.620136590401, 5606.915896261477, 5642.492175704207, 5659.377468478382, 5668.376478587435, 4900.901981960412, 5143.884892086241, 5943.851403714942, 5950.732217050157, 5973.581849270509, 2907.447584958851, 3481.9728180187412, 3806.9619303807717, 3895.6299151580556, 3921.31377009028, 5265.368155821326, 5624.521915637217, 5653.773849045992, 5740.703718512628, 5800.4489573490455, 4863.756812159449, 5650.63270887391, 5669.489745922887, 5686.09022556389, 5693.5160511357, 4703.165765351448, 5024.397072351341, 5558.499735023867, 5584.86037849057, 5598.160275958476, 4232.64022558086, 5212.270282160576, 5308.973455132699, 5446.673199608036, 5473.425290344457, 4745.667803253739, 5050.419201791694, 5203.479652034766, 5207.3490813648295, 5447.863803404788, 4530.70550414222, 4586.518415566451, 5146.7390489330355, 5430.185472179205, 5486.369067557282], "value": 4018.9009501036016, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [718.8957601147833, 722.4215090028285, 769.3960241210651, 800.6637212370804, 809.5062012172574, 15309.54071377859, 15865.762013569805, 15877.603059923478, 15900.204989750524, 15905.920470397647, 14208.076475029116, 14304.35630396632, 14304.998650094472, 14320.212388318638, 14327.498537551188, 651.9217693876741, 669.6183175589915, 822.596927505521, 829.5852073963003, 854.9230569248766, 641.8780431717983, 839.5508403004394, 843.0936743001298, 843.5360551696542, 848.0417128644654, 894.4812009034745, 895.6596493332524, 897.1656359585583, 897.2507955856835, 897.3583887520422, 890.4657205676607, 890.6838072484304, 892.1658249536704, 893.5934149961752, 893.6916763716507, 515.9045576568346, 657.7829316325601, 671.0437626382386, 675.3415419965544, 678.6572780745742, 14735.336909838976, 14773.261336933218, 14784.556463306119, 14807.777883331759, 14832.332545035499, 11958.342291173982, 12526.872581467711, 13368.933155334258, 13850.515231966832, 14108.012439129265, 12474.688132796662, 12880.677983050498, 12897.742045159044, 12979.41592628334, 13510.392032358568, 4446.555344465528, 4502.0, 4558.931616025762, 4623.214053610895, 4645.233536466479, 98153.0, 113117.0, 118304.40847795705, 123297.0, 123387.0, 10734.409607471664, 10783.784324313627, 11163.441827908571, 11393.544258229744, 11412.885871141349, 22301.219457318915, 22312.88843555804, 22333.88833055857, 22387.104515819185, 22440.66339004916, 7690.230976902265, 7986.800329991681, 8022.759317220492, 8097.392695547857, 8108.108108108104, 4222.577742225814, 4278.465191851019, 4417.757023363746, 4466.865994020184, 4514.503404625489, 3456.4469684850305, 3528.770629909048, 3569.39320315547, 3571.357155711945, 3575.856965721394, 541.4071591607184, 638.084348959245, 653.9640319782362, 707.0737334092355, 709.3544874164486, 624.865653884417, 645.2901808011151, 664.8437617159964, 674.2381109346505, 683.2975581574859, 8515.318774498026, 9057.77355566116, 9153.496557689392, 9384.296177786557, 9724.513774311255, 490.45314474360833, 593.8812237552506, 639.1563136659657, 676.681959479044, 685.4995853027218, 4674.883127921829, 4677.976610116995, 4776.665633405628, 4813.307731338213, 4836.951630483721, 108224.0, 118235.0, 160343.0, 167076.16461917857, 171652.0, 112564.43717781358, 141321.0, 142729.0, 146824.26587866992, 149566.25216874064, 121262.0, 124738.0, 124891.75541223084, 125411.0, 127968.36015819861, 8910.777230569287, 9172.44965302084, 9203.40177888462, 9248.95375523145, 9309.146586994206, 47458.76270618625, 55292.44707552873, 65560.01659975009, 65604.0, 67631.0, 96926.0, 102545.4363640915, 102938.48530757298, 102969.0, 107574.92425075649, 11047.337159770439, 11391.658250252505, 11717.296962182296, 12103.515859365527, 12314.076444266537, 12657.493700251887, 12705.0, 13172.341382930623, 14586.416543338572, 15287.694246115238, 13898.861011390161, 14125.0, 14395.280235988368, 14412.207328597027, 14925.253737312874, 11449.828252576384, 14080.15519068859, 14413.85586144125, 14652.706945861237, 14875.702485950436, 12964.675883102998, 13578.524751633646, 13627.114237574526, 14033.929830350782, 14476.348564314421, 10997.61508347204, 11286.322820630789, 11467.139964502863, 11575.536978521102, 11731.824022639497, 11344.291602522982, 11647.71023647221, 11729.178957472895, 11799.292042477478, 11823.52705891789], "value": 7907.314677405857, "unit": "score"}], "name": "dromaeo_css", "value": 7329.658739032484, "alertThreshold": 5.0}, {"lowerIsBetter": true, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 110.0, 129.0, 110.0, 112.0, 112.0, 109.0, 110.0, 110.0, 110.0], "value": 112.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 114.0, 131.0, 187.0, 137.0, 137.0, 128.0, 108.0, 160.0, 107.0], "value": 135.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 185.0, 183.0, 183.0, 185.0, 183.0, 182.0, 182.0, 185.0, 184.0], "value": 183.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 73.0, 75.0, 150.0, 59.0, 57.0, 58.0, 70.0, 142.0, 58.0], "value": 81.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 82.0, 133.0, 69.0, 80.0, 83.0, 127.0, 112.0, 192.0, 115.0], "value": 107.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 84.0, 87.0, 85.0, 88.0, 84.0, 89.0, 85.0, 88.0, 84.0], "value": 86.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 93.0, 94.0, 95.0, 93.0, 92.0, 94.0, 95.0, 93.0, 93.0], "value": 93.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 86.0, 86.0, 84.0, 85.0, 86.0, 84.0, 85.0, 86.0, 86.0], "value": 85.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [55.0, 57.0, 54.0, 54.0, 54.0, 54.0, 56.0, 55.0, 53.0, 55.0], "value": 54.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 75.0, 79.0, 78.0, 74.0, 72.0, 72.0, 77.0, 105.0], "value": 76.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 88.0, 85.0, 87.0, 85.0, 88.0, 84.0, 90.0, 90.0, 90.0], "value": 87.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 137.0, 137.0, 134.0, 141.0, 136.0, 146.0, 150.0, 190.0, 140.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 171.0, 168.0, 169.0, 168.0, 169.0, 173.0, 233.0, 210.0, 168.0], "value": 179.8, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 68.0, 68.0, 67.0, 67.0, 66.0, 67.0, 66.0, 66.0, 68.0], "value": 67.0, "unit": "score"}], "name": "kraken", "value": 1496.4, "alertThreshold": 2.0}]} 20:19:46 INFO - Return code: 0 20:19:46 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:19:46 INFO - # TBPL SUCCESS # 20:19:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:19:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:19:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:19:46 INFO - 2016-08-26 20:19:46.269 screenresolution[1666:11106] starting screenresolution argv=screenresolution get 20:19:46 INFO - 2016-08-26 20:19:46.272 screenresolution[1666:11106] Display 0: 1600x1200x32@60 20:19:46 INFO - 2016-08-26 20:19:46.278 screenresolution[1667:11109] starting screenresolution argv=screenresolution list 20:19:46 INFO - Available Modes on Display 0 20:19:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:19:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:19:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:19:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:19:46 INFO - Intel Iris: 20:19:46 INFO - Chipset Model: Intel Iris 20:19:46 INFO - Type: GPU 20:19:46 INFO - Bus: Built-In 20:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 20:19:46 INFO - Vendor: Intel (0x8086) 20:19:46 INFO - Device ID: 0x0a2e 20:19:46 INFO - Revision ID: 0x0009 20:19:46 INFO - Displays: 20:19:46 INFO - Display: 20:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:19:46 INFO - Display Serial Number: 1600x1200 60 20:19:46 INFO - Main Display: Yes 20:19:46 INFO - Mirror: Off 20:19:46 INFO - Online: Yes 20:19:46 INFO - Rotation: Supported 20:19:46 INFO - Return code: 0 20:19:46 INFO - Running post-action listener: _resource_record_post_action 20:19:46 INFO - [mozharness: 2016-08-27 03:19:46.519875Z] Finished run-tests step (success) 20:19:46 INFO - Running post-run listener: _resource_record_post_run 20:19:46 INFO - Total resource usage - Wall time: 1211s; CPU: 53.0%; Read bytes: 31906816; Write bytes: 707335168; Read time: 198; Write time: 2037 20:19:46 INFO - TinderboxPrint: CPU usage
53.4% 20:19:46 INFO - TinderboxPrint: I/O read bytes / time
31,906,816 / 198 20:19:46 INFO - TinderboxPrint: I/O write bytes / time
707,335,168 / 2,037 20:19:46 INFO - TinderboxPrint: CPU idle
2,259.1 (46.6%) 20:19:46 INFO - TinderboxPrint: CPU system
966.5 (19.9%) 20:19:46 INFO - TinderboxPrint: CPU user
1,620.1 (33.4%) 20:19:46 INFO - TinderboxPrint: Swap in / out
434,053,120 / 0 20:19:46 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 396628480; Write bytes: 392162816; Read time: 29364; Write time: 864 20:19:46 INFO - run-tests - Wall time: 1178s; CPU: 53.0%; Read bytes: 29928448; Write bytes: 296498688; Read time: 180; Write time: 1129 20:19:47 INFO - Running post-run listener: _upload_blobber_files 20:19:47 INFO - Blob upload gear active. 20:19:47 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:19:47 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 20:19:47 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:19:47 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:19:47 INFO - (blobuploader) - INFO - Open directory for files ... 20:19:47 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 20:19:47 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:19:47 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:19:48 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 20:19:48 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:19:48 INFO - (blobuploader) - INFO - Done attempting. 20:19:48 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 20:19:48 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:19:48 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:19:49 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 20:19:49 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:19:49 INFO - (blobuploader) - INFO - Done attempting. 20:19:49 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:19:49 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:19:49 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:19:51 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:19:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:19:51 INFO - (blobuploader) - INFO - Done attempting. 20:19:51 INFO - (blobuploader) - INFO - Iteration through files over. 20:19:51 INFO - Return code: 0 20:19:51 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:19:51 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:19:51 INFO - Setting buildbot property blobber_files to {"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3886121477d27c1e5b5247762298df768060764ac7c98507ef7ffce109b4ceac2ff034b55583543615210454ae392040353f257f1ae67e729c559b5054e45272", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/627cee6b711ad7141804a7151ec2b767f1415589ce166e9561f195856378ef34b6d9090a77f49ac3ac69c4e4e5b157b5e70635f5a270bb89bcade69a2d2ebb0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d3851bafbc5aeab21deb55475a06c062ac7a755ddef20f2cb273f0456b3c43d1054f64c8617eb55301e36f64a0c847e59b51575f85f87e4c22ed1e217cb4df66"} 20:19:51 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:19:51 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:19:51 INFO - Contents: 20:19:51 INFO - blobber_files:{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3886121477d27c1e5b5247762298df768060764ac7c98507ef7ffce109b4ceac2ff034b55583543615210454ae392040353f257f1ae67e729c559b5054e45272", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/627cee6b711ad7141804a7151ec2b767f1415589ce166e9561f195856378ef34b6d9090a77f49ac3ac69c4e4e5b157b5e70635f5a270bb89bcade69a2d2ebb0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d3851bafbc5aeab21deb55475a06c062ac7a755ddef20f2cb273f0456b3c43d1054f64c8617eb55301e36f64a0c847e59b51575f85f87e4c22ed1e217cb4df66"} 20:19:51 INFO - Running post-run listener: copy_logs_to_upload_dir 20:19:51 INFO - Copying logs to upload dir... 20:19:51 INFO - mkdir: /builds/slave/test/build/upload/logs 20:19:51 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.166996 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-08-26 20:19:51.580943) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 20:19:51.584408) ========= 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.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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/Autoland/sha512/3886121477d27c1e5b5247762298df768060764ac7c98507ef7ffce109b4ceac2ff034b55583543615210454ae392040353f257f1ae67e729c559b5054e45272", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/627cee6b711ad7141804a7151ec2b767f1415589ce166e9561f195856378ef34b6d9090a77f49ac3ac69c4e4e5b157b5e70635f5a270bb89bcade69a2d2ebb0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d3851bafbc5aeab21deb55475a06c062ac7a755ddef20f2cb273f0456b3c43d1054f64c8617eb55301e36f64a0c847e59b51575f85f87e4c22ed1e217cb4df66"} build_url:https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010872 build_url: 'https://queue.taskcluster.net/v1/task/Pn3J4KoaQeycQHMc7oPPBw/artifacts/public/build/firefox-51.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/3886121477d27c1e5b5247762298df768060764ac7c98507ef7ffce109b4ceac2ff034b55583543615210454ae392040353f257f1ae67e729c559b5054e45272", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/627cee6b711ad7141804a7151ec2b767f1415589ce166e9561f195856378ef34b6d9090a77f49ac3ac69c4e4e5b157b5e70635f5a270bb89bcade69a2d2ebb0f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d3851bafbc5aeab21deb55475a06c062ac7a755ddef20f2cb273f0456b3c43d1054f64c8617eb55301e36f64a0c847e59b51575f85f87e4c22ed1e217cb4df66"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-08-26 20:19:51.617794) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 20:19:51.618188) ========= 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.iHTWVpw1I4/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.izKepz8ax0/Listeners TMPDIR=/var/folders/46/tpjncvvj3m5c4y043tjxh8th00000w/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.004697 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-08-26 20:19:51.666933) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 20:19:51.667261) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-08-26 20:19:51.667593) ========= ========= Total master_lag: 0.18 =========