builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0125 starttime: 1488645578.74 results: success (0) buildid: 20170304075924 builduid: dee96865dce64f27bd52b2ec620ec9c8 revision: eb23648534779c110f3a1f2baae1849ae4a9c570 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:38.745139) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:38.745628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:38.745899) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:38.770681) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:38.770982) ========= 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.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-03-04 08:39:38-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 143M=0s 2017-03-04 08:39:39 (143 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.465620 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:39.260292) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:39.260561) ========= 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.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.020653 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:39.308422) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-04 08:39:39.308756) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev eb23648534779c110f3a1f2baae1849ae4a9c570 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev eb23648534779c110f3a1f2baae1849ae4a9c570 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-03-04 08:39:39,391 truncating revision to first 12 chars 2017-03-04 08:39:39,391 Setting DEBUG logging. 2017-03-04 08:39:39,391 attempt 1/10 2017-03-04 08:39:39,391 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/eb2364853477?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-04 08:39:40,954 unpacking tar archive at: mozilla-central-eb2364853477/testing/mozharness/ program finished with exit code 0 elapsedTime=1.804356 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-04 08:39:41.136612) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:41.136934) ========= script_repo_revision: eb23648534779c110f3a1f2baae1849ae4a9c570 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:41.137338) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:41.137606) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-04 08:39:41.157250) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-04 08:39:41.157519) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:39:41 INFO - MultiFileLogger online at 20170304 08:39:41 in /builds/slave/test 08:39:41 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 08:39:41 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 08:39:41 INFO - {'append_to_log': False, 08:39:41 INFO - 'base_work_dir': '/builds/slave/test', 08:39:41 INFO - 'blob_upload_branch': 'Firefox', 08:39:41 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 08:39:41 INFO - 'branch': 'Firefox', 08:39:41 INFO - 'buildbot_json_path': 'buildprops.json', 08:39:41 INFO - 'config_files': ('talos/mac_config.py',), 08:39:41 INFO - 'default_actions': ('clobber', 08:39:41 INFO - 'read-buildbot-config', 08:39:41 INFO - 'download-and-extract', 08:39:41 INFO - 'populate-webroot', 08:39:41 INFO - 'create-virtualenv', 08:39:41 INFO - 'install', 08:39:41 INFO - 'run-tests'), 08:39:41 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 08:39:41 INFO - 'download_minidump_stackwalk': True, 08:39:41 INFO - 'download_symbols': 'ondemand', 08:39:41 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 08:39:41 INFO - 'tooltool.py': '/tools/tooltool.py', 08:39:41 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 08:39:41 INFO - '/tools/misc-python/virtualenv.py')}, 08:39:41 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 08:39:41 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 08:39:41 INFO - 'gecko_profile': False, 08:39:41 INFO - 'gecko_profile_interval': 0, 08:39:41 INFO - 'installer_path': 'installer.exe', 08:39:41 INFO - 'log_level': 'info', 08:39:41 INFO - 'log_name': 'talos', 08:39:41 INFO - 'log_to_console': True, 08:39:41 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 08:39:41 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 08:39:41 INFO - 'opt_config_files': (), 08:39:41 INFO - 'pip_index': False, 08:39:41 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:41 INFO - 'cmd': ('bash', 08:39:41 INFO - '-c', 08:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:41 INFO - 'enabled': True, 08:39:41 INFO - 'halt_on_failure': False, 08:39:41 INFO - 'name': 'check_screen_resolution'},), 08:39:41 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 08:39:41 INFO - 'cmd': ('bash', 08:39:41 INFO - '-c', 08:39:41 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 08:39:41 INFO - 'enabled': True, 08:39:41 INFO - 'halt_on_failure': False, 08:39:41 INFO - 'name': 'check_screen_resolution'},), 08:39:41 INFO - 'run_cmd_checks_enabled': True, 08:39:41 INFO - 'suite': 'dromaeojs-e10s', 08:39:41 INFO - 'system_bits': '32', 08:39:41 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 08:39:41 INFO - 'title': 't-yosemite-r7-0125', 08:39:41 INFO - 'tooltool_cache': '/builds/tooltool_cache', 08:39:41 INFO - 'use_talos_json': True, 08:39:41 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 08:39:41 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 08:39:41 INFO - 'work_dir': 'build'} 08:39:41 INFO - [mozharness: 2017-03-04 16:39:41.369724Z] Running clobber step. 08:39:41 INFO - Running pre-action listener: _resource_record_pre_action 08:39:41 INFO - Running main action method: clobber 08:39:41 INFO - rmtree: /builds/slave/test/build 08:39:41 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 08:39:42 INFO - Running post-action listener: _resource_record_post_action 08:39:42 INFO - [mozharness: 2017-03-04 16:39:42.008942Z] Finished clobber step (success) 08:39:42 INFO - [mozharness: 2017-03-04 16:39:42.009107Z] Running read-buildbot-config step. 08:39:42 INFO - Running pre-action listener: _resource_record_pre_action 08:39:42 INFO - Running main action method: read_buildbot_config 08:39:42 INFO - Using buildbot properties: 08:39:42 INFO - { 08:39:42 INFO - "project": "", 08:39:42 INFO - "product": "firefox", 08:39:42 INFO - "script_repo_revision": "production", 08:39:42 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 08:39:42 INFO - "repository": "", 08:39:42 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 08:39:42 INFO - "buildid": "20170304075924", 08:39:42 INFO - "pgo_build": "False", 08:39:42 INFO - "basedir": "/builds/slave/test", 08:39:42 INFO - "buildnumber": 2, 08:39:42 INFO - "slavename": "t-yosemite-r7-0125", 08:39:42 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 08:39:42 INFO - "platform": "yosemite_r7", 08:39:42 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 08:39:42 INFO - "branch": "mozilla-central", 08:39:42 INFO - "repo_path": "mozilla-central", 08:39:42 INFO - "revision": "eb23648534779c110f3a1f2baae1849ae4a9c570", 08:39:42 INFO - "stage_platform": "macosx64", 08:39:42 INFO - "builduid": "dee96865dce64f27bd52b2ec620ec9c8", 08:39:42 INFO - "slavebuilddir": "test" 08:39:42 INFO - } 08:39:42 INFO - Found installer url https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg. 08:39:42 INFO - Running post-action listener: _resource_record_post_action 08:39:42 INFO - [mozharness: 2017-03-04 16:39:42.012691Z] Finished read-buildbot-config step (success) 08:39:42 INFO - [mozharness: 2017-03-04 16:39:42.012811Z] Running download-and-extract step. 08:39:42 INFO - Running pre-action listener: _resource_record_pre_action 08:39:42 INFO - Running main action method: download_and_extract 08:39:42 INFO - mkdir: /builds/slave/test/build/tests 08:39:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:39:42 INFO - trying https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json 08:39:42 INFO - Downloading https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:39:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json'}, attempt #1 08:39:43 INFO - Downloaded 1199 bytes. 08:39:43 INFO - Reading from file /builds/slave/test/build/firefox-54.0a1.en-US.mac.test_packages.json 08:39:43 INFO - Using the following test package requirements: 08:39:43 INFO - {u'common': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:39:43 INFO - u'cppunittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.cppunittest.tests.zip'], 08:39:43 INFO - u'gtest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.gtest.tests.zip'], 08:39:43 INFO - u'jittest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'jsshell-mac.zip'], 08:39:43 INFO - u'mochitest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.mochitest.tests.zip'], 08:39:43 INFO - u'mozbase': [u'firefox-54.0a1.en-US.mac.common.tests.zip'], 08:39:43 INFO - u'reftest': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.reftest.tests.zip'], 08:39:43 INFO - u'talos': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.talos.tests.zip'], 08:39:43 INFO - u'web-platform': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.web-platform.tests.zip'], 08:39:43 INFO - u'xpcshell': [u'firefox-54.0a1.en-US.mac.common.tests.zip', 08:39:43 INFO - u'firefox-54.0a1.en-US.mac.xpcshell.tests.zip']} 08:39:43 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip'] for test suite category: common 08:39:43 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:39:43 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:39:43 INFO - Fetch https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:39:44 INFO - Expected file size: 51775332 08:39:44 INFO - Obtained file size: 51775332 08:40:00 INFO - Downloading packages: [u'firefox-54.0a1.en-US.mac.common.tests.zip', u'firefox-54.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 08:40:00 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip 08:40:00 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip'}, attempt #1 08:40:00 INFO - Fetch https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.common.tests.zip into memory 08:40:02 INFO - Expected file size: 51775332 08:40:02 INFO - Obtained file size: 51775332 08:40:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip 08:40:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip'}, attempt #1 08:40:17 INFO - Fetch https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.talos.tests.zip into memory 08:40:18 INFO - Expected file size: 14404424 08:40:18 INFO - Obtained file size: 14404424 08:40: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')]} 08:40:19 INFO - trying https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:40:19 INFO - Downloading https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg 08:40:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg'}, attempt #1 08:40:21 INFO - Downloaded 75121876 bytes. 08:40:21 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:40:21 INFO - mkdir: /builds/slave/test/properties 08:40:21 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 08:40:21 INFO - Writing to file /builds/slave/test/properties/build_url 08:40:21 INFO - Contents: 08:40:21 INFO - build_url:https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg 08:40:22 INFO - Running post-action listener: _resource_record_post_action 08:40:22 INFO - Running post-action listener: set_extra_try_arguments 08:40:22 INFO - [mozharness: 2017-03-04 16:40:22.039258Z] Finished download-and-extract step (success) 08:40:22 INFO - [mozharness: 2017-03-04 16:40:22.039410Z] Running populate-webroot step. 08:40:22 INFO - Running pre-action listener: _resource_record_pre_action 08:40:22 INFO - Running main action method: populate_webroot 08:40:22 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 08:40:22 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 08:40:22 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 08:40:22 INFO - '--tppagecycles', 08:40:22 INFO - '7'], 08:40:22 INFO - 'tests': ['tsvgm']}}, 08:40:22 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['tresize', 'tcanvasmark']}, 08:40:22 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 08:40:22 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['dromaeo_css', 'kraken']}, 08:40:22 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 08:40:22 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:22 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 08:40:22 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['damp', 'tps']}, 08:40:22 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'tests': ['damp', 'tps']}, 08:40:22 INFO - 'g3': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['dromaeo_dom']}, 08:40:22 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 08:40:22 INFO - 'g4': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['basic_compositor_video']}, 08:40:22 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 08:40:22 INFO - 'other': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'other-e10s': {'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'other-e10s_l64': {'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['a11yr', 08:40:22 INFO - 'ts_paint', 08:40:22 INFO - 'tpaint', 08:40:22 INFO - 'sessionrestore', 08:40:22 INFO - 'sessionrestore_no_auto_restore', 08:40:22 INFO - 'tabpaint']}, 08:40:22 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['tsvgx', 08:40:22 INFO - 'tsvgr_opacity', 08:40:22 INFO - 'tart', 08:40:22 INFO - 'tscrollx', 08:40:22 INFO - 'cart', 08:40:22 INFO - 'tsvg_static']}, 08:40:22 INFO - 'svgr-e10s': {'tests': ['tsvgx', 08:40:22 INFO - 'tsvgr_opacity', 08:40:22 INFO - 'tart', 08:40:22 INFO - 'tscrollx', 08:40:22 INFO - 'cart', 08:40:22 INFO - 'tsvg_static']}, 08:40:22 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'talos_options': ['--disable-e10s'], 08:40:22 INFO - 'tests': ['tp5o']}, 08:40:22 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 08:40:22 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'talos_options': ['--disable-e10s', 08:40:22 INFO - '--xperf_path', 08:40:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:22 INFO - 'tests': ['tp5n']}, 08:40:22 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 08:40:22 INFO - 'talos_options': ['--xperf_path', 08:40:22 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 08:40:22 INFO - 'tests': ['tp5n']}}, 08:40:22 INFO - 'talos.zip': {'path': '', 08:40:22 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 08:40:22 INFO - Running post-action listener: _resource_record_post_action 08:40:22 INFO - [mozharness: 2017-03-04 16:40:22.057993Z] Finished populate-webroot step (success) 08:40:22 INFO - [mozharness: 2017-03-04 16:40:22.058119Z] Running create-virtualenv step. 08:40:22 INFO - Running pre-action listener: _resource_record_pre_action 08:40:22 INFO - Running main action method: create_virtualenv 08:40:22 INFO - Creating virtualenv /builds/slave/test/build/venv 08:40:22 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 08:40:22 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 08:40:22 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 08:40:22 INFO - Using real prefix '/tools/python27' 08:40:22 INFO - New python executable in /builds/slave/test/build/venv/bin/python 08:40:22 INFO - Installing distribute.............................................................................................................................................................................................done. 08:40:25 INFO - Installing pip.................done. 08:40:25 INFO - Return code: 0 08:40:25 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 08:40:25 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 08:40:26 INFO - Reading from file tmpfile_stdout 08:40:26 INFO - Output received: 08:40:26 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 08:40:26 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 08:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:26 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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:26 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 08:40:26 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 08:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:26 INFO - 'HOME': '/Users/cltbld', 08:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:26 INFO - 'LOGNAME': 'cltbld', 08:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:26 INFO - 'MOZ_NO_REMOTE': '1', 08:40:26 INFO - 'NO_EM_RESTART': '1', 08:40:26 INFO - 'PAGER': '/bin/cat', 08:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:26 INFO - 'PWD': '/builds/slave/test', 08:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:26 INFO - 'SHELL': '/bin/bash', 08:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:26 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:26 INFO - 'USER': 'cltbld', 08:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:26 INFO - 'XPC_FLAGS': '0x0', 08:40:26 INFO - 'XPC_SERVICE_NAME': '0', 08:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:26 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 08:40:26 INFO - Cleaning up... 08:40:26 INFO - Return code: 0 08:40:26 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 08:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:26 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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:26 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 08:40:26 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 08:40:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:26 INFO - 'HOME': '/Users/cltbld', 08:40:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:26 INFO - 'LOGNAME': 'cltbld', 08:40:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:26 INFO - 'MOZ_NO_REMOTE': '1', 08:40:26 INFO - 'NO_EM_RESTART': '1', 08:40:26 INFO - 'PAGER': '/bin/cat', 08:40:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:26 INFO - 'PWD': '/builds/slave/test', 08:40:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:26 INFO - 'SHELL': '/bin/bash', 08:40:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:26 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:26 INFO - 'USER': 'cltbld', 08:40:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:26 INFO - 'XPC_FLAGS': '0x0', 08:40:26 INFO - 'XPC_SERVICE_NAME': '0', 08:40:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:26 INFO - Downloading/unpacking psutil>=3.1.1 08:40:26 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 08:40:26 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 08:40:26 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 08:40:26 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 08:40:26 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 08:40:26 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 08:40:30 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 08:40:30 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:30 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:30 INFO - Installing collected packages: psutil 08:40:30 INFO - Running setup.py install for psutil 08:40:30 INFO - building 'psutil._psutil_osx' extension 08:40:30 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 08:40:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 08:40:31 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 08:40:31 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 08:40:31 INFO - building 'psutil._psutil_posix' extension 08:40:31 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 08:40:31 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 08:40:31 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 08:40:31 INFO - ^ 08:40:31 INFO - 1 warning generated. 08:40:31 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 08:40:31 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 08:40:31 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 08:40:31 INFO - Successfully installed psutil 08:40:31 INFO - Cleaning up... 08:40:31 INFO - Return code: 0 08:40:31 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 08:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:31 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:31 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:31 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:31 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:31 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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:31 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 08:40:31 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 08:40:31 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:31 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:31 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:31 INFO - 'HOME': '/Users/cltbld', 08:40:31 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:31 INFO - 'LOGNAME': 'cltbld', 08:40:31 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:31 INFO - 'MOZ_NO_REMOTE': '1', 08:40:31 INFO - 'NO_EM_RESTART': '1', 08:40:31 INFO - 'PAGER': '/bin/cat', 08:40:31 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:31 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:31 INFO - 'PWD': '/builds/slave/test', 08:40:31 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:31 INFO - 'SHELL': '/bin/bash', 08:40:31 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:31 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:31 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:31 INFO - 'USER': 'cltbld', 08:40:31 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:31 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:31 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:31 INFO - 'XPC_FLAGS': '0x0', 08:40:31 INFO - 'XPC_SERVICE_NAME': '0', 08:40:31 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:31 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:31 INFO - Downloading/unpacking mozsystemmonitor==0.3 08:40:31 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 08:40:31 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 08:40:31 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 08:40:31 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 08:40:31 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 08:40:31 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 08:40:34 INFO - Downloading mozsystemmonitor-0.3.tar.gz 08:40:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 08:40:34 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 08:40:34 INFO - Installing collected packages: mozsystemmonitor 08:40:34 INFO - Running setup.py install for mozsystemmonitor 08:40:34 INFO - Successfully installed mozsystemmonitor 08:40:34 INFO - Cleaning up... 08:40:34 INFO - Return code: 0 08:40:34 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 08:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:34 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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:34 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 08:40:34 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 08:40:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:34 INFO - 'HOME': '/Users/cltbld', 08:40:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:34 INFO - 'LOGNAME': 'cltbld', 08:40:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:34 INFO - 'MOZ_NO_REMOTE': '1', 08:40:34 INFO - 'NO_EM_RESTART': '1', 08:40:34 INFO - 'PAGER': '/bin/cat', 08:40:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:34 INFO - 'PWD': '/builds/slave/test', 08:40:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:34 INFO - 'SHELL': '/bin/bash', 08:40:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:34 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:34 INFO - 'USER': 'cltbld', 08:40:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:34 INFO - 'XPC_FLAGS': '0x0', 08:40:34 INFO - 'XPC_SERVICE_NAME': '0', 08:40:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:35 INFO - Downloading/unpacking jsonschema==2.5.1 08:40:35 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 08:40:35 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 08:40:35 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 08:40:35 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 08:40:35 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 08:40:35 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 08:40:38 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 08:40:38 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 08:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:38 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:38 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:38 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:38 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:39 INFO - Downloading functools32-3.2.3-2.tar.gz 08:40:39 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 08:40:39 INFO - warning: no files found matching '*.txt' 08:40:39 INFO - no previously-included directories found matching 'build' 08:40:39 INFO - no previously-included directories found matching 'dist' 08:40:39 INFO - no previously-included directories found matching '.git*' 08:40:39 INFO - Installing collected packages: jsonschema, functools32 08:40:39 INFO - Running setup.py install for functools32 08:40:39 INFO - warning: no files found matching '*.txt' 08:40:39 INFO - no previously-included directories found matching 'build' 08:40:39 INFO - no previously-included directories found matching 'dist' 08:40:39 INFO - no previously-included directories found matching '.git*' 08:40:39 INFO - Successfully installed jsonschema functools32 08:40:39 INFO - Cleaning up... 08:40:39 INFO - Return code: 0 08:40:39 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 08:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 08:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:39 INFO - 'HOME': '/Users/cltbld', 08:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:39 INFO - 'LOGNAME': 'cltbld', 08:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:39 INFO - 'MOZ_NO_REMOTE': '1', 08:40:39 INFO - 'NO_EM_RESTART': '1', 08:40:39 INFO - 'PAGER': '/bin/cat', 08:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:39 INFO - 'PWD': '/builds/slave/test', 08:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:39 INFO - 'SHELL': '/bin/bash', 08:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:39 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:39 INFO - 'USER': 'cltbld', 08:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:39 INFO - 'XPC_FLAGS': '0x0', 08:40:39 INFO - 'XPC_SERVICE_NAME': '0', 08:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:39 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 08:40:39 INFO - Cleaning up... 08:40:39 INFO - Return code: 0 08:40:39 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:39 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:39 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 08:40:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 08:40:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:39 INFO - 'HOME': '/Users/cltbld', 08:40:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:39 INFO - 'LOGNAME': 'cltbld', 08:40:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:39 INFO - 'MOZ_NO_REMOTE': '1', 08:40:39 INFO - 'NO_EM_RESTART': '1', 08:40:39 INFO - 'PAGER': '/bin/cat', 08:40:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:39 INFO - 'PWD': '/builds/slave/test', 08:40:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:39 INFO - 'SHELL': '/bin/bash', 08:40:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:39 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:39 INFO - 'USER': 'cltbld', 08:40:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:39 INFO - 'XPC_FLAGS': '0x0', 08:40:39 INFO - 'XPC_SERVICE_NAME': '0', 08:40:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:39 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:39 INFO - Downloading/unpacking blobuploader==1.2.4 08:40:39 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 08:40:39 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 08:40:39 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 08:40:39 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 08:40:39 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 08:40:39 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 08:40:42 INFO - Downloading blobuploader-1.2.4.tar.gz 08:40:42 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 08:40:42 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 08:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 08:40:43 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 08:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:43 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:43 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:43 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:43 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:43 INFO - Downloading docopt-0.6.1.tar.gz 08:40:43 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 08:40:44 INFO - Installing collected packages: blobuploader, requests, docopt 08:40:44 INFO - Running setup.py install for blobuploader 08:40:44 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 08:40:44 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 08:40:44 INFO - Running setup.py install for requests 08:40:44 INFO - Running setup.py install for docopt 08:40:44 INFO - Successfully installed blobuploader requests docopt 08:40:44 INFO - Cleaning up... 08:40:44 INFO - Return code: 0 08:40:44 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:44 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 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:44 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 08:40:44 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 08:40:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:44 INFO - 'HOME': '/Users/cltbld', 08:40:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:44 INFO - 'LOGNAME': 'cltbld', 08:40:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:44 INFO - 'MOZ_NO_REMOTE': '1', 08:40:44 INFO - 'NO_EM_RESTART': '1', 08:40:44 INFO - 'PAGER': '/bin/cat', 08:40:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:44 INFO - 'PWD': '/builds/slave/test', 08:40:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:44 INFO - 'SHELL': '/bin/bash', 08:40:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:44 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:44 INFO - 'USER': 'cltbld', 08:40:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:44 INFO - 'XPC_FLAGS': '0x0', 08:40:44 INFO - 'XPC_SERVICE_NAME': '0', 08:40:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:45 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-NbWPSW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-XHI2fr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-b1e7Wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-6pMdvF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_BVHG1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-cgUQmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-7Ipd9B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:45 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:45 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-viJ_Ve-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-jk2I2n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-T5KYTb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-PufF59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-2ZK71i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-mtRysl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-MkVxgs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-vfuMEO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-HuQ8V2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:46 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-3XLiGP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:47 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 08:40:47 INFO - Running setup.py install for manifestparser 08:40:47 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 08:40:47 INFO - Running setup.py install for mozcrash 08:40:47 INFO - Running setup.py install for mozdebug 08:40:47 INFO - Running setup.py install for mozdevice 08:40:47 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 08:40:47 INFO - Installing dm script to /builds/slave/test/build/venv/bin 08:40:47 INFO - Running setup.py install for mozfile 08:40:47 INFO - Running setup.py install for mozhttpd 08:40:47 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 08:40:47 INFO - Running setup.py install for mozinfo 08:40:48 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Running setup.py install for mozInstall 08:40:48 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Running setup.py install for mozleak 08:40:48 INFO - Running setup.py install for mozlog 08:40:48 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Running setup.py install for moznetwork 08:40:48 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Running setup.py install for mozprocess 08:40:48 INFO - Running setup.py install for mozprofile 08:40:48 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 08:40:48 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 08:40:49 INFO - Running setup.py install for mozrunner 08:40:49 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 08:40:49 INFO - Running setup.py install for mozscreenshot 08:40:49 INFO - Running setup.py install for moztest 08:40:49 INFO - Running setup.py install for mozversion 08:40:49 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 08:40:49 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 08:40:49 INFO - Cleaning up... 08:40:49 INFO - Return code: 0 08:40:49 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40: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')]} 08:40:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40: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')]} 08:40:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 08:40:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:40:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:49 INFO - 'HOME': '/Users/cltbld', 08:40:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:49 INFO - 'LOGNAME': 'cltbld', 08:40:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:49 INFO - 'MOZ_NO_REMOTE': '1', 08:40:49 INFO - 'NO_EM_RESTART': '1', 08:40:49 INFO - 'PAGER': '/bin/cat', 08:40:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:49 INFO - 'PWD': '/builds/slave/test', 08:40:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:49 INFO - 'SHELL': '/bin/bash', 08:40:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:49 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:49 INFO - 'USER': 'cltbld', 08:40:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:49 INFO - 'XPC_FLAGS': '0x0', 08:40:49 INFO - 'XPC_SERVICE_NAME': '0', 08:40:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:49 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 08:40:49 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-f1iv2B-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 08:40:50 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)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-kSKLXy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-5radd8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-GeJLEc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ENlbKp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-U2QL_G-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-Sv9bFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-NZfwGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-JPWT_M-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 08:40:50 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 08:40:50 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 08:40:50 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ak4Jyr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ja0MXJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-6NBShn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ss6XwO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-ZM3Tf1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-_i9Q8t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-JTlt1o-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 08:40:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 08:40:51 INFO - Running setup.py (path:/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/pip-3YuCrS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:40:51 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 08:40:51 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 08:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:51 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:51 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 08:40:51 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 08:40:51 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 08:40:54 INFO - Downloading blessings-1.6.tar.gz 08:40:54 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 08:40:54 INFO - Installing collected packages: blessings 08:40:54 INFO - Running setup.py install for blessings 08:40:54 INFO - Successfully installed blessings 08:40:54 INFO - Cleaning up... 08:40:54 INFO - Return code: 0 08:40:54 INFO - Done creating virtualenv /builds/slave/test/build/venv. 08:40:54 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:55 INFO - Reading from file tmpfile_stdout 08:40:55 INFO - Current package versions: 08:40:55 INFO - blessings == 1.6 08:40:55 INFO - blobuploader == 1.2.4 08:40:55 INFO - docopt == 0.6.1 08:40:55 INFO - functools32 == 3.2.3-2 08:40:55 INFO - jsonschema == 2.5.1 08:40:55 INFO - manifestparser == 1.1 08:40:55 INFO - mozInstall == 1.12 08:40:55 INFO - mozcrash == 1.0 08:40:55 INFO - mozdebug == 0.1 08:40:55 INFO - mozdevice == 0.49 08:40:55 INFO - mozfile == 1.2 08:40:55 INFO - mozhttpd == 0.7 08:40:55 INFO - mozinfo == 0.9 08:40:55 INFO - mozleak == 0.1 08:40:55 INFO - mozlog == 3.4 08:40:55 INFO - moznetwork == 0.27 08:40:55 INFO - mozprocess == 0.25 08:40:55 INFO - mozprofile == 0.28 08:40:55 INFO - mozrunner == 6.13 08:40:55 INFO - mozscreenshot == 0.1 08:40:55 INFO - mozsystemmonitor == 0.3 08:40:55 INFO - moztest == 0.8 08:40:55 INFO - mozversion == 1.4 08:40:55 INFO - psutil == 3.1.1 08:40:55 INFO - requests == 1.2.3 08:40:55 INFO - wsgiref == 0.1.2 08:40:55 INFO - Installing None into virtualenv /builds/slave/test/build/venv 08:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 08:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 08:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:55 INFO - 'HOME': '/Users/cltbld', 08:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:55 INFO - 'LOGNAME': 'cltbld', 08:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:55 INFO - 'MOZ_NO_REMOTE': '1', 08:40:55 INFO - 'NO_EM_RESTART': '1', 08:40:55 INFO - 'PAGER': '/bin/cat', 08:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:55 INFO - 'PWD': '/builds/slave/test', 08:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:55 INFO - 'SHELL': '/bin/bash', 08:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:55 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:55 INFO - 'USER': 'cltbld', 08:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:55 INFO - 'XPC_FLAGS': '0x0', 08:40:55 INFO - 'XPC_SERVICE_NAME': '0', 08:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 08:40:55 INFO - Cleaning up... 08:40:55 INFO - Return code: 0 08:40:55 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 08:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 08:40:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 08:40:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 08:40:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 08:40:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10c16db70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10c170768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe0a3567000>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, '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.Rc8THygecd/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.zrcmJqjCJE/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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 08:40:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 08:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 08:40:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:40:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:40:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:40:55 INFO - 'HOME': '/Users/cltbld', 08:40:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:40:55 INFO - 'LOGNAME': 'cltbld', 08:40:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:40:55 INFO - 'MOZ_NO_REMOTE': '1', 08:40:55 INFO - 'NO_EM_RESTART': '1', 08:40:55 INFO - 'PAGER': '/bin/cat', 08:40:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:40:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:40:55 INFO - 'PWD': '/builds/slave/test', 08:40:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:40:55 INFO - 'SHELL': '/bin/bash', 08:40:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:40:55 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:40:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:40:55 INFO - 'USER': 'cltbld', 08:40:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:40:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:40:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:40:55 INFO - 'XPC_FLAGS': '0x0', 08:40:55 INFO - 'XPC_SERVICE_NAME': '0', 08:40:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:40:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 08:40:55 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 08:40:55 INFO - Cleaning up... 08:40:55 INFO - Return code: 0 08:40:55 INFO - Running post-action listener: _resource_record_post_action 08:40:55 INFO - Running post-action listener: _start_resource_monitoring 08:40:55 INFO - Starting resource monitoring. 08:40:55 INFO - [mozharness: 2017-03-04 16:40:55.582428Z] Finished create-virtualenv step (success) 08:40:55 INFO - [mozharness: 2017-03-04 16:40:55.583385Z] Running install step. 08:40:55 INFO - Running pre-action listener: _resource_record_pre_action 08:40:55 INFO - Running main action method: install 08:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 08:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 08:40:55 INFO - Reading from file tmpfile_stdout 08:40:55 INFO - Detecting whether we're running mozinstall >=1.0... 08:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 08:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 08:40:55 INFO - Reading from file tmpfile_stdout 08:40:55 INFO - Output received: 08:40:55 INFO - Usage: mozinstall [options] installer 08:40:55 INFO - Options: 08:40:55 INFO - -h, --help show this help message and exit 08:40:55 INFO - -d DEST, --destination=DEST 08:40:55 INFO - Directory to install application into. [default: 08:40:55 INFO - "/builds/slave/test"] 08:40:55 INFO - --app=APP Application being installed. [default: firefox] 08:40:55 INFO - mkdir: /builds/slave/test/build/application 08:40:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 08:40:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 08:41:15 INFO - Reading from file tmpfile_stdout 08:41:15 INFO - Output received: 08:41:15 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 08:41:15 INFO - Running post-action listener: _resource_record_post_action 08:41:15 INFO - [mozharness: 2017-03-04 16:41:15.994341Z] Finished install step (success) 08:41:15 INFO - [mozharness: 2017-03-04 16:41:15.994487Z] Running run-tests step. 08:41:15 INFO - Running pre-action listener: _resource_record_pre_action 08:41:15 INFO - Running main action method: run_tests 08:41:15 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 08:41:16 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 08:41:16 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 08:41:16 INFO - 2017-03-04 08:41:16.009 screenresolution[37099:315091] starting screenresolution argv=screenresolution get 08:41:16 INFO - 2017-03-04 08:41:16.012 screenresolution[37099:315091] Display 0: 1600x1200x32@60 08:41:16 INFO - 2017-03-04 08:41:16.017 screenresolution[37100:315093] starting screenresolution argv=screenresolution list 08:41:16 INFO - Available Modes on Display 0 08:41:16 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 08:41:16 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 08:41:16 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 08:41:16 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 08:41:16 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 08:41:16 INFO - Intel Iris: 08:41:16 INFO - Chipset Model: Intel Iris 08:41:16 INFO - Type: GPU 08:41:16 INFO - Bus: Built-In 08:41:16 INFO - VRAM (Dynamic, Max): 1536 MB 08:41:16 INFO - Vendor: Intel (0x8086) 08:41:16 INFO - Device ID: 0x0a2e 08:41:16 INFO - Revision ID: 0x0009 08:41:16 INFO - Displays: 08:41:16 INFO - Display: 08:41:16 INFO - Resolution: 1600 x 1200 @ 60 Hz 08:41:16 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 08:41:16 INFO - Display Serial Number: 1600x1200 60 08:41:16 INFO - Main Display: Yes 08:41:16 INFO - Mirror: Off 08:41:16 INFO - Online: Yes 08:41:16 INFO - Rotation: Supported 08:41:16 INFO - Return code: 0 08:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 08:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 08:41:16 INFO - Python 2.7.3 08:41:16 INFO - Return code: 0 08:41:16 INFO - grabbing minidump binary from tooltool 08:41:16 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')]} 08:41:16 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 0x10bf4bd30>, '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 0x10be72db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10bf40be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 08:41:16 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 08:41:16 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 08:41:16 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 08:41:16 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 08:41:16 INFO - Return code: 0 08:41:16 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 08:41:16 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 08:41:16 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 08:41:16 INFO - ENV: RUST_BACKTRACE is now 1 08:41:16 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 08:41:16 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 08:41:16 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] in /builds/slave/test/build 08:41:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0125 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log 08:41:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.Rc8THygecd/Render', 08:41:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 08:41:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 08:41:16 INFO - 'HOME': '/Users/cltbld', 08:41:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 08:41:16 INFO - 'LOGNAME': 'cltbld', 08:41:16 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 08:41:16 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 08:41:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 08:41:16 INFO - 'MOZ_NO_REMOTE': '1', 08:41:16 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 08:41:16 INFO - 'NO_EM_RESTART': '1', 08:41:16 INFO - 'PAGER': '/bin/cat', 08:41:16 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 08:41:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 08:41:16 INFO - 'PWD': '/builds/slave/test', 08:41:16 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 08:41:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 08:41:16 INFO - 'RUST_BACKTRACE': '1', 08:41:16 INFO - 'SHELL': '/bin/bash', 08:41:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.zrcmJqjCJE/Listeners', 08:41:16 INFO - 'TMPDIR': '/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/', 08:41:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 08:41:16 INFO - 'USER': 'cltbld', 08:41:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 08:41:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 08:41:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 08:41:16 INFO - 'XPC_FLAGS': '0x0', 08:41:16 INFO - 'XPC_SERVICE_NAME': '0', 08:41:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 08:41:16 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0125', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log'] with output_timeout 3600 08:41:16 INFO - mozversion application_buildid: 20170304075924 08:41:16 INFO - mozversion application_changeset: eb23648534779c110f3a1f2baae1849ae4a9c570 08:41:16 INFO - mozversion application_display_name: Nightly 08:41:16 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 08:41:16 INFO - mozversion application_name: Firefox 08:41:16 INFO - mozversion application_remotingname: firefox 08:41:16 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 08:41:16 INFO - mozversion application_vendor: Mozilla 08:41:16 INFO - mozversion application_version: 54.0a1 08:41:16 INFO - mozversion platform_buildid: 20170304075924 08:41:16 INFO - mozversion platform_changeset: eb23648534779c110f3a1f2baae1849ae4a9c570 08:41:16 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 08:41:16 INFO - mozversion platform_version: 54.0a1 08:41:16 INFO - using testdate: 1488645676 08:41:16 INFO - actual date: 1488645676 08:41:16 INFO - starting webserver on 'localhost:49299' 08:41:16 INFO - SUITE-START | Running 2 tests 08:41:16 INFO - TEST-START | dromaeo_css 08:41:16 INFO - operating with platform_type : mac_ 08:41:16 INFO - Initialising browser for dromaeo_css test... 08:41:16 INFO - TEST-INFO | started process 37110 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpQdfG5i/profile http://localhost:49299/getInfo.html) 08:41:19 INFO - PROCESS | 37110 | 2017-03-04 08:41:19.783 plugin-container[37112:315275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 08:41:19 INFO - PROCESS | 37110 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:19 INFO - PROCESS | 37110 | 2017-03-04 08:41:19.786 plugin-container[37112:315275] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a2f, name = 'com.apple.CFPasteboardClient' 08:41:19 INFO - PROCESS | 37110 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:19 INFO - PROCESS | 37110 | 2017-03-04 08:41:19.786 plugin-container[37112:315275] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:19 INFO - PROCESS | 37110 | __metrics Screen width/height:1600/1200 08:41:19 INFO - PROCESS | 37110 | colorDepth:24 08:41:19 INFO - PROCESS | 37110 | Browser inner width/height: 1024/689 08:41:19 INFO - PROCESS | 37110 | __metrics 08:41:19 ERROR - PROCESS | 37110 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 08:41:20 INFO - TEST-INFO | 37110: exit 0 08:41:20 INFO - Browser initialized. 08:41:20 INFO - Running cycle 1/1 for dromaeo_css test... 08:41:20 INFO - TEST-INFO | started process 37114 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpQdfG5i/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 08:41:23 INFO - PROCESS | 37114 | 2017-03-04 08:41:23.339 plugin-container[37115:315419] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 08:41:23 INFO - PROCESS | 37114 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:23 INFO - PROCESS | 37114 | 2017-03-04 08:41:23.339 plugin-container[37115:315419] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4417, name = 'com.apple.CFPasteboardClient' 08:41:23 INFO - PROCESS | 37114 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:41:23 INFO - PROCESS | 37114 | 2017-03-04 08:41:23.339 plugin-container[37115:315419] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:41:33 INFO - PROCESS | 37114 | RSS: Main: 226799616 08:41:33 INFO - PROCESS | 37114 | 08:44:36 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49299/tests/dromaeo/cssquery-ext.html) 08:44:38 INFO - PROCESS | 37114 | RSS: Main: 252686336 08:44:38 INFO - PROCESS | 37114 | 08:46:21 INFO - PROCESS | 37114 | ************************* 08:46:21 INFO - PROCESS | 37114 | A coding exception was thrown and uncaught in a Task. 08:46:21 INFO - PROCESS | 37114 | 08:46:21 ERROR - PROCESS | 37114 | Full message: TypeError: NetworkError when attempting to fetch resource. 08:46:21 INFO - PROCESS | 37114 | Full stack: 08:46:21 INFO - PROCESS | 37114 | ************************* 08:47:40 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-ext.html (next: http://localhost:49299/tests/dromaeo/cssquery-jquery.html) 08:47:41 INFO - PROCESS | 37114 | RSS: Main: 257503232 08:47:41 INFO - PROCESS | 37114 | 08:50:43 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49299/tests/dromaeo/cssquery-mootools.html) 08:50:43 INFO - PROCESS | 37114 | RSS: Main: 268886016 08:50:43 INFO - PROCESS | 37114 | 08:53:45 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49299/tests/dromaeo/cssquery-prototype.html) 08:53:46 INFO - PROCESS | 37114 | RSS: Main: 262553600 08:53:46 INFO - PROCESS | 37114 | 08:56:49 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49299/tests/dromaeo/cssquery-yui.html) 08:56:50 INFO - PROCESS | 37114 | RSS: Main: 272269312 08:56:50 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | Cycle 1(1): loaded http://localhost:49299/tests/dromaeo/cssquery-yui.html (next: http://localhost:49299/tests/dromaeo/cssquery-dojo.html) 08:59:53 INFO - PROCESS | 37114 | RSS: Main: 266186752 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | __start_tp_report 08:59:53 INFO - PROCESS | 37114 | _x_x_mozilla_page_load 08:59:53 INFO - PROCESS | 37114 | _x_x_mozilla_page_load_details 08:59:53 INFO - PROCESS | 37114 | |i|pagename|runs| 08:59:53 INFO - PROCESS | 37114 | |0;dojo.html;3380.611229708583;3414.7097496712777;3415.9829200854;3428.4514477683574;3438.209211881268;4605.953940460595;5750.763585829046;5873.676947767871;5881.529477641794;5945.108233764938;4831.444383895853;5078.5175408336245;5786.508146807516;5866.296044474659;5915.112733090039;5337.226102215176;5349.304590403242;5359.330083739533;5363.8122665706705;5444.237806707064;5313.149896016619;5371.81198658047;5474.972625136868;5475.342958844924;5534.778608855641;5211.088059589594;5223.791048358061;5262.97368513155;5374.328208973879;5405.999890020359;5250.474952504757;5253.527182553588;5286.5242128208265;5392.487713667196;5399.676019438846;5281.947180528184;5292.894142117175;5328.946710532884;5332.280142180794;5332.653377530487;5589.944100558983;5744.080947048494;5913;5918.970405147989;5999.13012613173;6168.599041062316;6217.347178546278;6230.5638605297645;6243.906341404882;6250.7812226572305;5547.846054010874;6200.852842224158;6408.07082972971;6411.326810684905;6415.101885736;6050.878982420415;6112.541559383019;6120.816375508697;6120.938790612126;6143.262808462969;6200.162977998003;6221.377862213742;6344.048392741033;6367.968160159169;6435.163428754232;6123.679189354777;6135.875804921915;6187.876242475215;6190.628562286277;6273.592216505913;6263.6241825490615;6305.085762564402;6305.243370795534;6308.810735677937;6332.145160403286;6144.969275153596;6247.750089996349;6250.9062364064575;6271.404216599416;6298.622082675054;6187.504999600022;6208.689565521706;6256.624602523772;6286.49708023357;6301.495880329563;4397.494288156839;5124;5136.614753893473;5158.716270605153;5163.199704045879;4569.977150114228;4577.748223847654;4599.264117741145;4602.401687780634;4632.119897219518;5738.68437235956;5805.332386775491;5969.403059694083;5993.130996005555;5998.820035398945;6213.2233470816145;6228.096925945803;6235.345288744707;6247.562670613104;6267.0286105653695;5945.970270148621;5995.400459954057;5998.550108741774;6006.849828754229;6012.759489620366;5588.748506317225;5612.859678507988;5614.606977511535;5616.185653080281;5618.241537392481;5452.536534394541;6124.045297608125;6259.65571893532;6394.968025159845;6437.259715132633;5307.628466007497;6292.465140463112;6343.55595108338;6347.873042539216;6382.074599183;6452.935470645233;6517.641529715911;6532.41208291256;6533.150690410406;6535.052417399355;5221.347331583552;6137.5302083125;6182;6199;6249.875002500015;5362.51737343641;6267;6357.96821015892;6368.681565921778;6370.936290637034;6080.817575472743;6121.452973575903;6212.161358216764;6245.375462453719;6246.437820596168;6120.90429240112;6213.658248796179;6219.502439804714;6233.189685340877;6248.625082495065;4748.762561871824;5929.680265267199;6026.337102918587;6090.969545152246;6139.4781443577795;5072.949270507248;6232.719527621177;6250.312465628868;6266.68666566679;6267.467265282502;6167.599106058092;6179.5056395489155;6201.658908760061;6210.31686514474;6217.96891015542 08:59:53 INFO - PROCESS | 37114 | |1;ext.html;786.4966421490245;810.3841080778607;829.8174401631643;857.4897935728244;916.1845957098179;36096;37007.88976330711;37014.88955331341;38399;38501;37812.43281350782;38211;38220.80889595548;38261.85214443571;38314.233715325674;7675.462717609769;7816.140224575292;7836.373090152802;10362.378257304548;10635.840462393031;10523.421211833345;10589.470526473684;10629.5216715248;10631.255812093157;10664.146868250522;10607.946960265188;10637.574497020149;10650.893491065068;10652.254342196049;10656.09423199029;10565.471726413649;10613.363198208093;10617.256792024562;10627.521761520711;10635.096016838577;10187.694369168938;10236.846447303296;10267.178625709925;10277.17782577392;10280.537375818107;33357.83321083403;33458;33493.827716030064;33505.32989340224;33616.83191584051;3664.2305115925687;3685.7419980601367;3702.5186725725484;3710.5361829771277;3836.9808150959348;22415;23519.76480235193;24956.750432495624;24984.750152498425;25238.029118895574;3040.802347847405;3065.126438964907;3115.9376812463624;3139.089663997438;3142.1673256587023;248495;288806.22387552133;296325.99999999785;296547;297177;33198.33603327968;34998.47502287468;40818.591814081476;41803.58196418058;43073;35524.822375887954;42836;43706.562934370886;43791.78104109523;43857.68426947197;12740.235585864877;12827.358632068359;12862.742745145044;12870.61388158338;12881.484740610458;8829.867551986725;8985.595648195847;10898.182636302308;10905.618303359344;10922;11026.44867756609;11725.003374713246;11746.765064698659;11835.644930651923;11875.346855923008;5471.097268950658;5547;5563.805266815642;5565.970295495346;5584.385717571064;990.8018396320765;1079.6005477973201;1081.6538707613645;1085.8642669666292;1131.2307630811129;26160.8691956539;26273.584434023836;26694.332641684112;26804.731952680224;27156.321091972462;1121.1423261205184;1126.774645070989;1156.1040193849833;1166.8191430328313;1182.4206138992004;12870.584235734063;13258.668533286553;13530;13616.183029018093;13869.445222191202;427016;428840;430135;430281;430490;55600.16599751007;55756;55757.16364254539;55856;55951;1240.4789988204975;1397.084909384355;1432.0548438030642;1439.4242303078852;1442.213993373614;20464.098117319012;27642.58536122;27826.88692452279;30209.395812083196;30652.233694157825;51819.481805181465;51826;51833.7408312956;52030;52345.7382713084;433124;437213;438111;438131;438533;19260.518487037934;23473.530529388976;23588.056477741386;23707.40731481727;23717.52564948726;41733.0786844918;42107.789461052496;43405;43424.78287608542;43445.69662910118;34810;35032.82483587668;35899.46150807792;35986.280274393845;36284.45573316455;34734.65265347314;34748.82625586856;35720.464193036605;35821;35989.82005089958;32585.837070814494;34682.30635387228;34814.65185348114;35732.82133589315;35989.28021439609;27369.86315068412;28242.15273541797;29073.709262907945;29857.10428687143;29978;20242.59514809725;22536.774632254124;22552.774472255067;22882.08471661115;22987.425314367276 08:59:53 INFO - PROCESS | 37114 | |2;jquery.html;392.4838836929436;396.6192455242967;407.40518842489973;421.0358279539854;428.5114968935414;12406;19391.418257452264;20330.186792528282;20722.585548289044;20744.89627551862;20564.280250191245;20990.580188396223;21235.46911327214;21360.57278854422;21397.465063373424;5687.232223649817;6088.756449742005;6553.705083271264;6595.34046595339;6599.406053455188;6313.147725057127;6466.418022377985;6538.934610653881;6587.011948207759;6593.967030164842;6576.539642225022;6587.538872278942;6607.702653380609;6613.702383392735;6620.238672552646;6297.905531417033;6450.064740612608;6559;6561.704723287464;6596.307387724291;6117.143599896018;6352.841178970562;6360.681965901686;6366.77716279928;6368;23846.76153238463;24164.516709665884;24193;24393.87803060991;24683.38291542708;2598.50628380609;2863.842488663123;2869.497837878363;2878.093400578811;2881.7118288171223;7184.533005354688;10614.681559553226;14344.713105737932;15190.316435760416;16160.676786464204;1826.1690930626532;1883.2090521980801;1907.6947688369794;1938.9709154362695;1946.5133716570858;125677.37161314135;137977.62022379748;144695.55304446927;154722.99999999886;155745;17729.29082836672;18700.251989920253;19455.3190638327;19592.21631134767;19666.31167909145;29060.12819615415;29082.27294317659;29134.27164320909;29144;29217.123486295444;8465.34925689497;9131;9307.813843723088;9446.291528135418;9590.760230994141;7287.817804554928;7781.688732450639;7785.1436342002335;7962.681492740341;7968.561729104955;6848.794536163923;7262.910563415424;8906.421082629608;8955.552222388855;9008.549572521348;3170.461021626283;3709.684676802502;3797.2215695782297;3803.676687481595;3822.1591249924973;816.506013861617;817.1420008990536;828.1263267252986;846.8306338732154;861.6811779641447;20213.898930505253;20643.4839129023;20762.169513219604;20885;21122.47193820136;837.3468694418364;844.1853611265159;846.9703560375357;874.3835595904872;875.2735229759285;10555.155587552978;10773.83839242412;10792.082672972729;10860.67417977462;10861.565537378574;544649;547381;552686;600968;689845;139228.9115663266;140898.88651670233;142971;146436;150347.9652034788;1174.506707182985;1191.6127258640977;1232.7411243638758;1237.9195352302072;1239.547565138736;17575.82424175742;18093.004884731465;18182.727259090858;18392.816071839112;18660.626787464447;43135.921601958944;49252.01495969989;59004.704976474844;59382.70308648429;60009.69995149996;71767;77612.61193693995;81099.35052922515;84109;87466.37600871985;10734.516946737573;10775.08411785007;10880;10972.670819875417;11033.503492342703;12160.635180944586;12211.71776963406;12283.75432491363;12409.503619855104;12464.314462704639;10354.378737275787;10440.791184176425;10573.8942610576;10796.298240614335;10894.891051089387;9914;10043.799124017625;10134.898651013396;10192.490375481048;10676.359418434919;10395.896041039796;10597.4701264938;10611;10614.203934704774;10765.03114719679;10314.484275786332;10590.311629744043;10599.099076578268;10959.945200274267;11161.72095697614;9814.852777208205;10029.598816047277;10326.793464130826;10719.410432425979;11033.503492342703 08:59:53 INFO - PROCESS | 37114 | |3;mootools.html;1357.2670757790793;1392.1647011792923;1571.6935197636458;1585.643230273188;1588.7458006718928;10241.385516868993;10434.947825260873;10496.632617858377;11094.556217751291;11099.278546894446;10804.18968577356;11001.944990275037;11020.173486988468;11025.393603351813;11041.779164416706;2308.226744040748;2343.9296821095395;2347.542229265294;2347.6243800991847;2354.1289722802585;2291.049214575949;2316.733575638806;2328.6157783965627;2332.416895776056;2333.381653861728;2273.6930514380597;2321.5240875620543;2334.299710086967;2334.7665233476687;2345.847519911209;2290.7251129864358;2308.319045881469;2309.2148669452467;2318.8144948404088;2322.6980492535945;2262.2760716570706;2309.872956987365;2310.2145270608075;2313.57198918199;2320.0951628864755;10940.343579385262;11047.281926674741;11050.171237157245;11057.944710276477;11061.778764424747;4519.932201016987;4970.975145124287;5331.626786124973;5502.394736579013;5844.678542680151;3323.8504267307785;3379.915502112467;3472.305538892206;3570.785752854837;3591.712662986955;1126.3667681861334;1153.7288737146764;1204.915655904087;1218.2020776391478;1230.6615680687794;13405;13609.31953402326;14678.926605366905;15149.166795826226;15272.618184545365;4724.480307166209;4877.487863774323;4888.853334399973;4969.726665033458;4982.177940639826;11179.66461006171;12877.162984405984;12893.097483176087;12902.161359511789;12916.870831291755;5654.830355089354;5894.17481552583;5985.461308482258;5997.070454079625;5999.640021598718;4327.480702315742;5121.974390128025;5221.869453263623;5299.099153143898;5300.1519756838725;3961.643452089268;4014.397840323975;4047.7976101195413;4086.7956602170366;4166.708330416842;971.3831716859704;976.8925418203992;1104.1718710966775;1127.7180704823795;1138.755167638945;801.2541369099437;842.1704620948361;880.8546589812737;907.1744712311764;928.3872644054892;5614.438556144353;6190.257169139642;6509.772157974543;6618.205815302098;6634.668266586651;812.3744716568209;856.0839901305543;972.1348000279756;1000.3377505811262;1043.639944219243;3897.629725176104;4050.7974601270407;4140.751554906716;4195.727277726938;4237.555056719062;12893.097483176087;13292.933535332262;13306.600801975956;13333.666658333424;13352.399142038632;12986.740265194643;13102.410391532403;13176;13327.267000314881;13338.466461341632;9606.903930960601;9653;9867.259955503367;9870.358426702242;9951.950240248607;5740.39725828782;6350.460210882126;6633.469322454095;6741.528093033637;6842.897356539556;9969.50152492358;11333;11749.88250117488;11951.880481195076;12053;10932.781344373227;11984.820227696766;12112.15214934946;12218.144729868824;12271.07966902468;8653.30773538128;8821.98547167084;8909.955450222707;8967.50678712651;9008.549572521217;9434.433933963985;9560.330776845833;9640.951795240979;9657.22742180638;9681.612735490502;8432.873506897278;8579.828403431771;8684.088170741981;8744.956275218583;8785.077566855643;8682.78293042679;8742.125787421208;8826.338024648305;8848.557572121239;8860.822783544421;7453.962730186315;7676.15562288159;7720.3051725344985;7767.223277672188;7775.650095745592;5227.398849132246;5334.199870019528;5399.487048730364;5456.945430545644;5494.532964698045;5713.885722285614;5781.595288329777;5911.408859114054;6069.332373439007;6075.696215189311 08:59:53 INFO - PROCESS | 37114 | |4;prototype.html;358.4032585744735;372.3000758573881;380.6079737869994;380.6916397717849;386.576698515126;4215.7892105394685;4262.87211383659;4393.78031098445;5073.771680274383;5273.709945952971;4974.87562810929;5054.620903432239;5055.747212639371;5105.591552675786;5128.5127313079165;3953.8023098845088;4163.355474587551;4255.765618130213;4269.679774016946;4310;4054.9189016219807;4253.702240843142;4287.742735435868;4291.570842915715;4304.096139810628;3897.064704470935;4035.3946907963746;4219.050713589433;4241.791089539482;4259.233337999159;3401.4829026520442;3927.98036009821;4104.897377565555;4134;4276.3799249109015;3372.5278461015478;3426.194844211608;3964.5440774311037;3966.3257246268204;3971.086650070471;4053.7973101344814;4239.936400953989;4925.458199598042;4960.429550601691;4989.226669866177;4290.506591741959;4422.955770442287;4943.528235882059;4983.252512123175;5046.798128074873;5120.257562653394;5411.756470958816;5463.470228336072;5489.890202195974;5513.889722205574;4366.345048242757;4373.453318335208;4381.6494680425485;4410.7794610269675;4417.514073451917;4777.5222477752295;5137.0153770856905;5230.92153617696;5580.610201319507;5773.509251713609;3677.099110717838;4200;4440.737725794943;4510.954890451119;4525.751083690363;4438.090191510732;4560.693497214493;4574.559013910607;4642.326862604971;4642.396488456479;4650.790714417859;4745.193317136095;4794.184988551885;4828.141165651249;4951.529604687549;4721.834735784231;4916.975415122973;5147.485251474897;5458.4814442627885;5496.230527726121;1329.9002574806927;1430.8354539228121;1500.5573355860047;1560.875129989598;1568.7058177004017;1070.55571937645;1095.9232853700323;1161.9941900290614;1172.2263306217856;1192.974042323601;4298.484181898192;4650.86047418578;4813.136183970532;5054.508919868647;5094;4828.251620998751;4988.426330971912;5043.420006699203;5508.504234618904;5738.110592858114;4347.4131941841;4356.586124318184;4838.274258861128;5133.717645529457;5167.741612919339;2771.021152261327;2796.8461734604793;2831.89907051778;2874.3676391193903;2980;4957.3973663826055;5445.774700692313;5917.733701983421;5929.835610002405;5937.346891841894;4690.577847993697;5140.46025167352;5316.813911513079;5542.224088627595;5760.452756988079;5830.795922142789;5918.674472904032;5926.7925622603;5941.910871336933;5958.756294460847;5013.924791128209;5252.317198764289;5724.740557077436;5727.649325144837;5762.279715035621;4754.286856971482;5570.387257401763;5637.154426836008;5658.264425624642;5659.406277014044;5049.343585333883;5717.428257174416;5831.300243970751;5885.323187833454;5948.613340132878;3840.2895464339185;4468.977655111704;4495.865124046284;4528.41130653013;4537.5008749036915;5378.542823859859;5589.692566908859;5685.8862822742485;5793.971030144822;5810.448007439287;5093.23601459784;5429.674219546682;5468.507834294933;5533.750981205775;5537.145056406149;4962.803934295358;4964.751762411937;4972.980538989571;5007.669899516926;5538.335399752055;5069.873253168553;5380.542653874308;5383.273258110105;5390.164524498709;5478.561715062865;4649.744264065509;4745.976270118627;5088.829569199031;5269.815556455505;5566.749496272759;4814.494478079751;4988.750562471934;5230.529252367305;5420.593455490775;5482.643628164156 08:59:53 INFO - PROCESS | 37114 | |5;yui.html;683.9897401538977;700.7407259314051;733.5671953547412;810.226233946581;822.5311572403729;14291.928540357296;15682.529524114267;15962.600934976632;15966.68066638668;16000.919995400021;14209.147451152912;14941.252937353116;15047.548573542785;15065.774013389808;15087.547373578811;942.0108885670036;969.1374676537887;969.8642190093393;973.5716284834685;974.6929717139078;825.6078362777693;936.3398803843274;970.4419958523841;972.8005758819461;990.8860481044665;1020.8213562626511;1022.371241686365;1023.2479127841068;1024.2932376660117;1025.0108645157422;1023.2223510132278;1026.7535791410046;1027.1628622672497;1029.696239609317;1032.303195343148;764.0983639305617;766.1572270502459;766.7124828189429;767.3477544087536;768.9231076892322;14885.181315027668;15043.623909402244;15175.165365904872;15990.440334588233;16197.51407457778;14180.929095354457;14527.564173074825;14596.270186490632;14708.852911470854;14885.7767133492;10716.946415267952;10728;10829.079528240109;10830.67507974762;11320.830187547193;3634.327649384872;3635.1820840310984;3642.708583313355;3687.3731465650903;3772.8113594320175;75029.87455188176;92157;105759;106731;107273.46363268212;11082.944585277184;11249.831252531056;11435.771284574428;11554.786747391403;11644.76710465786;24763.876180619343;25014.99940002416;25057.749422505905;25071.997120114993;25082.874585626956;7373.078365204349;7531.359834414026;7601.08786945559;7725.072991241087;7878.763637090897;4181.059261666101;4219.409282700424;4277.465316835395;4321.330193819963;4400.361947517592;3423.7432192585657;3435.9656403436147;3471.7743346682387;3479.4258947273925;3608.260306637133;681.386751923273;681.7852376501386;685.8559702462538;697.9844326504923;710.2116650517921;646.3278190681639;653.9247986481521;678.1658559971263;700.3416788219058;703.1175874277851;8701.564921753887;9454.290928180277;10064.899351006543;10201;10255.487225638688;634.7524465458475;669.7220653428869;687.4981263677544;688.2566827825935;689.4622194688151;4722.409698787652;4922.4339200992345;4931.753412329369;4942.579880710108;4942.9011419771405;97476.51261743788;98527;99177.00822991822;101504.81942166988;101904;63958;66396;72652;74325.62837185779;85022;52581.999999999236;56561.58596035132;56728.71635641796;67870;70676.6466167666;9199.402038867453;10422.895771042193;10489.265751397634;10754.516046777757;10778.407187604756;37219.81390093032;37516.81241593775;42443;46185.307220392395;47294;45955;46953.76523117499;57879;59096.70451647714;59214;11448.656540303804;13048.086633935533;13234.272115033766;13523.59429217125;13579;13892.374843131882;13956.2324072177;14031.68671026774;14205;14384;13258.270795106362;13302.800457993331;13428.9011360697;13732.519361822287;14055.032295478846;12654.430550625428;13103.27931963713;13588.320583970959;13678.794818077937;13765.311734413439;12469.127161098999;12605.49578016869;13105.868941310466;13133.212007279593;13565.321733913463;9714.854277185988;10275.961695980295;10365.118964888068;10398.740031499274;11211.271267367594;9565.534138029077;9576.473293968898;10548.103411210133;10620.362778233331;10663.200259980687 08:59:53 INFO - PROCESS | 37114 | __end_tp_report 08:59:53 INFO - PROCESS | 37114 | __start_cc_report 08:59:53 INFO - PROCESS | 37114 | _x_x_mozilla_cycle_collect,435 08:59:53 INFO - PROCESS | 37114 | __end_cc_report 08:59:53 INFO - PROCESS | 37114 | __startTimestamp1488646793613__endTimestamp 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | ------- Summary: start ------- 08:59:53 INFO - PROCESS | 37114 | Number of tests: 6 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#0] dojo.html Cycles:165 Average:5804.65 Median:6112.54 stddev:652.65 (10.7%) stddev-sans-first:626.34 08:59:53 INFO - PROCESS | 37114 | Values: 3380.6 3414.7 3416.0 3428.5 3438.2 4606.0 5750.8 5873.7 5881.5 5945.1 4831.4 5078.5 5786.5 5866.3 5915.1 5337.2 5349.3 5359.3 5363.8 5444.2 5313.1 5371.8 5475.0 5475.3 5534.8 5211.1 5223.8 5263.0 5374.3 5406.0 5250.5 5253.5 5286.5 5392.5 5399.7 5281.9 5292.9 5328.9 5332.3 5332.7 5589.9 5744.1 5913.0 5919.0 5999.1 6168.6 6217.3 6230.6 6243.9 6250.8 5547.8 6200.9 6408.1 6411.3 6415.1 6050.9 6112.5 6120.8 6120.9 6143.3 6200.2 6221.4 6344.0 6368.0 6435.2 6123.7 6135.9 6187.9 6190.6 6273.6 6263.6 6305.1 6305.2 6308.8 6332.1 6145.0 6247.8 6250.9 6271.4 6298.6 6187.5 6208.7 6256.6 6286.5 6301.5 4397.5 5124.0 5136.6 5158.7 5163.2 4570.0 4577.7 4599.3 4602.4 4632.1 5738.7 5805.3 5969.4 5993.1 5998.8 6213.2 6228.1 6235.3 6247.6 6267.0 5946.0 5995.4 5998.6 6006.8 6012.8 5588.7 5612.9 5614.6 5616.2 5618.2 5452.5 6124.0 6259.7 6395.0 6437.3 5307.6 6292.5 6343.6 6347.9 6382.1 6452.9 6517.6 6532.4 6533.2 6535.1 5221.3 6137.5 6182.0 6199.0 6249.9 5362.5 6267.0 6358.0 6368.7 6370.9 6080.8 6121.5 6212.2 6245.4 6246.4 6120.9 6213.7 6219.5 6233.2 6248.6 4748.8 5929.7 6026.3 6091.0 6139.5 5072.9 6232.7 6250.3 6266.7 6267.5 6167.6 6179.5 6201.7 6210.3 6218.0 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#1] ext.html Cycles:180 Average:51829.79 Median:16884.09 stddev:103594.33 (613.6%) stddev-sans-first:103814.05 08:59:53 INFO - PROCESS | 37114 | Values: 786.5 810.4 829.8 857.5 916.2 36096.0 37007.9 37014.9 38399.0 38501.0 37812.4 38211.0 38220.8 38261.9 38314.2 7675.5 7816.1 7836.4 10362.4 10635.8 10523.4 10589.5 10629.5 10631.3 10664.1 10607.9 10637.6 10650.9 10652.3 10656.1 10565.5 10613.4 10617.3 10627.5 10635.1 10187.7 10236.8 10267.2 10277.2 10280.5 33357.8 33458.0 33493.8 33505.3 33616.8 3664.2 3685.7 3702.5 3710.5 3837.0 22415.0 23519.8 24956.8 24984.8 25238.0 3040.8 3065.1 3115.9 3139.1 3142.2 248495.0 288806.2 296326.0 296547.0 297177.0 33198.3 34998.5 40818.6 41803.6 43073.0 35524.8 42836.0 43706.6 43791.8 43857.7 12740.2 12827.4 12862.7 12870.6 12881.5 8829.9 8985.6 10898.2 10905.6 10922.0 11026.4 11725.0 11746.8 11835.6 11875.3 5471.1 5547.0 5563.8 5566.0 5584.4 990.8 1079.6 1081.7 1085.9 1131.2 26160.9 26273.6 26694.3 26804.7 27156.3 1121.1 1126.8 1156.1 1166.8 1182.4 12870.6 13258.7 13530.0 13616.2 13869.4 427016.0 428840.0 430135.0 430281.0 430490.0 55600.2 55756.0 55757.2 55856.0 55951.0 1240.5 1397.1 1432.1 1439.4 1442.2 20464.1 27642.6 27826.9 30209.4 30652.2 51819.5 51826.0 51833.7 52030.0 52345.7 433124.0 437213.0 438111.0 438131.0 438533.0 19260.5 23473.5 23588.1 23707.4 23717.5 41733.1 42107.8 43405.0 43424.8 43445.7 34810.0 35032.8 35899.5 35986.3 36284.5 34734.7 34748.8 35720.5 35821.0 35989.8 32585.8 34682.3 34814.7 35732.8 35989.3 27369.9 28242.2 29073.7 29857.1 29978.0 20242.6 22536.8 22552.8 22882.1 22987.4 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#2] jquery.html Cycles:180 Average:36910.36 Median:24538.63 stddev:99530.73 (405.6%) stddev-sans-first:99772.18 08:59:53 INFO - PROCESS | 37114 | Values: 392.5 396.6 407.4 421.0 428.5 12406.0 19391.4 20330.2 20722.6 20744.9 20564.3 20990.6 21235.5 21360.6 21397.5 5687.2 6088.8 6553.7 6595.3 6599.4 6313.1 6466.4 6538.9 6587.0 6594.0 6576.5 6587.5 6607.7 6613.7 6620.2 6297.9 6450.1 6559.0 6561.7 6596.3 6117.1 6352.8 6360.7 6366.8 6368.0 23846.8 24164.5 24193.0 24393.9 24683.4 2598.5 2863.8 2869.5 2878.1 2881.7 7184.5 10614.7 14344.7 15190.3 16160.7 1826.2 1883.2 1907.7 1939.0 1946.5 125677.4 137977.6 144695.6 154723.0 155745.0 17729.3 18700.3 19455.3 19592.2 19666.3 29060.1 29082.3 29134.3 29144.0 29217.1 8465.3 9131.0 9307.8 9446.3 9590.8 7287.8 7781.7 7785.1 7962.7 7968.6 6848.8 7262.9 8906.4 8955.6 9008.5 3170.5 3709.7 3797.2 3803.7 3822.2 816.5 817.1 828.1 846.8 861.7 20213.9 20643.5 20762.2 20885.0 21122.5 837.3 844.2 847.0 874.4 875.3 10555.2 10773.8 10792.1 10860.7 10861.6 544649.0 547381.0 552686.0 600968.0 689845.0 139228.9 140898.9 142971.0 146436.0 150348.0 1174.5 1191.6 1232.7 1237.9 1239.5 17575.8 18093.0 18182.7 18392.8 18660.6 43135.9 49252.0 59004.7 59382.7 60009.7 71767.0 77612.6 81099.4 84109.0 87466.4 10734.5 10775.1 10880.0 10972.7 11033.5 12160.6 12211.7 12283.8 12409.5 12464.3 10354.4 10440.8 10573.9 10796.3 10894.9 9914.0 10043.8 10134.9 10192.5 10676.4 10395.9 10597.5 10611.0 10614.2 10765.0 10314.5 10590.3 10599.1 10959.9 11161.7 9814.9 10029.6 10326.8 10719.4 11033.5 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#3] mootools.html Cycles:180 Average:6575.11 Median:3988.02 stddev:4103.50 (102.9%) stddev-sans-first:4096.27 08:59:53 INFO - PROCESS | 37114 | Values: 1357.3 1392.2 1571.7 1585.6 1588.7 10241.4 10434.9 10496.6 11094.6 11099.3 10804.2 11001.9 11020.2 11025.4 11041.8 2308.2 2343.9 2347.5 2347.6 2354.1 2291.0 2316.7 2328.6 2332.4 2333.4 2273.7 2321.5 2334.3 2334.8 2345.8 2290.7 2308.3 2309.2 2318.8 2322.7 2262.3 2309.9 2310.2 2313.6 2320.1 10940.3 11047.3 11050.2 11057.9 11061.8 4519.9 4971.0 5331.6 5502.4 5844.7 3323.9 3379.9 3472.3 3570.8 3591.7 1126.4 1153.7 1204.9 1218.2 1230.7 13405.0 13609.3 14678.9 15149.2 15272.6 4724.5 4877.5 4888.9 4969.7 4982.2 11179.7 12877.2 12893.1 12902.2 12916.9 5654.8 5894.2 5985.5 5997.1 5999.6 4327.5 5122.0 5221.9 5299.1 5300.2 3961.6 4014.4 4047.8 4086.8 4166.7 971.4 976.9 1104.2 1127.7 1138.8 801.3 842.2 880.9 907.2 928.4 5614.4 6190.3 6509.8 6618.2 6634.7 812.4 856.1 972.1 1000.3 1043.6 3897.6 4050.8 4140.8 4195.7 4237.6 12893.1 13292.9 13306.6 13333.7 13352.4 12986.7 13102.4 13176.0 13327.3 13338.5 9606.9 9653.0 9867.3 9870.4 9952.0 5740.4 6350.5 6633.5 6741.5 6842.9 9969.5 11333.0 11749.9 11951.9 12053.0 10932.8 11984.8 12112.2 12218.1 12271.1 8653.3 8822.0 8910.0 8967.5 9008.5 9434.4 9560.3 9641.0 9657.2 9681.6 8432.9 8579.8 8684.1 8745.0 8785.1 8682.8 8742.1 8826.3 8848.6 8860.8 7454.0 7676.2 7720.3 7767.2 7775.7 5227.4 5334.2 5399.5 5456.9 5494.5 5713.9 5781.6 5911.4 6069.3 6075.7 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#4] prototype.html Cycles:180 Average:4539.97 Median:4921.22 stddev:1272.65 (25.9%) stddev-sans-first:1236.91 08:59:53 INFO - PROCESS | 37114 | Values: 358.4 372.3 380.6 380.7 386.6 4215.8 4262.9 4393.8 5073.8 5273.7 4974.9 5054.6 5055.7 5105.6 5128.5 3953.8 4163.4 4255.8 4269.7 4310.0 4054.9 4253.7 4287.7 4291.6 4304.1 3897.1 4035.4 4219.1 4241.8 4259.2 3401.5 3928.0 4104.9 4134.0 4276.4 3372.5 3426.2 3964.5 3966.3 3971.1 4053.8 4239.9 4925.5 4960.4 4989.2 4290.5 4423.0 4943.5 4983.3 5046.8 5120.3 5411.8 5463.5 5489.9 5513.9 4366.3 4373.5 4381.6 4410.8 4417.5 4777.5 5137.0 5230.9 5580.6 5773.5 3677.1 4200.0 4440.7 4511.0 4525.8 4438.1 4560.7 4574.6 4642.3 4642.4 4650.8 4745.2 4794.2 4828.1 4951.5 4721.8 4917.0 5147.5 5458.5 5496.2 1329.9 1430.8 1500.6 1560.9 1568.7 1070.6 1095.9 1162.0 1172.2 1193.0 4298.5 4650.9 4813.1 5054.5 5094.0 4828.3 4988.4 5043.4 5508.5 5738.1 4347.4 4356.6 4838.3 5133.7 5167.7 2771.0 2796.8 2831.9 2874.4 2980.0 4957.4 5445.8 5917.7 5929.8 5937.3 4690.6 5140.5 5316.8 5542.2 5760.5 5830.8 5918.7 5926.8 5941.9 5958.8 5013.9 5252.3 5724.7 5727.6 5762.3 4754.3 5570.4 5637.2 5658.3 5659.4 5049.3 5717.4 5831.3 5885.3 5948.6 3840.3 4469.0 4495.9 4528.4 4537.5 5378.5 5589.7 5685.9 5794.0 5810.4 5093.2 5429.7 5468.5 5533.8 5537.1 4962.8 4964.8 4973.0 5007.7 5538.3 5069.9 5380.5 5383.3 5390.2 5478.6 4649.7 4746.0 5088.8 5269.8 5566.7 4814.5 4988.8 5230.5 5420.6 5482.6 08:59:53 INFO - PROCESS | 37114 | 08:59:53 INFO - PROCESS | 37114 | [#5] yui.html Cycles:180 Average:18701.38 Median:3453.87 stddev:25903.23 (750.0%) stddev-sans-first:25940.56 08:59:53 INFO - PROCESS | 37114 | Values: 684.0 700.7 733.6 810.2 822.5 14291.9 15682.5 15962.6 15966.7 16000.9 14209.1 14941.3 15047.5 15065.8 15087.5 942.0 969.1 969.9 973.6 974.7 825.6 936.3 970.4 972.8 990.9 1020.8 1022.4 1023.2 1024.3 1025.0 1023.2 1026.8 1027.2 1029.7 1032.3 764.1 766.2 766.7 767.3 768.9 14885.2 15043.6 15175.2 15990.4 16197.5 14180.9 14527.6 14596.3 14708.9 14885.8 10716.9 10728.0 10829.1 10830.7 11320.8 3634.3 3635.2 3642.7 3687.4 3772.8 75029.9 92157.0 105759.0 106731.0 107273.5 11082.9 11249.8 11435.8 11554.8 11644.8 24763.9 25015.0 25057.7 25072.0 25082.9 7373.1 7531.4 7601.1 7725.1 7878.8 4181.1 4219.4 4277.5 4321.3 4400.4 3423.7 3436.0 3471.8 3479.4 3608.3 681.4 681.8 685.9 698.0 710.2 646.3 653.9 678.2 700.3 703.1 8701.6 9454.3 10064.9 10201.0 10255.5 634.8 669.7 687.5 688.3 689.5 4722.4 4922.4 4931.8 4942.6 4942.9 97476.5 98527.0 99177.0 101504.8 101904.0 63958.0 66396.0 72652.0 74325.6 85022.0 52582.0 56561.6 56728.7 67870.0 70676.6 9199.4 10422.9 10489.3 10754.5 10778.4 37219.8 37516.8 42443.0 46185.3 47294.0 45955.0 46953.8 57879.0 59096.7 59214.0 11448.7 13048.1 13234.3 13523.6 13579.0 13892.4 13956.2 14031.7 14205.0 14384.0 13258.3 13302.8 13428.9 13732.5 14055.0 12654.4 13103.3 13588.3 13678.8 13765.3 12469.1 12605.5 13105.9 13133.2 13565.3 9714.9 10276.0 10365.1 10398.7 11211.3 9565.5 9576.5 10548.1 10620.4 10663.2 08:59:53 INFO - PROCESS | 37114 | -------- Summary: end -------- 08:59:53 INFO - PROCESS | 37114 | 08:59:54 INFO - TEST-INFO | 37114: exit 0 08:59:54 INFO - TEST-OK | dromaeo_css | took 1117652ms 08:59:54 INFO - TEST-START | kraken 08:59:54 INFO - operating with platform_type : mac_ 08:59:54 INFO - Initialising browser for kraken test... 08:59:54 INFO - TEST-INFO | started process 37122 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpeodmYL/profile http://localhost:49299/getInfo.html) 08:59:57 INFO - PROCESS | 37122 | 2017-03-04 08:59:57.333 plugin-container[37123:317709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 08:59:57 INFO - PROCESS | 37122 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:59:57 INFO - PROCESS | 37122 | 2017-03-04 08:59:57.337 plugin-container[37123:317709] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1f, name = 'com.apple.CFPasteboardClient' 08:59:57 INFO - PROCESS | 37122 | See /usr/include/servers/bootstrap_defs.h for the error codes. 08:59:57 INFO - PROCESS | 37122 | 2017-03-04 08:59:57.337 plugin-container[37123:317709] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 08:59:57 INFO - PROCESS | 37122 | __metrics Screen width/height:1600/1200 08:59:57 INFO - PROCESS | 37122 | colorDepth:24 08:59:57 INFO - PROCESS | 37122 | Browser inner width/height: 1024/689 08:59:57 INFO - PROCESS | 37122 | __metrics 08:59:57 ERROR - PROCESS | 37122 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3354: TypeError: this.tabs is undefined 08:59:58 INFO - TEST-INFO | 37122: exit 0 08:59:58 INFO - Browser initialized. 08:59:58 INFO - Running cycle 1/1 for kraken test... 08:59:58 INFO - TEST-INFO | started process 37124 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/tmpeodmYL/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 09:00:00 INFO - PROCESS | 37124 | 2017-03-04 09:00:00.932 plugin-container[37125:317868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x444b, name = 'com.apple.tsm.portname' 09:00:00 INFO - PROCESS | 37124 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:00 INFO - PROCESS | 37124 | 2017-03-04 09:00:00.933 plugin-container[37125:317868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4717, name = 'com.apple.CFPasteboardClient' 09:00:00 INFO - PROCESS | 37124 | See /usr/include/servers/bootstrap_defs.h for the error codes. 09:00:00 INFO - PROCESS | 37124 | 2017-03-04 09:00:00.933 plugin-container[37125:317868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 09:00:10 INFO - PROCESS | 37124 | RSS: Main: 226414592 09:00:10 INFO - PROCESS | 37124 | 09:00:14 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-beat-detection) 09:00:15 INFO - PROCESS | 37124 | RSS: Main: 241086464 09:00:15 INFO - PROCESS | 37124 | 09:00:17 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-dft) 09:00:18 INFO - PROCESS | 37124 | RSS: Main: 249307136 09:00:18 INFO - PROCESS | 37124 | 09:00:21 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-fft) 09:00:22 INFO - PROCESS | 37124 | RSS: Main: 251834368 09:00:22 INFO - PROCESS | 37124 | 09:00:24 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49299/tests/kraken/driver.html?testName=audio-oscillator) 09:00:24 INFO - PROCESS | 37124 | RSS: Main: 253988864 09:00:24 INFO - PROCESS | 37124 | 09:00:27 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-gaussian-blur) 09:00:27 INFO - PROCESS | 37124 | RSS: Main: 254877696 09:00:27 INFO - PROCESS | 37124 | 09:00:30 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-darkroom) 09:00:31 INFO - PROCESS | 37124 | RSS: Main: 260505600 09:00:31 INFO - PROCESS | 37124 | 09:00:34 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49299/tests/kraken/driver.html?testName=imaging-desaturate) 09:00:35 INFO - PROCESS | 37124 | RSS: Main: 259588096 09:00:35 INFO - PROCESS | 37124 | 09:00:38 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49299/tests/kraken/driver.html?testName=json-parse-financial) 09:00:39 INFO - PROCESS | 37124 | RSS: Main: 259747840 09:00:39 INFO - PROCESS | 37124 | 09:00:40 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49299/tests/kraken/driver.html?testName=json-stringify-tinderbox) 09:00:41 INFO - PROCESS | 37124 | RSS: Main: 259334144 09:00:41 INFO - PROCESS | 37124 | 09:00:43 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-aes) 09:00:43 INFO - PROCESS | 37124 | RSS: Main: 259383296 09:00:43 INFO - PROCESS | 37124 | 09:00:46 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-ccm) 09:00:46 INFO - PROCESS | 37124 | RSS: Main: 259428352 09:00:46 INFO - PROCESS | 37124 | 09:00:49 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 09:00:50 INFO - PROCESS | 37124 | RSS: Main: 259756032 09:00:50 INFO - PROCESS | 37124 | 09:00:53 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 09:00:54 INFO - PROCESS | 37124 | RSS: Main: 259792896 09:00:54 INFO - PROCESS | 37124 | 09:00:55 INFO - PROCESS | 37124 | Cycle 1(1): loaded http://localhost:49299/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49299/tests/kraken/driver.html?testName=ai-astar) 09:00:56 INFO - PROCESS | 37124 | RSS: Main: 262844416 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | __start_tp_report 09:00:56 INFO - PROCESS | 37124 | _x_x_mozilla_page_load 09:00:56 INFO - PROCESS | 37124 | _x_x_mozilla_page_load_details 09:00:56 INFO - PROCESS | 37124 | |i|pagename|runs| 09:00:56 INFO - PROCESS | 37124 | |0;ai-astar;132;173;98;174;174;174;122;119;148;160 09:00:56 INFO - PROCESS | 37124 | |1;audio-beat-detection;163;115;113;151;113;113;108;113;124;142 09:00:56 INFO - PROCESS | 37124 | |2;audio-dft;237;204;194;205;197;200;194;212;197;197 09:00:56 INFO - PROCESS | 37124 | |3;audio-fft;91;60;62;91;61;59;61;64;72;69 09:00:56 INFO - PROCESS | 37124 | |4;audio-oscillator;83;86;105;105;89;71;83;72;71;86 09:00:56 INFO - PROCESS | 37124 | |5;imaging-gaussian-blur;91;111;88;89;89;89;95;112;90;89 09:00:56 INFO - PROCESS | 37124 | |6;imaging-darkroom;96;98;97;107;96;100;97;152;97;96 09:00:56 INFO - PROCESS | 37124 | |7;imaging-desaturate;89;93;92;93;97;105;95;90;91;99 09:00:56 INFO - PROCESS | 37124 | |8;json-parse-financial;65;64;64;64;67;67;68;67;67;65 09:00:56 INFO - PROCESS | 37124 | |9;json-stringify-tinderbox;59;64;59;70;69;64;58;58;58;61 09:00:56 INFO - PROCESS | 37124 | |10;stanford-crypto-aes;118;107;77;104;81;86;109;74;102;102 09:00:56 INFO - PROCESS | 37124 | |11;stanford-crypto-ccm;152;141;141;144;144;143;143;156;259;157 09:00:56 INFO - PROCESS | 37124 | |12;stanford-crypto-pbkdf2;179;176;174;181;174;194;208;177;177;170 09:00:56 INFO - PROCESS | 37124 | |13;stanford-crypto-sha256-iterative;70;66;69;69;69;67;67;73;67;70 09:00:56 INFO - PROCESS | 37124 | __end_tp_report 09:00:56 INFO - PROCESS | 37124 | __start_cc_report 09:00:56 INFO - PROCESS | 37124 | _x_x_mozilla_cycle_collect,1007 09:00:56 INFO - PROCESS | 37124 | __end_cc_report 09:00:56 INFO - PROCESS | 37124 | __startTimestamp1488646856234__endTimestamp 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | ------- Summary: start ------- 09:00:56 INFO - PROCESS | 37124 | Number of tests: 14 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#0] ai-astar Cycles:10 Average:147.40 Median:173.50 stddev:28.02 (16.2%) stddev-sans-first:29.16 09:00:56 INFO - PROCESS | 37124 | Values: 132.0 173.0 98.0 174.0 174.0 174.0 122.0 119.0 148.0 160.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#1] audio-beat-detection Cycles:10 Average:125.50 Median:119.50 stddev:19.36 (16.2%) stddev-sans-first:15.04 09:00:56 INFO - PROCESS | 37124 | Values: 163.0 115.0 113.0 151.0 113.0 113.0 108.0 113.0 124.0 142.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#2] audio-dft Cycles:10 Average:203.70 Median:202.00 stddev:12.98 (6.4%) stddev-sans-first:5.96 09:00:56 INFO - PROCESS | 37124 | Values: 237.0 204.0 194.0 205.0 197.0 200.0 194.0 212.0 197.0 197.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#3] audio-fft Cycles:10 Average:69.00 Median:66.50 stddev:12.29 (18.5%) stddev-sans-first:10.14 09:00:56 INFO - PROCESS | 37124 | Values: 91.0 60.0 62.0 91.0 61.0 59.0 61.0 64.0 72.0 69.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#4] audio-oscillator Cycles:10 Average:85.10 Median:83.00 stddev:12.41 (15.0%) stddev-sans-first:13.14 09:00:56 INFO - PROCESS | 37124 | Values: 83.0 86.0 105.0 105.0 89.0 71.0 83.0 72.0 71.0 86.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#5] imaging-gaussian-blur Cycles:10 Average:94.30 Median:89.00 stddev:9.27 (10.4%) stddev-sans-first:9.76 09:00:56 INFO - PROCESS | 37124 | Values: 91.0 111.0 88.0 89.0 89.0 89.0 95.0 112.0 90.0 89.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#6] imaging-darkroom Cycles:10 Average:103.60 Median:96.50 stddev:17.33 (18.0%) stddev-sans-first:18.16 09:00:56 INFO - PROCESS | 37124 | Values: 96.0 98.0 97.0 107.0 96.0 100.0 97.0 152.0 97.0 96.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#7] imaging-desaturate Cycles:10 Average:94.40 Median:93.00 stddev:4.84 (5.2%) stddev-sans-first:4.72 09:00:56 INFO - PROCESS | 37124 | Values: 89.0 93.0 92.0 93.0 97.0 105.0 95.0 90.0 91.0 99.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:67.00 stddev:1.55 (2.3%) stddev-sans-first:1.62 09:00:56 INFO - PROCESS | 37124 | Values: 65.0 64.0 64.0 64.0 67.0 67.0 68.0 67.0 67.0 65.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#9] json-stringify-tinderbox Cycles:10 Average:62.00 Median:62.50 stddev:4.57 (7.3%) stddev-sans-first:4.72 09:00:56 INFO - PROCESS | 37124 | Values: 59.0 64.0 59.0 70.0 69.0 64.0 58.0 58.0 58.0 61.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#10] stanford-crypto-aes Cycles:10 Average:96.00 Median:96.00 stddev:15.20 (15.8%) stddev-sans-first:13.88 09:00:56 INFO - PROCESS | 37124 | Values: 118.0 107.0 77.0 104.0 81.0 86.0 109.0 74.0 102.0 102.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#11] stanford-crypto-ccm Cycles:10 Average:158.00 Median:148.00 stddev:36.00 (24.3%) stddev-sans-first:38.11 09:00:56 INFO - PROCESS | 37124 | Values: 152.0 141.0 141.0 144.0 144.0 143.0 143.0 156.0 259.0 157.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:181.00 Median:178.00 stddev:11.44 (6.4%) stddev-sans-first:12.11 09:00:56 INFO - PROCESS | 37124 | Values: 179.0 176.0 174.0 181.0 174.0 194.0 208.0 177.0 177.0 170.0 09:00:56 INFO - PROCESS | 37124 | 09:00:56 INFO - PROCESS | 37124 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.70 Median:69.00 stddev:2.06 (3.0%) stddev-sans-first:2.13 09:00:56 INFO - PROCESS | 37124 | Values: 70.0 66.0 69.0 69.0 69.0 67.0 67.0 73.0 67.0 70.0 09:00:56 INFO - PROCESS | 37124 | -------- Summary: end -------- 09:00:56 INFO - PROCESS | 37124 | 09:00:57 INFO - TEST-INFO | 37124: exit 0 09:00:57 INFO - TEST-OK | kraken | took 62881ms 09:00:57 INFO - SUITE-END | took 1180s 09:00:57 INFO - Completed test suite (00:19:40) 09:00:57 INFO - javascript benchmark 09:00:57 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7455.957287841602, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3380.611229708583, 3414.7097496712777, 3415.9829200854, 3428.4514477683574, 3438.209211881268, 4605.953940460595, 5750.763585829046, 5873.676947767871, 5881.529477641794, 5945.108233764938, 4831.444383895853, 5078.5175408336245, 5786.508146807516, 5866.296044474659, 5915.112733090039, 5337.226102215176, 5349.304590403242, 5359.330083739533, 5363.8122665706705, 5444.237806707064, 5313.149896016619, 5371.81198658047, 5474.972625136868, 5475.342958844924, 5534.778608855641, 5211.088059589594, 5223.791048358061, 5262.97368513155, 5374.328208973879, 5405.999890020359, 5250.474952504757, 5253.527182553588, 5286.5242128208265, 5392.487713667196, 5399.676019438846, 5281.947180528184, 5292.894142117175, 5328.946710532884, 5332.280142180794, 5332.653377530487, 5589.944100558983, 5744.080947048494, 5913.0, 5918.970405147989, 5999.13012613173, 6168.599041062316, 6217.347178546278, 6230.5638605297645, 6243.906341404882, 6250.7812226572305, 5547.846054010874, 6200.852842224158, 6408.07082972971, 6411.326810684905, 6415.101885736, 6050.878982420415, 6112.541559383019, 6120.816375508697, 6120.938790612126, 6143.262808462969, 6200.162977998003, 6221.377862213742, 6344.048392741033, 6367.968160159169, 6435.163428754232, 6123.679189354777, 6135.875804921915, 6187.876242475215, 6190.628562286277, 6273.592216505913, 6263.6241825490615, 6305.085762564402, 6305.243370795534, 6308.810735677937, 6332.145160403286, 6144.969275153596, 6247.750089996349, 6250.9062364064575, 6271.404216599416, 6298.622082675054, 6187.504999600022, 6208.689565521706, 6256.624602523772, 6286.49708023357, 6301.495880329563, 4397.494288156839, 5124.0, 5136.614753893473, 5158.716270605153, 5163.199704045879, 4569.977150114228, 4577.748223847654, 4599.264117741145, 4602.401687780634, 4632.119897219518, 5738.68437235956, 5805.332386775491, 5969.403059694083, 5993.130996005555, 5998.820035398945, 6213.2233470816145, 6228.096925945803, 6235.345288744707, 6247.562670613104, 6267.0286105653695, 5945.970270148621, 5995.400459954057, 5998.550108741774, 6006.849828754229, 6012.759489620366, 5588.748506317225, 5612.859678507988, 5614.606977511535, 5616.185653080281, 5618.241537392481, 5452.536534394541, 6124.045297608125, 6259.65571893532, 6394.968025159845, 6437.259715132633, 5307.628466007497, 6292.465140463112, 6343.55595108338, 6347.873042539216, 6382.074599183, 6452.935470645233, 6517.641529715911, 6532.41208291256, 6533.150690410406, 6535.052417399355, 5221.347331583552, 6137.5302083125, 6182.0, 6199.0, 6249.875002500015, 5362.51737343641, 6267.0, 6357.96821015892, 6368.681565921778, 6370.936290637034, 6080.817575472743, 6121.452973575903, 6212.161358216764, 6245.375462453719, 6246.437820596168, 6120.90429240112, 6213.658248796179, 6219.502439804714, 6233.189685340877, 6248.625082495065, 4748.762561871824, 5929.680265267199, 6026.337102918587, 6090.969545152246, 6139.4781443577795, 5072.949270507248, 6232.719527621177, 6250.312465628868, 6266.68666566679, 6267.467265282502, 6167.599106058092, 6179.5056395489155, 6201.658908760061, 6210.31686514474, 6217.96891015542], "value": 5766.559850592552, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [786.4966421490245, 810.3841080778607, 829.8174401631643, 857.4897935728244, 916.1845957098179, 36096.0, 37007.88976330711, 37014.88955331341, 38399.0, 38501.0, 37812.43281350782, 38211.0, 38220.80889595548, 38261.85214443571, 38314.233715325674, 7675.462717609769, 7816.140224575292, 7836.373090152802, 10362.378257304548, 10635.840462393031, 10523.421211833345, 10589.470526473684, 10629.5216715248, 10631.255812093157, 10664.146868250522, 10607.946960265188, 10637.574497020149, 10650.893491065068, 10652.254342196049, 10656.09423199029, 10565.471726413649, 10613.363198208093, 10617.256792024562, 10627.521761520711, 10635.096016838577, 10187.694369168938, 10236.846447303296, 10267.178625709925, 10277.17782577392, 10280.537375818107, 33357.83321083403, 33458.0, 33493.827716030064, 33505.32989340224, 33616.83191584051, 3664.2305115925687, 3685.7419980601367, 3702.5186725725484, 3710.5361829771277, 3836.9808150959348, 22415.0, 23519.76480235193, 24956.750432495624, 24984.750152498425, 25238.029118895574, 3040.802347847405, 3065.126438964907, 3115.9376812463624, 3139.089663997438, 3142.1673256587023, 248495.0, 288806.22387552133, 296325.99999999785, 296547.0, 297177.0, 33198.33603327968, 34998.47502287468, 40818.591814081476, 41803.58196418058, 43073.0, 35524.822375887954, 42836.0, 43706.562934370886, 43791.78104109523, 43857.68426947197, 12740.235585864877, 12827.358632068359, 12862.742745145044, 12870.61388158338, 12881.484740610458, 8829.867551986725, 8985.595648195847, 10898.182636302308, 10905.618303359344, 10922.0, 11026.44867756609, 11725.003374713246, 11746.765064698659, 11835.644930651923, 11875.346855923008, 5471.097268950658, 5547.0, 5563.805266815642, 5565.970295495346, 5584.385717571064, 990.8018396320765, 1079.6005477973201, 1081.6538707613645, 1085.8642669666292, 1131.2307630811129, 26160.8691956539, 26273.584434023836, 26694.332641684112, 26804.731952680224, 27156.321091972462, 1121.1423261205184, 1126.774645070989, 1156.1040193849833, 1166.8191430328313, 1182.4206138992004, 12870.584235734063, 13258.668533286553, 13530.0, 13616.183029018093, 13869.445222191202, 427016.0, 428840.0, 430135.0, 430281.0, 430490.0, 55600.16599751007, 55756.0, 55757.16364254539, 55856.0, 55951.0, 1240.4789988204975, 1397.084909384355, 1432.0548438030642, 1439.4242303078852, 1442.213993373614, 20464.098117319012, 27642.58536122, 27826.88692452279, 30209.395812083196, 30652.233694157825, 51819.481805181465, 51826.0, 51833.7408312956, 52030.0, 52345.7382713084, 433124.0, 437213.0, 438111.0, 438131.0, 438533.0, 19260.518487037934, 23473.530529388976, 23588.056477741386, 23707.40731481727, 23717.52564948726, 41733.0786844918, 42107.789461052496, 43405.0, 43424.78287608542, 43445.69662910118, 34810.0, 35032.82483587668, 35899.46150807792, 35986.280274393845, 36284.45573316455, 34734.65265347314, 34748.82625586856, 35720.464193036605, 35821.0, 35989.82005089958, 32585.837070814494, 34682.30635387228, 34814.65185348114, 35732.82133589315, 35989.28021439609, 27369.86315068412, 28242.15273541797, 29073.709262907945, 29857.10428687143, 29978.0, 20242.59514809725, 22536.774632254124, 22552.774472255067, 22882.08471661115, 22987.425314367276], "value": 17871.32543996413, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [392.4838836929436, 396.6192455242967, 407.40518842489973, 421.0358279539854, 428.5114968935414, 12406.0, 19391.418257452264, 20330.186792528282, 20722.585548289044, 20744.89627551862, 20564.280250191245, 20990.580188396223, 21235.46911327214, 21360.57278854422, 21397.465063373424, 5687.232223649817, 6088.756449742005, 6553.705083271264, 6595.34046595339, 6599.406053455188, 6313.147725057127, 6466.418022377985, 6538.934610653881, 6587.011948207759, 6593.967030164842, 6576.539642225022, 6587.538872278942, 6607.702653380609, 6613.702383392735, 6620.238672552646, 6297.905531417033, 6450.064740612608, 6559.0, 6561.704723287464, 6596.307387724291, 6117.143599896018, 6352.841178970562, 6360.681965901686, 6366.77716279928, 6368.0, 23846.76153238463, 24164.516709665884, 24193.0, 24393.87803060991, 24683.38291542708, 2598.50628380609, 2863.842488663123, 2869.497837878363, 2878.093400578811, 2881.7118288171223, 7184.533005354688, 10614.681559553226, 14344.713105737932, 15190.316435760416, 16160.676786464204, 1826.1690930626532, 1883.2090521980801, 1907.6947688369794, 1938.9709154362695, 1946.5133716570858, 125677.37161314135, 137977.62022379748, 144695.55304446927, 154722.99999999886, 155745.0, 17729.29082836672, 18700.251989920253, 19455.3190638327, 19592.21631134767, 19666.31167909145, 29060.12819615415, 29082.27294317659, 29134.27164320909, 29144.0, 29217.123486295444, 8465.34925689497, 9131.0, 9307.813843723088, 9446.291528135418, 9590.760230994141, 7287.817804554928, 7781.688732450639, 7785.1436342002335, 7962.681492740341, 7968.561729104955, 6848.794536163923, 7262.910563415424, 8906.421082629608, 8955.552222388855, 9008.549572521348, 3170.461021626283, 3709.684676802502, 3797.2215695782297, 3803.676687481595, 3822.1591249924973, 816.506013861617, 817.1420008990536, 828.1263267252986, 846.8306338732154, 861.6811779641447, 20213.898930505253, 20643.4839129023, 20762.169513219604, 20885.0, 21122.47193820136, 837.3468694418364, 844.1853611265159, 846.9703560375357, 874.3835595904872, 875.2735229759285, 10555.155587552978, 10773.83839242412, 10792.082672972729, 10860.67417977462, 10861.565537378574, 544649.0, 547381.0, 552686.0, 600968.0, 689845.0, 139228.9115663266, 140898.88651670233, 142971.0, 146436.0, 150347.9652034788, 1174.506707182985, 1191.6127258640977, 1232.7411243638758, 1237.9195352302072, 1239.547565138736, 17575.82424175742, 18093.004884731465, 18182.727259090858, 18392.816071839112, 18660.626787464447, 43135.921601958944, 49252.01495969989, 59004.704976474844, 59382.70308648429, 60009.69995149996, 71767.0, 77612.61193693995, 81099.35052922515, 84109.0, 87466.37600871985, 10734.516946737573, 10775.08411785007, 10880.0, 10972.670819875417, 11033.503492342703, 12160.635180944586, 12211.71776963406, 12283.75432491363, 12409.503619855104, 12464.314462704639, 10354.378737275787, 10440.791184176425, 10573.8942610576, 10796.298240614335, 10894.891051089387, 9914.0, 10043.799124017625, 10134.898651013396, 10192.490375481048, 10676.359418434919, 10395.896041039796, 10597.4701264938, 10611.0, 10614.203934704774, 10765.03114719679, 10314.484275786332, 10590.311629744043, 10599.099076578268, 10959.945200274267, 11161.72095697614, 9814.852777208205, 10029.598816047277, 10326.793464130826, 10719.410432425979, 11033.503492342703], "value": 10694.794538351889, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1357.2670757790793, 1392.1647011792923, 1571.6935197636458, 1585.643230273188, 1588.7458006718928, 10241.385516868993, 10434.947825260873, 10496.632617858377, 11094.556217751291, 11099.278546894446, 10804.18968577356, 11001.944990275037, 11020.173486988468, 11025.393603351813, 11041.779164416706, 2308.226744040748, 2343.9296821095395, 2347.542229265294, 2347.6243800991847, 2354.1289722802585, 2291.049214575949, 2316.733575638806, 2328.6157783965627, 2332.416895776056, 2333.381653861728, 2273.6930514380597, 2321.5240875620543, 2334.299710086967, 2334.7665233476687, 2345.847519911209, 2290.7251129864358, 2308.319045881469, 2309.2148669452467, 2318.8144948404088, 2322.6980492535945, 2262.2760716570706, 2309.872956987365, 2310.2145270608075, 2313.57198918199, 2320.0951628864755, 10940.343579385262, 11047.281926674741, 11050.171237157245, 11057.944710276477, 11061.778764424747, 4519.932201016987, 4970.975145124287, 5331.626786124973, 5502.394736579013, 5844.678542680151, 3323.8504267307785, 3379.915502112467, 3472.305538892206, 3570.785752854837, 3591.712662986955, 1126.3667681861334, 1153.7288737146764, 1204.915655904087, 1218.2020776391478, 1230.6615680687794, 13405.0, 13609.31953402326, 14678.926605366905, 15149.166795826226, 15272.618184545365, 4724.480307166209, 4877.487863774323, 4888.853334399973, 4969.726665033458, 4982.177940639826, 11179.66461006171, 12877.162984405984, 12893.097483176087, 12902.161359511789, 12916.870831291755, 5654.830355089354, 5894.17481552583, 5985.461308482258, 5997.070454079625, 5999.640021598718, 4327.480702315742, 5121.974390128025, 5221.869453263623, 5299.099153143898, 5300.1519756838725, 3961.643452089268, 4014.397840323975, 4047.7976101195413, 4086.7956602170366, 4166.708330416842, 971.3831716859704, 976.8925418203992, 1104.1718710966775, 1127.7180704823795, 1138.755167638945, 801.2541369099437, 842.1704620948361, 880.8546589812737, 907.1744712311764, 928.3872644054892, 5614.438556144353, 6190.257169139642, 6509.772157974543, 6618.205815302098, 6634.668266586651, 812.3744716568209, 856.0839901305543, 972.1348000279756, 1000.3377505811262, 1043.639944219243, 3897.629725176104, 4050.7974601270407, 4140.751554906716, 4195.727277726938, 4237.555056719062, 12893.097483176087, 13292.933535332262, 13306.600801975956, 13333.666658333424, 13352.399142038632, 12986.740265194643, 13102.410391532403, 13176.0, 13327.267000314881, 13338.466461341632, 9606.903930960601, 9653.0, 9867.259955503367, 9870.358426702242, 9951.950240248607, 5740.39725828782, 6350.460210882126, 6633.469322454095, 6741.528093033637, 6842.897356539556, 9969.50152492358, 11333.0, 11749.88250117488, 11951.880481195076, 12053.0, 10932.781344373227, 11984.820227696766, 12112.15214934946, 12218.144729868824, 12271.07966902468, 8653.30773538128, 8821.98547167084, 8909.955450222707, 8967.50678712651, 9008.549572521217, 9434.433933963985, 9560.330776845833, 9640.951795240979, 9657.22742180638, 9681.612735490502, 8432.873506897278, 8579.828403431771, 8684.088170741981, 8744.956275218583, 8785.077566855643, 8682.78293042679, 8742.125787421208, 8826.338024648305, 8848.557572121239, 8860.822783544421, 7453.962730186315, 7676.15562288159, 7720.3051725344985, 7767.223277672188, 7775.650095745592, 5227.398849132246, 5334.199870019528, 5399.487048730364, 5456.945430545644, 5494.532964698045, 5713.885722285614, 5781.595288329777, 5911.408859114054, 6069.332373439007, 6075.696215189311], "value": 5013.81877590722, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [358.4032585744735, 372.3000758573881, 380.6079737869994, 380.6916397717849, 386.576698515126, 4215.7892105394685, 4262.87211383659, 4393.78031098445, 5073.771680274383, 5273.709945952971, 4974.87562810929, 5054.620903432239, 5055.747212639371, 5105.591552675786, 5128.5127313079165, 3953.8023098845088, 4163.355474587551, 4255.765618130213, 4269.679774016946, 4310.0, 4054.9189016219807, 4253.702240843142, 4287.742735435868, 4291.570842915715, 4304.096139810628, 3897.064704470935, 4035.3946907963746, 4219.050713589433, 4241.791089539482, 4259.233337999159, 3401.4829026520442, 3927.98036009821, 4104.897377565555, 4134.0, 4276.3799249109015, 3372.5278461015478, 3426.194844211608, 3964.5440774311037, 3966.3257246268204, 3971.086650070471, 4053.7973101344814, 4239.936400953989, 4925.458199598042, 4960.429550601691, 4989.226669866177, 4290.506591741959, 4422.955770442287, 4943.528235882059, 4983.252512123175, 5046.798128074873, 5120.257562653394, 5411.756470958816, 5463.470228336072, 5489.890202195974, 5513.889722205574, 4366.345048242757, 4373.453318335208, 4381.6494680425485, 4410.7794610269675, 4417.514073451917, 4777.5222477752295, 5137.0153770856905, 5230.92153617696, 5580.610201319507, 5773.509251713609, 3677.099110717838, 4200.0, 4440.737725794943, 4510.954890451119, 4525.751083690363, 4438.090191510732, 4560.693497214493, 4574.559013910607, 4642.326862604971, 4642.396488456479, 4650.790714417859, 4745.193317136095, 4794.184988551885, 4828.141165651249, 4951.529604687549, 4721.834735784231, 4916.975415122973, 5147.485251474897, 5458.4814442627885, 5496.230527726121, 1329.9002574806927, 1430.8354539228121, 1500.5573355860047, 1560.875129989598, 1568.7058177004017, 1070.55571937645, 1095.9232853700323, 1161.9941900290614, 1172.2263306217856, 1192.974042323601, 4298.484181898192, 4650.86047418578, 4813.136183970532, 5054.508919868647, 5094.0, 4828.251620998751, 4988.426330971912, 5043.420006699203, 5508.504234618904, 5738.110592858114, 4347.4131941841, 4356.586124318184, 4838.274258861128, 5133.717645529457, 5167.741612919339, 2771.021152261327, 2796.8461734604793, 2831.89907051778, 2874.3676391193903, 2980.0, 4957.3973663826055, 5445.774700692313, 5917.733701983421, 5929.835610002405, 5937.346891841894, 4690.577847993697, 5140.46025167352, 5316.813911513079, 5542.224088627595, 5760.452756988079, 5830.795922142789, 5918.674472904032, 5926.7925622603, 5941.910871336933, 5958.756294460847, 5013.924791128209, 5252.317198764289, 5724.740557077436, 5727.649325144837, 5762.279715035621, 4754.286856971482, 5570.387257401763, 5637.154426836008, 5658.264425624642, 5659.406277014044, 5049.343585333883, 5717.428257174416, 5831.300243970751, 5885.323187833454, 5948.613340132878, 3840.2895464339185, 4468.977655111704, 4495.865124046284, 4528.41130653013, 4537.5008749036915, 5378.542823859859, 5589.692566908859, 5685.8862822742485, 5793.971030144822, 5810.448007439287, 5093.23601459784, 5429.674219546682, 5468.507834294933, 5533.750981205775, 5537.145056406149, 4962.803934295358, 4964.751762411937, 4972.980538989571, 5007.669899516926, 5538.335399752055, 5069.873253168553, 5380.542653874308, 5383.273258110105, 5390.164524498709, 5478.561715062865, 4649.744264065509, 4745.976270118627, 5088.829569199031, 5269.815556455505, 5566.749496272759, 4814.494478079751, 4988.750562471934, 5230.529252367305, 5420.593455490775, 5482.643628164156], "value": 4169.575057630054, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [683.9897401538977, 700.7407259314051, 733.5671953547412, 810.226233946581, 822.5311572403729, 14291.928540357296, 15682.529524114267, 15962.600934976632, 15966.68066638668, 16000.919995400021, 14209.147451152912, 14941.252937353116, 15047.548573542785, 15065.774013389808, 15087.547373578811, 942.0108885670036, 969.1374676537887, 969.8642190093393, 973.5716284834685, 974.6929717139078, 825.6078362777693, 936.3398803843274, 970.4419958523841, 972.8005758819461, 990.8860481044665, 1020.8213562626511, 1022.371241686365, 1023.2479127841068, 1024.2932376660117, 1025.0108645157422, 1023.2223510132278, 1026.7535791410046, 1027.1628622672497, 1029.696239609317, 1032.303195343148, 764.0983639305617, 766.1572270502459, 766.7124828189429, 767.3477544087536, 768.9231076892322, 14885.181315027668, 15043.623909402244, 15175.165365904872, 15990.440334588233, 16197.51407457778, 14180.929095354457, 14527.564173074825, 14596.270186490632, 14708.852911470854, 14885.7767133492, 10716.946415267952, 10728.0, 10829.079528240109, 10830.67507974762, 11320.830187547193, 3634.327649384872, 3635.1820840310984, 3642.708583313355, 3687.3731465650903, 3772.8113594320175, 75029.87455188176, 92157.0, 105759.0, 106731.0, 107273.46363268212, 11082.944585277184, 11249.831252531056, 11435.771284574428, 11554.786747391403, 11644.76710465786, 24763.876180619343, 25014.99940002416, 25057.749422505905, 25071.997120114993, 25082.874585626956, 7373.078365204349, 7531.359834414026, 7601.08786945559, 7725.072991241087, 7878.763637090897, 4181.059261666101, 4219.409282700424, 4277.465316835395, 4321.330193819963, 4400.361947517592, 3423.7432192585657, 3435.9656403436147, 3471.7743346682387, 3479.4258947273925, 3608.260306637133, 681.386751923273, 681.7852376501386, 685.8559702462538, 697.9844326504923, 710.2116650517921, 646.3278190681639, 653.9247986481521, 678.1658559971263, 700.3416788219058, 703.1175874277851, 8701.564921753887, 9454.290928180277, 10064.899351006543, 10201.0, 10255.487225638688, 634.7524465458475, 669.7220653428869, 687.4981263677544, 688.2566827825935, 689.4622194688151, 4722.409698787652, 4922.4339200992345, 4931.753412329369, 4942.579880710108, 4942.9011419771405, 97476.51261743788, 98527.0, 99177.00822991822, 101504.81942166988, 101904.0, 63958.0, 66396.0, 72652.0, 74325.62837185779, 85022.0, 52581.999999999236, 56561.58596035132, 56728.71635641796, 67870.0, 70676.6466167666, 9199.402038867453, 10422.895771042193, 10489.265751397634, 10754.516046777757, 10778.407187604756, 37219.81390093032, 37516.81241593775, 42443.0, 46185.307220392395, 47294.0, 45955.0, 46953.76523117499, 57879.0, 59096.70451647714, 59214.0, 11448.656540303804, 13048.086633935533, 13234.272115033766, 13523.59429217125, 13579.0, 13892.374843131882, 13956.2324072177, 14031.68671026774, 14205.0, 14384.0, 13258.270795106362, 13302.800457993331, 13428.9011360697, 13732.519361822287, 14055.032295478846, 12654.430550625428, 13103.27931963713, 13588.320583970959, 13678.794818077937, 13765.311734413439, 12469.127161098999, 12605.49578016869, 13105.868941310466, 13133.212007279593, 13565.321733913463, 9714.854277185988, 10275.961695980295, 10365.118964888068, 10398.740031499274, 11211.271267367594, 9565.534138029077, 9576.473293968898, 10548.103411210133, 10620.362778233331, 10663.200259980687], "value": 7455.457086754801, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1554.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [132.0, 173.0, 98.0, 174.0, 174.0, 174.0, 122.0, 119.0, 148.0, 160.0], "value": 147.4, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [163.0, 115.0, 113.0, 151.0, 113.0, 113.0, 108.0, 113.0, 124.0, 142.0], "value": 125.5, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [237.0, 204.0, 194.0, 205.0, 197.0, 200.0, 194.0, 212.0, 197.0, 197.0], "value": 203.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 60.0, 62.0, 91.0, 61.0, 59.0, 61.0, 64.0, 72.0, 69.0], "value": 69.0, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 86.0, 105.0, 105.0, 89.0, 71.0, 83.0, 72.0, 71.0, 86.0], "value": 85.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 111.0, 88.0, 89.0, 89.0, 89.0, 95.0, 112.0, 90.0, 89.0], "value": 94.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 98.0, 97.0, 107.0, 96.0, 100.0, 97.0, 152.0, 97.0, 96.0], "value": 103.6, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 93.0, 92.0, 93.0, 97.0, 105.0, 95.0, 90.0, 91.0, 99.0], "value": 94.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 64.0, 64.0, 67.0, 67.0, 68.0, 67.0, 67.0, 65.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 64.0, 59.0, 70.0, 69.0, 64.0, 58.0, 58.0, 58.0, 61.0], "value": 62.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [118.0, 107.0, 77.0, 104.0, 81.0, 86.0, 109.0, 74.0, 102.0, 102.0], "value": 96.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 141.0, 141.0, 144.0, 144.0, 143.0, 143.0, 156.0, 259.0, 157.0], "value": 158.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 176.0, 174.0, 181.0, 174.0, 194.0, 208.0, 177.0, 177.0, 170.0], "value": 181.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 66.0, 69.0, 69.0, 69.0, 67.0, 67.0, 73.0, 67.0, 70.0], "value": 68.7, "unit": "score"}]}]} 09:00:57 INFO - Return code: 0 09:00:57 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 09:00:57 ERROR - # TBPL SUCCESS # 09:00:57 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 09:00:57 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 09:00:57 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 09:00:57 INFO - 2017-03-04 09:00:57.571 screenresolution[37129:318252] starting screenresolution argv=screenresolution get 09:00:57 INFO - 2017-03-04 09:00:57.573 screenresolution[37129:318252] Display 0: 1600x1200x32@60 09:00:57 INFO - 2017-03-04 09:00:57.580 screenresolution[37130:318255] starting screenresolution argv=screenresolution list 09:00:57 INFO - Available Modes on Display 0 09:00:57 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 09:00:57 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 09:00:57 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 09:00:57 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 09:00:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 09:00:57 INFO - Intel Iris: 09:00:57 INFO - Chipset Model: Intel Iris 09:00:57 INFO - Type: GPU 09:00:57 INFO - Bus: Built-In 09:00:57 INFO - VRAM (Dynamic, Max): 1536 MB 09:00:57 INFO - Vendor: Intel (0x8086) 09:00:57 INFO - Device ID: 0x0a2e 09:00:57 INFO - Revision ID: 0x0009 09:00:57 INFO - Displays: 09:00:57 INFO - Display: 09:00:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 09:00:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 09:00:57 INFO - Display Serial Number: 1600x1200 60 09:00:57 INFO - Main Display: Yes 09:00:57 INFO - Mirror: Off 09:00:57 INFO - Online: Yes 09:00:57 INFO - Rotation: Supported 09:00:57 INFO - Return code: 0 09:00:57 INFO - Running post-action listener: _resource_record_post_action 09:00:57 INFO - [mozharness: 2017-03-04 17:00:57.815376Z] Finished run-tests step (success) 09:00:57 INFO - Running post-run listener: _resource_record_post_run 09:00:57 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 32096768; Write bytes: 514922496; Read time: 289; Write time: 1603 09:00:57 INFO - TinderboxPrint: CPU usage
54.7% 09:00:57 INFO - TinderboxPrint: I/O read bytes / time
32,096,768 / 289 09:00:57 INFO - TinderboxPrint: I/O write bytes / time
514,922,496 / 1,603 09:00:57 INFO - TinderboxPrint: CPU idle
2,175.1 (45.3%) 09:00:57 INFO - TinderboxPrint: CPU system
969.3 (20.2%) 09:00:57 INFO - TinderboxPrint: CPU user
1,661.4 (34.6%) 09:00:57 INFO - TinderboxPrint: Swap in / out
236,433,408 / 0 09:00:57 INFO - install - Wall time: 20s; CPU: 54.0%; Read bytes: 205490688; Write bytes: 203065344; Read time: 15891; Write time: 412 09:00:57 INFO - run-tests - Wall time: 1182s; CPU: 55.0%; Read bytes: 31859200; Write bytes: 301043712; Read time: 286; Write time: 1156 09:00:58 INFO - Running post-run listener: _upload_blobber_files 09:00:58 INFO - Blob upload gear active. 09:00:58 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 09:00:58 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 09:00:58 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', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 09:00:58 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 Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 09:00:58 INFO - (blobuploader) - INFO - Open directory for files ... 09:00:58 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 09:00:58 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:58 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:00:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 09:00:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:00:59 INFO - (blobuploader) - INFO - Done attempting. 09:00:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 09:00:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:00:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 09:01:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:00 INFO - (blobuploader) - INFO - Done attempting. 09:01:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 09:01:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 09:01:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:01 INFO - (blobuploader) - INFO - Done attempting. 09:01:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 09:01:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 09:01:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 09:01:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 09:01:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 09:01:03 INFO - (blobuploader) - INFO - Done attempting. 09:01:03 INFO - (blobuploader) - INFO - Iteration through files over. 09:01:03 INFO - Return code: 0 09:01:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 09:01:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 09:01:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da98b11ee4a407bae9b319ae64b6d530d3b872649086110ab7a47107c5a042a2ef2d3f8898617b555a5d051ec47adf351d125e0746e9d7905d258336f88e58fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4bf3b2f776f72dc62c72d9ab03d27fdb81a06e9c35f86341577c56475a34caf2cb1a5900492b4b437c4b183f960454443c70fd09233e29725396dafcc89e4e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84d8dc1f435b2cb8c4bb586ff3fd8099f7a570c3b9eef95025b5ff80720da2c755207b67606be4eef9c29f2827a15cef1351913af3b7eb8c424ddc4c68ac982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6947fedf877dadbe563379a6cd98ff46cd5d5fd50809c9aec4ff93340d89809da294bd8e282bd4da7cf94527449188b2b3b5776346e15d37e9bb85cbaaaf25f4"} 09:01:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 09:01:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 09:01:03 INFO - Contents: 09:01:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da98b11ee4a407bae9b319ae64b6d530d3b872649086110ab7a47107c5a042a2ef2d3f8898617b555a5d051ec47adf351d125e0746e9d7905d258336f88e58fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4bf3b2f776f72dc62c72d9ab03d27fdb81a06e9c35f86341577c56475a34caf2cb1a5900492b4b437c4b183f960454443c70fd09233e29725396dafcc89e4e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84d8dc1f435b2cb8c4bb586ff3fd8099f7a570c3b9eef95025b5ff80720da2c755207b67606be4eef9c29f2827a15cef1351913af3b7eb8c424ddc4c68ac982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6947fedf877dadbe563379a6cd98ff46cd5d5fd50809c9aec4ff93340d89809da294bd8e282bd4da7cf94527449188b2b3b5776346e15d37e9bb85cbaaaf25f4"} 09:01:03 INFO - Running post-run listener: copy_logs_to_upload_dir 09:01:03 INFO - Copying logs to upload dir... 09:01:03 INFO - mkdir: /builds/slave/test/build/upload/logs 09:01:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1282.551803 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 22 secs) (at 2017-03-04 09:01:03.757134) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-04 09:01:03.760708) ========= 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.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da98b11ee4a407bae9b319ae64b6d530d3b872649086110ab7a47107c5a042a2ef2d3f8898617b555a5d051ec47adf351d125e0746e9d7905d258336f88e58fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4bf3b2f776f72dc62c72d9ab03d27fdb81a06e9c35f86341577c56475a34caf2cb1a5900492b4b437c4b183f960454443c70fd09233e29725396dafcc89e4e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84d8dc1f435b2cb8c4bb586ff3fd8099f7a570c3b9eef95025b5ff80720da2c755207b67606be4eef9c29f2827a15cef1351913af3b7eb8c424ddc4c68ac982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6947fedf877dadbe563379a6cd98ff46cd5d5fd50809c9aec4ff93340d89809da294bd8e282bd4da7cf94527449188b2b3b5776346e15d37e9bb85cbaaaf25f4"} build_url:https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010265 build_url: 'https://queue.taskcluster.net/v1/task/PCuhms7zTOCmsPRckB_ZQQ/artifacts/public/build/firefox-54.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/da98b11ee4a407bae9b319ae64b6d530d3b872649086110ab7a47107c5a042a2ef2d3f8898617b555a5d051ec47adf351d125e0746e9d7905d258336f88e58fc", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/fc4bf3b2f776f72dc62c72d9ab03d27fdb81a06e9c35f86341577c56475a34caf2cb1a5900492b4b437c4b183f960454443c70fd09233e29725396dafcc89e4e", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e84d8dc1f435b2cb8c4bb586ff3fd8099f7a570c3b9eef95025b5ff80720da2c755207b67606be4eef9c29f2827a15cef1351913af3b7eb8c424ddc4c68ac982", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/6947fedf877dadbe563379a6cd98ff46cd5d5fd50809c9aec4ff93340d89809da294bd8e282bd4da7cf94527449188b2b3b5776346e15d37e9bb85cbaaaf25f4"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-04 09:01:03.794057) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:01:03.794403) ========= 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.Rc8THygecd/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.zrcmJqjCJE/Listeners TMPDIR=/var/folders/rl/_gz3yxcs4s1f_08bbj7xmf7w00000w/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.004917 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-04 09:01:03.843070) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-04 09:01:03.843377) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-04 09:01:03.843707) ========= ========= Total master_lag: 0.19 =========