builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0121 starttime: 1482016707.08 results: success (0) buildid: 20161217140149 builduid: e07c5f664d7845cb983cb5f0ef45c0f5 revision: 5745bab28ff5e85128c774b56b4cc27c2afe2e1b ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.078130) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.078628) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.078940) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.222817) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.223315) ========= 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.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-12-17 15:18:27-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 164M=0s 2016-12-17 15:18:27 (164 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.606122 ========= master_lag: 0.08 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.910058) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.910403) ========= 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.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.026748 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:27.967049) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 15:18:27.967646) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 5745bab28ff5e85128c774b56b4cc27c2afe2e1b --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 5745bab28ff5e85128c774b56b4cc27c2afe2e1b --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-12-17 15:18:28,062 truncating revision to first 12 chars 2016-12-17 15:18:28,062 Setting DEBUG logging. 2016-12-17 15:18:28,062 attempt 1/10 2016-12-17 15:18:28,062 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/5745bab28ff5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-17 15:18:29,040 unpacking tar archive at: mozilla-central-5745bab28ff5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.254150 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-17 15:18:29.252105) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:29.252423) ========= script_repo_revision: 5745bab28ff5e85128c774b56b4cc27c2afe2e1b ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:29.252900) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:29.253199) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-17 15:18:29.305214) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-17 15:18:29.305645) ========= /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.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:18:29 INFO - MultiFileLogger online at 20161217 15:18:29 in /builds/slave/test 15:18:29 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 15:18:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:18:29 INFO - {'append_to_log': False, 15:18:29 INFO - 'base_work_dir': '/builds/slave/test', 15:18:29 INFO - 'blob_upload_branch': 'Firefox', 15:18:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:18:29 INFO - 'branch': 'Firefox', 15:18:29 INFO - 'buildbot_json_path': 'buildprops.json', 15:18:29 INFO - 'config_files': ('talos/mac_config.py',), 15:18:29 INFO - 'default_actions': ('clobber', 15:18:29 INFO - 'read-buildbot-config', 15:18:29 INFO - 'download-and-extract', 15:18:29 INFO - 'populate-webroot', 15:18:29 INFO - 'create-virtualenv', 15:18:29 INFO - 'install', 15:18:29 INFO - 'run-tests'), 15:18:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:18:29 INFO - 'download_minidump_stackwalk': True, 15:18:29 INFO - 'download_symbols': 'ondemand', 15:18:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:18:29 INFO - 'tooltool.py': '/tools/tooltool.py', 15:18:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:18:29 INFO - '/tools/misc-python/virtualenv.py')}, 15:18:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:18:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:18:29 INFO - 'installer_path': 'installer.exe', 15:18:29 INFO - 'log_level': 'info', 15:18:29 INFO - 'log_name': 'talos', 15:18:29 INFO - 'log_to_console': True, 15:18:29 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:18:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:18:29 INFO - 'opt_config_files': (), 15:18:29 INFO - 'pip_index': False, 15:18:29 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:29 INFO - 'cmd': ('bash', 15:18:29 INFO - '-c', 15:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:29 INFO - 'enabled': True, 15:18:29 INFO - 'halt_on_failure': False, 15:18:29 INFO - 'name': 'check_screen_resolution'},), 15:18:29 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:18:29 INFO - 'cmd': ('bash', 15:18:29 INFO - '-c', 15:18:29 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:18:29 INFO - 'enabled': True, 15:18:29 INFO - 'halt_on_failure': False, 15:18:29 INFO - 'name': 'check_screen_resolution'},), 15:18:29 INFO - 'run_cmd_checks_enabled': True, 15:18:29 INFO - 'sps_profile': False, 15:18:29 INFO - 'sps_profile_interval': 0, 15:18:29 INFO - 'suite': 'dromaeojs-e10s', 15:18:29 INFO - 'system_bits': '32', 15:18:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:18:29 INFO - 'title': 't-yosemite-r7-0121', 15:18:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:18:29 INFO - 'use_talos_json': True, 15:18:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:18:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:18:29 INFO - 'work_dir': 'build'} 15:18:29 INFO - [mozharness: 2016-12-17 23:18:29.511501Z] Running clobber step. 15:18:29 INFO - Running pre-action listener: _resource_record_pre_action 15:18:29 INFO - Running main action method: clobber 15:18:29 INFO - rmtree: /builds/slave/test/build 15:18:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:18:32 INFO - Running post-action listener: _resource_record_post_action 15:18:32 INFO - [mozharness: 2016-12-17 23:18:32.183884Z] Finished clobber step (success) 15:18:32 INFO - [mozharness: 2016-12-17 23:18:32.184138Z] Running read-buildbot-config step. 15:18:32 INFO - Running pre-action listener: _resource_record_pre_action 15:18:32 INFO - Running main action method: read_buildbot_config 15:18:32 INFO - Using buildbot properties: 15:18:32 INFO - { 15:18:32 INFO - "project": "", 15:18:32 INFO - "product": "firefox", 15:18:32 INFO - "script_repo_revision": "production", 15:18:32 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 15:18:32 INFO - "repository": "", 15:18:32 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 15:18:32 INFO - "buildid": "20161217140149", 15:18:32 INFO - "pgo_build": "False", 15:18:32 INFO - "basedir": "/builds/slave/test", 15:18:32 INFO - "buildnumber": 23, 15:18:32 INFO - "slavename": "t-yosemite-r7-0121", 15:18:32 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 15:18:32 INFO - "platform": "yosemite_r7", 15:18:32 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 15:18:32 INFO - "branch": "mozilla-central", 15:18:32 INFO - "repo_path": "mozilla-central", 15:18:32 INFO - "revision": "5745bab28ff5e85128c774b56b4cc27c2afe2e1b", 15:18:32 INFO - "stage_platform": "macosx64", 15:18:32 INFO - "builduid": "e07c5f664d7845cb983cb5f0ef45c0f5", 15:18:32 INFO - "slavebuilddir": "test" 15:18:32 INFO - } 15:18:32 INFO - Found installer url https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 15:18:32 INFO - Running post-action listener: _resource_record_post_action 15:18:32 INFO - [mozharness: 2016-12-17 23:18:32.189777Z] Finished read-buildbot-config step (success) 15:18:32 INFO - [mozharness: 2016-12-17 23:18:32.190004Z] Running download-and-extract step. 15:18:32 INFO - Running pre-action listener: _resource_record_pre_action 15:18:32 INFO - Running main action method: download_and_extract 15:18:32 INFO - mkdir: /builds/slave/test/build/tests 15:18:32 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')]} 15:18:32 INFO - trying https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 15:18:32 INFO - Downloading https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:18:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 15:18:33 INFO - Downloaded 1199 bytes. 15:18:33 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 15:18:33 INFO - Using the following test package requirements: 15:18:33 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:18:33 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 15:18:33 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 15:18:33 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'jsshell-mac.zip'], 15:18:33 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 15:18:33 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 15:18:33 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 15:18:33 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 15:18:33 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 15:18:33 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 15:18:33 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 15:18:33 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 15:18:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:18:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:33 INFO - Fetch https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:18:34 INFO - Expected file size: 16275310 15:18:34 INFO - Obtained file size: 16275310 15:18:40 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 15:18:40 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 15:18:40 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 15:18:40 INFO - Fetch https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 15:18:41 INFO - Expected file size: 16275310 15:18:41 INFO - Obtained file size: 16275310 15:18:48 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 15:18:48 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 15:18:48 INFO - Fetch https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 15:18:49 INFO - Expected file size: 14409734 15:18:49 INFO - Obtained file size: 14409734 15:18: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')]} 15:18:49 INFO - trying https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:18:49 INFO - Downloading https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 15:18:49 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 15:18:51 INFO - Downloaded 69578345 bytes. 15:18:51 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:18:51 INFO - mkdir: /builds/slave/test/properties 15:18:51 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:18:51 INFO - Writing to file /builds/slave/test/properties/build_url 15:18:51 INFO - Contents: 15:18:51 INFO - build_url:https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - Running post-action listener: set_extra_try_arguments 15:18:52 INFO - [mozharness: 2016-12-17 23:18:52.182858Z] Finished download-and-extract step (success) 15:18:52 INFO - [mozharness: 2016-12-17 23:18:52.183001Z] Running populate-webroot step. 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: populate_webroot 15:18:52 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:18:52 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:18:52 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:18:52 INFO - '--tppagecycles', 15:18:52 INFO - '7'], 15:18:52 INFO - 'tests': ['tsvgm']}}, 15:18:52 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:18:52 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:18:52 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:18:52 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:18:52 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:52 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:18:52 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['damp', 'tps']}, 15:18:52 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'tests': ['damp', 'tps']}, 15:18:52 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['dromaeo_dom']}, 15:18:52 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:18:52 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['basic_compositor_video']}, 15:18:52 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:18:52 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['a11yr', 15:18:52 INFO - 'ts_paint', 15:18:52 INFO - 'tpaint', 15:18:52 INFO - 'sessionrestore', 15:18:52 INFO - 'sessionrestore_no_auto_restore', 15:18:52 INFO - 'tabpaint']}, 15:18:52 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tsvgx', 15:18:52 INFO - 'tsvgr_opacity', 15:18:52 INFO - 'tart', 15:18:52 INFO - 'tscrollx', 15:18:52 INFO - 'cart', 15:18:52 INFO - 'tsvg_static']}, 15:18:52 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:18:52 INFO - 'tsvgr_opacity', 15:18:52 INFO - 'tart', 15:18:52 INFO - 'tscrollx', 15:18:52 INFO - 'cart', 15:18:52 INFO - 'tsvg_static']}, 15:18:52 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s'], 15:18:52 INFO - 'tests': ['tp5o']}, 15:18:52 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:18:52 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'talos_options': ['--disable-e10s', 15:18:52 INFO - '--xperf_path', 15:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:52 INFO - 'tests': ['tp5n']}, 15:18:52 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:18:52 INFO - 'talos_options': ['--xperf_path', 15:18:52 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:18:52 INFO - 'tests': ['tp5n']}}, 15:18:52 INFO - 'talos.zip': {'path': '', 15:18:52 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:18:52 INFO - Running post-action listener: _resource_record_post_action 15:18:52 INFO - [mozharness: 2016-12-17 23:18:52.201165Z] Finished populate-webroot step (success) 15:18:52 INFO - [mozharness: 2016-12-17 23:18:52.201299Z] Running create-virtualenv step. 15:18:52 INFO - Running pre-action listener: _resource_record_pre_action 15:18:52 INFO - Running main action method: create_virtualenv 15:18:52 INFO - Creating virtualenv /builds/slave/test/build/venv 15:18:52 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 15:18:52 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:18:52 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:18:52 INFO - Using real prefix '/tools/python27' 15:18:52 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:18:53 INFO - Installing distribute.............................................................................................................................................................................................done. 15:18:55 INFO - Installing pip.................done. 15:18:55 INFO - Return code: 0 15:18:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:18:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:18:56 INFO - Reading from file tmpfile_stdout 15:18:56 INFO - Output received: 15:18:56 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) 15:18:56 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 15:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 15:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:56 INFO - 'HOME': '/Users/cltbld', 15:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:56 INFO - 'LOGNAME': 'cltbld', 15:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:56 INFO - 'MOZ_NO_REMOTE': '1', 15:18:56 INFO - 'NO_EM_RESTART': '1', 15:18:56 INFO - 'PAGER': '/bin/cat', 15:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:56 INFO - 'PWD': '/builds/slave/test', 15:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:56 INFO - 'SHELL': '/bin/bash', 15:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:18:56 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:56 INFO - 'USER': 'cltbld', 15:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:56 INFO - 'XPC_FLAGS': '0x0', 15:18:56 INFO - 'XPC_SERVICE_NAME': '0', 15:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:56 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 15:18:56 INFO - Cleaning up... 15:18:56 INFO - Return code: 0 15:18:56 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:18:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:18:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:18:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 15:18:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 15:18:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:18:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:18:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:18:56 INFO - 'HOME': '/Users/cltbld', 15:18:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:18:56 INFO - 'LOGNAME': 'cltbld', 15:18:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:18:56 INFO - 'MOZ_NO_REMOTE': '1', 15:18:56 INFO - 'NO_EM_RESTART': '1', 15:18:56 INFO - 'PAGER': '/bin/cat', 15:18:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:18:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:18:56 INFO - 'PWD': '/builds/slave/test', 15:18:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:18:56 INFO - 'SHELL': '/bin/bash', 15:18:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:18:56 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:18:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:18:56 INFO - 'USER': 'cltbld', 15:18:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:18:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:18:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:18:56 INFO - 'XPC_FLAGS': '0x0', 15:18:56 INFO - 'XPC_SERVICE_NAME': '0', 15:18:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:18:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:18:56 INFO - Downloading/unpacking psutil>=3.1.1 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:18:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:18:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:19:00 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:00 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:00 INFO - Installing collected packages: psutil 15:19:00 INFO - Running setup.py install for psutil 15:19:00 INFO - building 'psutil._psutil_osx' extension 15:19:00 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 15:19:01 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 15:19:01 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 15:19:01 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 15:19:01 INFO - building 'psutil._psutil_posix' extension 15:19:01 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 15:19:01 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:19:01 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:19:01 INFO - ^ 15:19:01 INFO - 1 warning generated. 15:19:01 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 15:19:01 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:19:01 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:19:01 INFO - Successfully installed psutil 15:19:01 INFO - Cleaning up... 15:19:01 INFO - Return code: 0 15:19:01 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:19:01 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')]} 15:19:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:01 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')]} 15:19:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:01 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:01 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 15:19:01 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 15:19:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:01 INFO - 'HOME': '/Users/cltbld', 15:19:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:01 INFO - 'LOGNAME': 'cltbld', 15:19:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:01 INFO - 'MOZ_NO_REMOTE': '1', 15:19:01 INFO - 'NO_EM_RESTART': '1', 15:19:01 INFO - 'PAGER': '/bin/cat', 15:19:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:01 INFO - 'PWD': '/builds/slave/test', 15:19:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:01 INFO - 'SHELL': '/bin/bash', 15:19:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:01 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:01 INFO - 'USER': 'cltbld', 15:19:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:01 INFO - 'XPC_FLAGS': '0x0', 15:19:01 INFO - 'XPC_SERVICE_NAME': '0', 15:19:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:02 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:19:02 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 15:19:02 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 15:19:02 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 15:19:02 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 15:19:02 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 15:19:02 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 15:19:04 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:19:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:19:04 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:19:04 INFO - Installing collected packages: mozsystemmonitor 15:19:04 INFO - Running setup.py install for mozsystemmonitor 15:19:04 INFO - Successfully installed mozsystemmonitor 15:19:04 INFO - Cleaning up... 15:19:05 INFO - Return code: 0 15:19:05 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:19:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:19:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:05 INFO - 'HOME': '/Users/cltbld', 15:19:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:05 INFO - 'LOGNAME': 'cltbld', 15:19:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:05 INFO - 'MOZ_NO_REMOTE': '1', 15:19:05 INFO - 'NO_EM_RESTART': '1', 15:19:05 INFO - 'PAGER': '/bin/cat', 15:19:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:05 INFO - 'PWD': '/builds/slave/test', 15:19:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:05 INFO - 'SHELL': '/bin/bash', 15:19:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:05 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:05 INFO - 'USER': 'cltbld', 15:19:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:05 INFO - 'XPC_FLAGS': '0x0', 15:19:05 INFO - 'XPC_SERVICE_NAME': '0', 15:19:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:05 INFO - Downloading/unpacking jsonschema==2.5.1 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:07 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:19:07 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:08 INFO - Downloading functools32-3.2.3-2.tar.gz 15:19:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:19:08 INFO - warning: no files found matching '*.txt' 15:19:08 INFO - no previously-included directories found matching 'build' 15:19:08 INFO - no previously-included directories found matching 'dist' 15:19:08 INFO - no previously-included directories found matching '.git*' 15:19:08 INFO - Installing collected packages: jsonschema, functools32 15:19:08 INFO - Running setup.py install for functools32 15:19:08 INFO - warning: no files found matching '*.txt' 15:19:08 INFO - no previously-included directories found matching 'build' 15:19:08 INFO - no previously-included directories found matching 'dist' 15:19:08 INFO - no previously-included directories found matching '.git*' 15:19:08 INFO - Successfully installed jsonschema functools32 15:19:08 INFO - Cleaning up... 15:19:08 INFO - Return code: 0 15:19:08 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:19:08 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')]} 15:19:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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')]} 15:19:08 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:08 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:08 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:08 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 15:19:08 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 15:19:08 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:08 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:08 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:08 INFO - 'HOME': '/Users/cltbld', 15:19:08 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:08 INFO - 'LOGNAME': 'cltbld', 15:19:08 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:08 INFO - 'MOZ_NO_REMOTE': '1', 15:19:08 INFO - 'NO_EM_RESTART': '1', 15:19:08 INFO - 'PAGER': '/bin/cat', 15:19:08 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:08 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:08 INFO - 'PWD': '/builds/slave/test', 15:19:08 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:08 INFO - 'SHELL': '/bin/bash', 15:19:08 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:08 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:08 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:08 INFO - 'USER': 'cltbld', 15:19:08 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:08 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:08 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:08 INFO - 'XPC_FLAGS': '0x0', 15:19:08 INFO - 'XPC_SERVICE_NAME': '0', 15:19:08 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:08 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:08 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:19:08 INFO - Cleaning up... 15:19:09 INFO - Return code: 0 15:19:09 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:09 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:09 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 15:19:09 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 15:19:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:09 INFO - 'HOME': '/Users/cltbld', 15:19:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:09 INFO - 'LOGNAME': 'cltbld', 15:19:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:09 INFO - 'MOZ_NO_REMOTE': '1', 15:19:09 INFO - 'NO_EM_RESTART': '1', 15:19:09 INFO - 'PAGER': '/bin/cat', 15:19:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:09 INFO - 'PWD': '/builds/slave/test', 15:19:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:09 INFO - 'SHELL': '/bin/bash', 15:19:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:09 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:09 INFO - 'USER': 'cltbld', 15:19:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:09 INFO - 'XPC_FLAGS': '0x0', 15:19:09 INFO - 'XPC_SERVICE_NAME': '0', 15:19:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:09 INFO - Downloading/unpacking blobuploader==1.2.4 15:19:09 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 15:19:09 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 15:19:09 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 15:19:09 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 15:19:09 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 15:19:09 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 15:19:11 INFO - Downloading blobuploader-1.2.4.tar.gz 15:19:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:19:11 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:11 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 15:19:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:19:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:19:12 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:19:12 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:19:13 INFO - Downloading docopt-0.6.1.tar.gz 15:19:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:19:13 INFO - Installing collected packages: blobuploader, requests, docopt 15:19:13 INFO - Running setup.py install for blobuploader 15:19:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:19:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:19:13 INFO - Running setup.py install for requests 15:19:13 INFO - Running setup.py install for docopt 15:19:13 INFO - Successfully installed blobuploader requests docopt 15:19:13 INFO - Cleaning up... 15:19:14 INFO - Return code: 0 15:19:14 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:14 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')]} 15:19:14 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:14 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:14 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')]} 15:19:14 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:14 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:14 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:14 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 15:19:14 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 15:19:14 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:14 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:14 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:14 INFO - 'HOME': '/Users/cltbld', 15:19:14 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:14 INFO - 'LOGNAME': 'cltbld', 15:19:14 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:14 INFO - 'MOZ_NO_REMOTE': '1', 15:19:14 INFO - 'NO_EM_RESTART': '1', 15:19:14 INFO - 'PAGER': '/bin/cat', 15:19:14 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:14 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:14 INFO - 'PWD': '/builds/slave/test', 15:19:14 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:14 INFO - 'SHELL': '/bin/bash', 15:19:14 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:14 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:14 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:14 INFO - 'USER': 'cltbld', 15:19:14 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:14 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:14 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:14 INFO - 'XPC_FLAGS': '0x0', 15:19:14 INFO - 'XPC_SERVICE_NAME': '0', 15:19:14 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:14 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-9ccZa6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pFoXom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-Y4Thio-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-h8J0ke-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-v6hoh8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-UAdXPw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:14 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3n3qHr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-x9lcpu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-QpOYJe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-gbHL8Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-bW7XVh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-KTpc4a-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-5FHPhQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pFvAz9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ODceuU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-lJiCXk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:15 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pXSy97-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:16 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:19:16 INFO - Running setup.py install for manifestparser 15:19:16 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:19:16 INFO - Running setup.py install for mozcrash 15:19:16 INFO - Running setup.py install for mozdebug 15:19:16 INFO - Running setup.py install for mozdevice 15:19:16 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:19:16 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:19:16 INFO - Running setup.py install for mozfile 15:19:16 INFO - Running setup.py install for mozhttpd 15:19:16 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:19:16 INFO - Running setup.py install for mozinfo 15:19:17 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Running setup.py install for mozInstall 15:19:17 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Running setup.py install for mozleak 15:19:17 INFO - Running setup.py install for mozlog 15:19:17 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Running setup.py install for moznetwork 15:19:17 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:19:17 INFO - Running setup.py install for mozprocess 15:19:17 INFO - Running setup.py install for mozprofile 15:19:18 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:19:18 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:19:18 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:19:18 INFO - Running setup.py install for mozrunner 15:19:18 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:19:18 INFO - Running setup.py install for mozscreenshot 15:19:18 INFO - Running setup.py install for moztest 15:19:18 INFO - Running setup.py install for mozversion 15:19:18 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:19:18 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:19:18 INFO - Cleaning up... 15:19:18 INFO - Return code: 0 15:19:18 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:18 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:18 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 15:19:18 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 15:19:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:18 INFO - 'HOME': '/Users/cltbld', 15:19:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:18 INFO - 'LOGNAME': 'cltbld', 15:19:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:18 INFO - 'MOZ_NO_REMOTE': '1', 15:19:18 INFO - 'NO_EM_RESTART': '1', 15:19:18 INFO - 'PAGER': '/bin/cat', 15:19:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:18 INFO - 'PWD': '/builds/slave/test', 15:19:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:18 INFO - 'SHELL': '/bin/bash', 15:19:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:18 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:18 INFO - 'USER': 'cltbld', 15:19:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:18 INFO - 'XPC_FLAGS': '0x0', 15:19:18 INFO - 'XPC_SERVICE_NAME': '0', 15:19:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:19:18 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-3243UK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-8kz41b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-1no2hx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-fzx2YX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-RgESS9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-YzP3ps-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-ukIXFQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-X5KgHG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-JpaKnd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:19:19 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)) 15:19:19 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:19:19 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-tbDSmg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:19:20 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)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-VNvRLF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:19:20 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)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-RVdDdG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-pBxkLK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:19:20 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)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-NwY6e5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:19:20 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)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-OXl9G2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:19:20 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)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-yhLBUZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:19:20 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 15:19:20 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:19:20 INFO - Running setup.py (path:/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/pip-zas8TR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:19:20 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)) 15:19:20 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)) 15:19:20 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)) 15:19:20 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)) 15:19:20 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)) 15:19:20 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)) 15:19:20 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:19:20 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 15:19:20 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 15:19:20 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 15:19:20 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 15:19:20 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 15:19:20 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 15:19:23 INFO - Downloading blessings-1.6.tar.gz 15:19:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:19:23 INFO - Installing collected packages: blessings 15:19:23 INFO - Running setup.py install for blessings 15:19:23 INFO - Successfully installed blessings 15:19:23 INFO - Cleaning up... 15:19:23 INFO - Return code: 0 15:19:23 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:19:23 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:23 INFO - Reading from file tmpfile_stdout 15:19:23 INFO - Current package versions: 15:19:23 INFO - blessings == 1.6 15:19:23 INFO - blobuploader == 1.2.4 15:19:23 INFO - docopt == 0.6.1 15:19:23 INFO - functools32 == 3.2.3-2 15:19:23 INFO - jsonschema == 2.5.1 15:19:23 INFO - manifestparser == 1.1 15:19:23 INFO - mozInstall == 1.12 15:19:23 INFO - mozcrash == 1.0 15:19:23 INFO - mozdebug == 0.1 15:19:23 INFO - mozdevice == 0.49 15:19:23 INFO - mozfile == 1.2 15:19:23 INFO - mozhttpd == 0.7 15:19:23 INFO - mozinfo == 0.9 15:19:23 INFO - mozleak == 0.1 15:19:23 INFO - mozlog == 3.4 15:19:23 INFO - moznetwork == 0.27 15:19:23 INFO - mozprocess == 0.23 15:19:23 INFO - mozprofile == 0.28 15:19:23 INFO - mozrunner == 6.13 15:19:23 INFO - mozscreenshot == 0.1 15:19:23 INFO - mozsystemmonitor == 0.3 15:19:23 INFO - moztest == 0.7 15:19:23 INFO - mozversion == 1.4 15:19:23 INFO - psutil == 3.1.1 15:19:23 INFO - requests == 1.2.3 15:19:23 INFO - wsgiref == 0.1.2 15:19:23 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:23 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:23 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 15:19:23 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 15:19:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:23 INFO - 'HOME': '/Users/cltbld', 15:19:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:23 INFO - 'LOGNAME': 'cltbld', 15:19:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:23 INFO - 'MOZ_NO_REMOTE': '1', 15:19:23 INFO - 'NO_EM_RESTART': '1', 15:19:23 INFO - 'PAGER': '/bin/cat', 15:19:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:23 INFO - 'PWD': '/builds/slave/test', 15:19:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:23 INFO - 'SHELL': '/bin/bash', 15:19:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:23 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:23 INFO - 'USER': 'cltbld', 15:19:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:23 INFO - 'XPC_FLAGS': '0x0', 15:19:23 INFO - 'XPC_SERVICE_NAME': '0', 15:19:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 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)) 15:19:24 INFO - Cleaning up... 15:19:24 INFO - Return code: 0 15:19:24 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:19:24 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')]} 15:19:24 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:19:24 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:24 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')]} 15:19:24 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:19:24 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:19:24 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 0x108ed2b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108ed5768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f95c3c8a460>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, '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.kc2sLuuNsW/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.hIExWrR62s/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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 15:19:24 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 15:19:24 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 15:19:24 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:24 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:24 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:24 INFO - 'HOME': '/Users/cltbld', 15:19:24 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:24 INFO - 'LOGNAME': 'cltbld', 15:19:24 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:24 INFO - 'MOZ_NO_REMOTE': '1', 15:19:24 INFO - 'NO_EM_RESTART': '1', 15:19:24 INFO - 'PAGER': '/bin/cat', 15:19:24 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:24 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:24 INFO - 'PWD': '/builds/slave/test', 15:19:24 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:24 INFO - 'SHELL': '/bin/bash', 15:19:24 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:24 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:24 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:24 INFO - 'USER': 'cltbld', 15:19:24 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:24 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:24 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:24 INFO - 'XPC_FLAGS': '0x0', 15:19:24 INFO - 'XPC_SERVICE_NAME': '0', 15:19:24 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:24 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:19:24 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:19:24 INFO - Cleaning up... 15:19:24 INFO - Return code: 0 15:19:24 INFO - Running post-action listener: _resource_record_post_action 15:19:24 INFO - Running post-action listener: _start_resource_monitoring 15:19:24 INFO - Starting resource monitoring. 15:19:24 INFO - [mozharness: 2016-12-17 23:19:24.549052Z] Finished create-virtualenv step (success) 15:19:24 INFO - [mozharness: 2016-12-17 23:19:24.550116Z] Running install step. 15:19:24 INFO - Running pre-action listener: _resource_record_pre_action 15:19:24 INFO - Running main action method: install 15:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:19:24 INFO - Reading from file tmpfile_stdout 15:19:24 INFO - Detecting whether we're running mozinstall >=1.0... 15:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:19:24 INFO - Reading from file tmpfile_stdout 15:19:24 INFO - Output received: 15:19:24 INFO - Usage: mozinstall [options] installer 15:19:24 INFO - Options: 15:19:24 INFO - -h, --help show this help message and exit 15:19:24 INFO - -d DEST, --destination=DEST 15:19:24 INFO - Directory to install application into. [default: 15:19:24 INFO - "/builds/slave/test"] 15:19:24 INFO - --app=APP Application being installed. [default: firefox] 15:19:24 INFO - mkdir: /builds/slave/test/build/application 15:19:24 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 15:19:24 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 15:19:46 INFO - Reading from file tmpfile_stdout 15:19:46 INFO - Output received: 15:19:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 15:19:46 INFO - Running post-action listener: _resource_record_post_action 15:19:46 INFO - [mozharness: 2016-12-17 23:19:46.713533Z] Finished install step (success) 15:19:46 INFO - [mozharness: 2016-12-17 23:19:46.713686Z] Running run-tests step. 15:19:46 INFO - Running pre-action listener: _resource_record_pre_action 15:19:46 INFO - Running main action method: run_tests 15:19:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:19:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:19:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:19:46 INFO - 2016-12-17 15:19:46.728 screenresolution[1628:8550] starting screenresolution argv=screenresolution get 15:19:46 INFO - 2016-12-17 15:19:46.730 screenresolution[1628:8550] Display 0: 1600x1200x32@60 15:19:46 INFO - 2016-12-17 15:19:46.736 screenresolution[1629:8553] starting screenresolution argv=screenresolution list 15:19:46 INFO - Available Modes on Display 0 15:19:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:19:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:19:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:19:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:19:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:19:46 INFO - Intel Iris: 15:19:46 INFO - Chipset Model: Intel Iris 15:19:46 INFO - Type: GPU 15:19:46 INFO - Bus: Built-In 15:19:46 INFO - VRAM (Dynamic, Max): 1536 MB 15:19:46 INFO - Vendor: Intel (0x8086) 15:19:46 INFO - Device ID: 0x0a2e 15:19:46 INFO - Revision ID: 0x0009 15:19:46 INFO - Displays: 15:19:46 INFO - Display: 15:19:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:19:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:19:46 INFO - Display Serial Number: 1600x1200 60 15:19:46 INFO - Main Display: Yes 15:19:46 INFO - Mirror: Off 15:19:46 INFO - Online: Yes 15:19:46 INFO - Rotation: Supported 15:19:46 INFO - Return code: 0 15:19:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:19:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:19:46 INFO - Python 2.7.3 15:19:46 INFO - Return code: 0 15:19:46 INFO - grabbing minidump binary from tooltool 15:19:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:19:46 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 0x108cb0d30>, '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 0x108bd7db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x108ca5be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:19:46 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 15:19:46 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 15:19:46 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 15:19:47 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:19:47 INFO - Return code: 0 15:19:47 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:19:47 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:19:47 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:19:47 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:19:47 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:19:47 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/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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 15:19:47 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/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0121 --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 15:19:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.kc2sLuuNsW/Render', 15:19:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:19:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:19:47 INFO - 'HOME': '/Users/cltbld', 15:19:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:19:47 INFO - 'LOGNAME': 'cltbld', 15:19:47 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:19:47 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:19:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:19:47 INFO - 'MOZ_NO_REMOTE': '1', 15:19:47 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:19:47 INFO - 'NO_EM_RESTART': '1', 15:19:47 INFO - 'PAGER': '/bin/cat', 15:19:47 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:19:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:19:47 INFO - 'PWD': '/builds/slave/test', 15:19:47 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:19:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:19:47 INFO - 'SHELL': '/bin/bash', 15:19:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hIExWrR62s/Listeners', 15:19:47 INFO - 'TMPDIR': '/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/', 15:19:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:19:47 INFO - 'USER': 'cltbld', 15:19:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:19:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:19:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:19:47 INFO - 'XPC_FLAGS': '0x0', 15:19:47 INFO - 'XPC_SERVICE_NAME': '0', 15:19:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:19:47 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/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0121', '--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 15:19:47 INFO - mozversion application_buildid: 20161217140149 15:19:47 INFO - mozversion application_changeset: 5745bab28ff5e85128c774b56b4cc27c2afe2e1b 15:19:47 INFO - mozversion application_display_name: Nightly 15:19:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:19:47 INFO - mozversion application_name: Firefox 15:19:47 INFO - mozversion application_remotingname: firefox 15:19:47 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 15:19:47 INFO - mozversion application_vendor: Mozilla 15:19:47 INFO - mozversion application_version: 53.0a1 15:19:47 INFO - mozversion platform_buildid: 20161217140149 15:19:47 INFO - mozversion platform_changeset: 5745bab28ff5e85128c774b56b4cc27c2afe2e1b 15:19:47 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 15:19:47 INFO - mozversion platform_version: 53.0a1 15:19:47 INFO - using testdate: 1482016787 15:19:47 INFO - actual date: 1482016787 15:19:47 INFO - starting webserver on 'localhost:49249' 15:19:47 INFO - SUITE-START | Running 2 tests 15:19:47 INFO - TEST-START | dromaeo_css 15:19:47 INFO - operating with platform_type : mac_ 15:19:47 INFO - Initialising browser for dromaeo_css test... 15:19:47 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpNCLZzA/profile http://localhost:49249/getInfo.html) 15:19:50 INFO - PROCESS | 1639 | 2016-12-17 15:19:50.364 plugin-container[1641:8713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4953, name = 'com.apple.tsm.portname' 15:19:50 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:50 INFO - PROCESS | 1639 | 2016-12-17 15:19:50.366 plugin-container[1641:8713] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x910f, name = 'com.apple.CFPasteboardClient' 15:19:50 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:50 INFO - PROCESS | 1639 | 2016-12-17 15:19:50.366 plugin-container[1641:8713] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:19:50 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 15:19:50 INFO - PROCESS | 1639 | colorDepth:24 15:19:50 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 15:19:50 INFO - PROCESS | 1639 | __metrics 15:19:50 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 15:19:51 INFO - TEST-INFO | 1639: exit 0 15:19:51 INFO - Browser initialized. 15:19:51 INFO - Running cycle 1/1 for dromaeo_css test... 15:19:51 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpNCLZzA/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:19:53 INFO - PROCESS | 1643 | 2016-12-17 15:19:53.697 plugin-container[1644:8838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434b, name = 'com.apple.tsm.portname' 15:19:53 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:53 INFO - PROCESS | 1643 | 2016-12-17 15:19:53.698 plugin-container[1644:8838] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x434f, name = 'com.apple.CFPasteboardClient' 15:19:53 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:19:53 INFO - PROCESS | 1643 | 2016-12-17 15:19:53.698 plugin-container[1644:8838] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:20:03 INFO - PROCESS | 1643 | RSS: Main: 236785664 15:20:03 INFO - PROCESS | 1643 | 15:20:52 INFO - PROCESS | 1643 | 1482016852931 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 15:23:06 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49249/tests/dromaeo/cssquery-ext.html) 15:23:08 INFO - PROCESS | 1643 | RSS: Main: 253206528 15:23:08 INFO - PROCESS | 1643 | 15:26:10 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-ext.html (next: http://localhost:49249/tests/dromaeo/cssquery-jquery.html) 15:26:11 INFO - PROCESS | 1643 | RSS: Main: 264998912 15:26:11 INFO - PROCESS | 1643 | 15:29:13 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49249/tests/dromaeo/cssquery-mootools.html) 15:29:14 INFO - PROCESS | 1643 | RSS: Main: 270315520 15:29:14 INFO - PROCESS | 1643 | 15:32:16 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49249/tests/dromaeo/cssquery-prototype.html) 15:32:16 INFO - PROCESS | 1643 | RSS: Main: 268845056 15:32:16 INFO - PROCESS | 1643 | 15:35:19 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49249/tests/dromaeo/cssquery-yui.html) 15:35:20 INFO - PROCESS | 1643 | RSS: Main: 273436672 15:35:20 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49249/tests/dromaeo/cssquery-yui.html (next: http://localhost:49249/tests/dromaeo/cssquery-dojo.html) 15:38:23 INFO - PROCESS | 1643 | RSS: Main: 276271104 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | __start_tp_report 15:38:23 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 15:38:23 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 15:38:23 INFO - PROCESS | 1643 | |i|pagename|runs| 15:38:23 INFO - PROCESS | 1643 | |0;dojo.html;3266.7876588021777;3294.68700473455;3298.505224216369;3319;3334.566506354174;5403.21653360263;5411.675299482029;5432.782688692452;5445.210444485557;5470;5362.383325917511;5413.404525502201;5460.044492213867;5514.558835293177;5547.389787123413;5017.42299635542;5056.786371270905;5073.188289873621;5075.289459475676;5104.387473503185;4999.250112483121;5021.146405111122;5097.872553186163;5113.2074528447965;5130.589552835783;3867.5939026402243;4329.242382583051;4417.293233082707;5111.5529489344135;5133.897322053556;4154.376843473473;4928.876778080541;4971.676841005359;4976.253561965698;5041.092603331399;4918.729469879156;5009.524095210949;5017.824376146854;5024.070546948789;5055.090083784918;4276.31578947367;4440.955590444087;5309;5393.541548968343;5439.918401223985;5926.555508336892;5999.790007349766;6013.218281623404;6018.518518518508;6033.09503574463;5980.910286345708;5985.042393217064;6000.189974353494;6051.1225872248715;6102.511799056065;4676.485586585472;5539.861503462405;5547.889042219133;5555.833325000256;5558.221848941152;6078.787242446581;6127.525510585588;6148.44663980244;6164.229471316085;6172.753089876444;5739.8565035874435;5894.616849904743;5903.26209223847;5922.733476993546;5997.940020599738;6091.08633704939;6110.816675499742;6111.052786818051;6117.082437634302;6155.692215389213;5805.129230615442;5859.033259512218;5982.252218472691;6014.6391216527145;6041.335453100155;5946.256717910262;5998.790042348497;6032.969835150796;6041.969790151021;6088.452039316483;4721.409823772028;4754.168020596382;4765.809367625326;4784.880377990508;4806.615470762331;4225.809838557272;4235.385869049032;4276.786160691953;4325.697201195886;4345.630621397217;5590.748416321276;5620.9437905621235;5621.325440947113;5623.409541998113;5640.125780503947;3937;5409.810656627078;5602.551795856321;6060.484858787053;6096.786612468542;5715.171300161536;5729.598928074995;5739.339975902824;5760.53915686744;5788.942110578924;4991.850244492671;5205.24523944026;5221.843344699665;5232.293640358579;5248.343957005374;6148.432149801772;6169.722362493609;6191.721372538156;6212.192414986113;6225.533085018732;6107.3405344170405;6122.97760420812;6127.097902969212;6128.655903792168;6139.246828488627;6204.5967012144065;6234.781782637768;6240.126382306572;6268.523933046512;6273.404026617646;5810.506106980785;5876.265466816648;5882;6003.129546215687;6044;5931.051031834886;6021.578489505692;6062.454379105901;6062.999605065115;6080.300765412034;5876.911846322394;5879.529637629065;5912.704364781658;5935.376785437639;5947.762089516371;4324.762138082436;4850.6119510438375;5076.746162691777;5321.866953326198;5882.941070607332;4977.676451030671;5375.19372094189;5646.830595082154;5676.063449530946;5690.402507736628;5528.944710552843;5595.300587426572;5611.466910643482;5812.447817457335;5832.533397328289;5515;5581.804636837691;5628.296462942132;5634.042212823748;5714.971425142848 15:38:23 INFO - PROCESS | 1643 | |1;ext.html;435.57748983486016;493.83209708699036;501.0380070265091;525.6451894970894;530.2417542913637;28651.570226446594;38463.615363846424;38520.42219366708;38589.80705096477;38595.421068683994;39117.80441097791;39668;39707.80146099273;39747.602523974754;39787.80106099473;8321.375896807733;8512;9081.409708368936;9360.438373697565;11021.559137634486;11071.391073490955;11316.943415282913;11327.716807079847;11330.263532870336;11346.829797553044;11309.982101610853;11361.488733006992;11362.31826090433;11396.14528910331;11435.828462573027;11286.717832054183;11305.547778088867;11313.208075434723;11324.433778311093;11332.6033588824;11197.16021298406;11239.269447485969;11242.325460472315;11243.437828108561;11265.774684506347;38840.8347749568;38937.41593876094;39073.60926390728;39213.60786392128;39255.607443925765;3359.947435489023;3406.3017081498233;3515.753897227195;3636.399994001013;3651.7261205409436;29213.415731685458;29286.41427171466;29334.266643333875;29589.70410295891;29785.40429191404;3104.425681248976;3154.779165458419;3161.1464904475893;3164.2563997460807;3241.432749268892;477846;484330;485952;486957;488137;55528;55676.72161639247;55944.44055559474;55962.44037559654;55978.16032759512;57163.85672286531;57958;58024;58193.41806581965;58292.708536457045;15066.020708654121;15333.233338333039;15650;15660.765088523682;15664.843351566566;11057.06014988735;11399;12040.939795300967;12298.569550065704;12374.566890158982;14730.852691473163;14759;14774.556763297118;14803.777943330859;14899.106053636817;5502.752376143083;5986.970065149646;6077.3314935357075;6131.938680613137;6266.717997690115;798.5328582779023;868.3357231717741;923.4459324405303;928.9071092890792;983.2085171436919;22405.775942240696;28028.43943121126;28193.15420537387;28772.136835894955;28845;988.2884323287221;1005.0954141272766;1012.9797404051877;1052.2476429353048;1127.8590176227972;14086.577402677936;14651.435998700184;14695.185356558228;14742.705145897236;14851;593821;594248;596571;600590.997045012;603981;52477.73761131246;55977.44022559804;55997;56034.87930241373;56054;1344.6150465021044;1367.7196174784146;1370.9314534273246;1377.7106807570522;1381.5924302330836;22753;26903.730962690905;27559.448811024067;28323.858380707963;28333.858330709038;38572.421413678254;41809.79095104505;47511.287330689374;47528.524714753796;47760.52239477561;462163;464901;520669;529613;540416;32969.340613188084;33951.66048339584;35292.64707352893;36890.63109368872;37971.81014094912;44097.77951110224;44498.88752781206;44580.777096115606;44588;45514.089718206116;30974.28308602982;36767.63232367642;37819;38076;38676.80661596674;29388.412231755672;33352.66647333593;36564;37341.25317493689;37829.43255851109;35715;36466.27067458583;36597.08507287339;36649;36968;23196.88401558049;32325.51511727279;33493.66506334906;33594;33853.3229335407;19315;23135.07459701593;23637.409064773517;24173;24280 15:38:23 INFO - PROCESS | 1643 | |2;jquery.html;341.99658003419967;368.3682484539017;377.30199131606525;385.71457121729895;395.5984675554311;11164.162687798416;11622.767544649101;11748.823767643493;11760.411979401017;18972.051397430125;20591.794082059176;21005.68491472629;21348.4662883428;21590.028448719808;21802;6392.968035159829;6794.728210871559;6796.728130874759;6804.421624161952;6841.726330946756;6221.502279817626;6385.584936979105;6618.238902526199;6674.799756007327;6683.532152749285;6002.609830361034;6675.866482670343;6678.1986161660425;6681.3986741193285;7125.9643701781415;7424.665890034935;7666.769996900102;7681.654325555363;7681.923180768176;7699.076110866704;6981.0924579804805;7150;7212.53118547298;7229.2047874733735;7238.335182907961;26747.57451402802;26805;26954.056608018822;27397.17808465749;27545.862270688718;2404.158544509425;2563.717991020986;2599.1812579037733;2599.636050952868;3236.530703048045;14375.496857610038;15681.843181568267;15794.921025394915;15859.127747973856;16713.916430417892;1818.5635447492507;1948.2207117153253;1963.3619073801071;2018.5256464730776;2026.230032587622;92899;137180.31409842987;138024.30987845096;140567.29716351352;140980.29509852384;16777.386218789932;16909.23908424124;20033.799662003486;20855.8957205213;21140.47148821292;33605.15987100342;33779;33853;33898.49152262718;33972.83013584916;10235.43705096212;10440.016396884568;10616.097631701237;10630.25588208818;10760.300580462244;8591.527465989431;8602.569871506399;8626.223639872316;8798.516081615444;8806.86789698155;8363.620002699608;8574.442661227;8855;9245.907540924505;9571.856422153673;3032.696730326994;3309.4870295104297;3422.845971931269;3645.5989841117457;3810.4089219330567;708.455566864241;876.8202518483696;879.2438502887512;901.4501154295874;960.059142040804;17456.82543174552;20064.49838754043;20304.289349872684;20471.4882127948;20644.690329644767;792.9286364227247;974.2595627323285;974.5906719177821;977.6675930183771;988.6342053440274;8997.685081022133;10680.412577308167;11063.557457701763;11080.335179889233;11084.168687348481;529908;537910;540166;540923;541118;125495;126293;126541;129446.35276823555;131704.3414782939;956.7273327101742;960.7742180587557;995.5569771451635;999.3803841618098;1002.5037606384741;15762;18275.167349060917;18367.265309387476;18467.630647387246;18815.811841881954;53902.460975389746;54049.18926216031;55009.34971950688;55552.72223638856;56100.63396196241;76885;79111.60444197743;83126;84835.57582212296;84941.57529212315;11465.885341146482;11939.164258501822;12157;12209.938950305546;12214.267143971034;10537.946205379707;11663.825042624538;12515.874841251834;12586.559470418491;12816.974642028801;11086.889131108908;11506.252093613888;11629.127815413709;11926.642200733992;11962.820557691468;10700.518476668723;11120.777584448428;11189.552417903518;11462.656120316404;11645.59240426581;10540.945905409704;11152.330860148417;11383.658490245305;11792;11812.70468238301;10578.682639520826;11691;11934.283942963102;11935.52257909674;12069.034477241976;11281.71795705114;11452.026577740986;11772.764544708887;11799.646010619696;11844.230125041844 15:38:23 INFO - PROCESS | 1643 | |3;mootools.html;1188.1385995153514;1499.100539676194;1661.0864024786372;1802.4052062819271;1861.906904654767;7438.516496427728;7698.961505192467;7953.323967462766;8355.665773369066;8417.705380311683;7874.685012599503;8132.471389359687;8166.959165204165;8264.95867520663;8326.333893288538;2282.417983414227;2331.9650205246935;2332.0438620165737;2335.9649605255936;2344.2615576093476;2330.2310237621546;2356.8821558921986;2360.24472168906;2365.4559451326204;2366.053578568578;1962.3524237001754;2298.0577963035157;2299.367673889677;2325.9534809303805;2331.3006098170567;2281.954360912789;2294.678744975705;2298.5747636687265;2314.0743702518957;2320.408295884539;2307.746147923727;2309.307207837659;2340.145846765918;2341.0518739910362;2343.7539058398793;8176.3458923286;8235.876461853077;8287.088420273765;8294.709685161051;8299.502029878227;5027.723475208862;5189.818356357509;5200.8959820803375;5213.556847667953;5221.660592061542;2898.3913378190605;3310.9172270693184;3362;3364.428047231952;3417.9916924507083;1037.3101887244973;1043.6712435582767;1073.8442925775796;1077.493578018338;1088.771358014818;10598.940105989339;10656.467176641214;10681.786364272748;10753.677389678322;10755.032047115797;4470.776461176928;4588.26587745959;4635.490096089427;4639.675222734444;4671.229247174246;9274.21169200612;9332.39339442934;9353.438793672402;9365.906340936639;9395.483248421402;5185.637005409585;5186.455422180692;5186.988537235278;5194.506521880416;5199.766010529535;4222.986483243999;4241.830326786956;4685.1800934836265;4743.881402964954;4749.501302363271;3782.678472329828;3818.16000479894;3848.422736589478;3869.9613003870163;3911.1786524830104;1147.8048731715628;1248.8126780982925;1285.4151361130664;1285.916415432994;1290.374168528263;773.961301934901;878.1789027259533;898.4654130792168;931.1759093202429;940.5109343141528;4980.352554167935;5551.389347171888;5807.448292412214;5816.726324121684;5920.79277225295;908.4322298563397;937.2174234514257;939.4123930557337;960;979.8339975429217;3711.870084547028;3770.547534295847;3839.1745774658584;3846.6922646188236;3849.2301539692176;9652.855207171899;9897.60409583623;9958.601655933682;9975.800483990279;9983.500824958868;9928.205743540499;9935.65225217114;9943.850842237372;9973.401595904268;9981.600735970625;7693.269139431745;7804.882926756217;7820.257075577811;7835.059792824897;7840.33357164636;5305.416404195458;5453.645513041798;5564.332280126248;5611.859703507445;5625.09998400254;8109.39179561547;9172.036936121878;9278.257739380699;9309.069093090908;9316;7841.5687137208;7850.489718168301;8525.275351594935;9085.90914090877;9251.86122208154;6046.395360463918;6187.133801267736;6340.4927605792345;6378.904316435164;6456.451201647725;7780.844383112193;7918.722844700408;7919.524828510308;7931.603419829101;7966.402519810921;6942.652867356713;7072.504924655224;7116.1460624723295;7139.571625702474;7194.2086370498155;6152.692365381624;7003.964980175067;7037.979492973388;7042.084529011196;7098.609576473137;5373.790897048133;6543.116679248241;6618.404343609098;6707.027481015128;6726.260111387841;4844.2007068833245;4874.317595536557;4882.609391248762;4951.97524012392;4978.551930326288;4806.7836947336755;4917.606591472745;4933.703977761203;4986.925196121989;4989.975050124726 15:38:23 INFO - PROCESS | 1643 | |4;prototype.html;295.3664389883699;354.0032442080041;358.46230654018973;366.75977234911136;368.35905524387556;5138.563222126124;5274.841754747355;5330.173823057427;5397.892042159155;5398;4217.936730949031;4661.533846615337;5134.64057515974;5269.841904742854;5314.867128321794;3708.944365834508;4150.232207041692;4172.7287726297845;4176.603222693854;4182.4981002279765;3357.5467311912944;3745.325841348556;4194.748315101104;4205.948512871782;4215.873523794291;3483.3381657484997;3906.6484016438517;3964.3260645690157;3966.1869316790135;3975.284448799215;3090.7218350348576;3253.6420993690676;3323.8670453181844;3475.2276339067257;3947.881563553098;3464.133966508373;3474.4788281757687;3475.3570589441033;3475.565554305712;3482.7387945903906;3960.6435420811977;4365.432493775789;4754.881127971828;4969.602431805447;5117.84646460603;3995.860144894915;4427.446569178853;4498.347739577775;4499.257622492285;4517.864464066083;4822.96306294145;5102.923456148161;5506.779728810843;5522.889542209174;5537.345352067616;3360.6807353301397;3534.2578058607724;3768.171002379472;3775.3388509055926;3795.35478968576;4637.930431043537;4911.557959783601;5859.6191247569195;5861.882762344771;5875.559333050039;3159.8578063987175;3597.964020359815;3838.443425703313;3862.6909847212155;3868;3901.51231096113;3915.35396659548;3920;3934.5278566571624;3937.6062393760394;4196.28663127263;4457.888552786207;4493.685442019093;4532.841350552715;4573;4424.336349547593;4683.695559788603;5248.868778280497;5250.976059668328;5609.59760059985;1083.7453198498347;1161.1581603337513;1243.421808858885;1248.5754843353122;1249.5813902342636;756.9848603028018;845.3026253340895;856.2293935458138;863.9956800215958;886.2599729226168;4513.345564893138;4759.643026773006;4788.946431785002;4920.4095508538485;4972.751362431936;5039.697618142924;5174.456682048406;5268.4994925482015;5504.697241651747;5555.777768889199;4407.581279778416;4521.412216411845;5114.258432527263;5125.820596279187;5135.794568217229;2426.7937225336045;2574.6524219230537;2847.345110624568;2877.812942158753;2880.6399200099986;4896.559309662148;4958.776855041532;5224.231157486538;5670.306604362894;5800.53595712342;4534.526278959285;5045.445001049881;5213.322268105122;5559.943610714033;5597.776088956478;4907.871189626335;5146.2280657900865;5153.974230128975;5522.392536820947;5747.425257474303;4710.657462623136;4744.5492678195515;4841.298011788201;5262.500312351755;5442.285680010784;4283.678724095767;4648.721076735406;4681.789319480699;4858.514148585114;5360.303160589098;4705.623660051652;5260;5767.336756273083;5815.41845815415;5854.970725146347;3281.408516869364;3409.2101646635715;3571.8571257149424;3621.6559426854406;3662.7985460799914;5070.41690205631;5250.684958902478;5315.475149304049;5471.753771080391;5498.948083813362;4901.701049222031;5240.737963101906;5428.321459817523;5445.7549410277425;5590.748416321357;4644.9767751161025;4789.353437286061;5007.57435617962;5646.209530665628;5681.829545113653;4854.611631069435;5185.585153187812;5511.559075274049;5520.834374968757;5549.944500554942;5136.226437723587;5533.09108357616;5578.553715702653;5584.692841893572;5619.353774316006;5488.588355873246;5494.670319780826;5511.614187007031;5517.448255174611;5522.282103326542 15:38:23 INFO - PROCESS | 1643 | |5;yui.html;650.7201903181633;670.3129292475215;732.9413646908246;772.578944475261;807.2774866494487;14421.332053570097;15452.536423907273;15464.458743943978;15480.380784768597;15510.379584816608;12869.227846329226;13222.677732226797;13838.238896860668;14011.64970875726;14480.203588802611;856.5888373580669;873.9300855931542;883.3507372081515;883.9071897450772;884.3765145572356;864.369010622243;868.917452841979;869.3393021303823;873.1617647058831;875.2210532625936;910.5037754423822;913.2967614936495;914.3279689428265;917.3578495053456;920.3097676742443;901.3555307954811;904.7602385367883;905.1355955062899;905.3300557587371;906.8730377747121;668.4051194436955;671.1375881991667;674.8549061951655;677.1265068062194;678.932106789322;11844.70388240301;12005.87994120065;12951.158174718614;12972.935135324356;13532.458701651922;10387.046601128604;11591.130665200144;12220.572279970247;12415.4413051412;12622.179558328771;12663.873361266453;12765.61703148907;12816.038797090256;13085.672858178526;13094.738105237937;3564.322778672044;3641.0715267606856;3834.501514803058;3849.402497963342;3867.0332416895776;109964.4501777494;111602;113175;113671.43164284209;113821.430892845;10251;10479.738006549896;10520.842187367196;10601.893981060244;10983.286086404358;18777;20966.95165241732;21389.25137620176;21403.67894481584;21438.464038399165;5877.617954832922;7122.143718422668;7445.255474452511;7519.398448124137;7663.808404789925;4131;4226.302660061117;4283.957160428418;4300.7634580097765;4314.719543229743;3330.701026599628;3365.192353835062;3435.7766745161484;3457.11843479911;3466.185446420089;564.1819361925143;642.6626021338834;645.0936434309708;650.0217173154373;660.46832299998;604.826636325527;639.354252205276;658.154271760785;676.1548064918851;690.8721886451023;7534.472586918972;8510.872336914952;9029;9110.86333704995;9221.723348299562;517.3300575754407;561.7247548701107;599.3167788720862;609.2840911928466;627.3444250757968;4254.553271906467;4372.147431250875;4514.232580461329;4569.725816451024;4581.587657110809;97615.51192243994;98219.50890245503;99200;102726.48636756769;103550;68270.65864670744;87747;90369.54815225882;90444;90833;78415.60792196002;78980.23569175166;81809.59095204606;81914;82037.76943345973;9391.765205869908;9708.5145742714;9803.656872009731;9820.263480239153;9828.655997040356;39488.80255598704;50866.728331792;51008.469745907685;51363.2295515575;51373.48626513687;46889;61026.69486652538;68881;70047;71899.64050179925;9805.607775688892;12964.675883102998;12968.740625187633;13298.867011329763;13684.726305474034;14587.562373128823;14716.337764800395;14734.926325368304;14865.628359291104;15048.247587620357;12962.416691249105;13392.531261405804;13648.976326775333;13703.246321452416;13961.162330260217;13324.333783310989;13375.598732038054;13472.663183420493;13501.662458438617;13990.370433330729;12935.805962910752;12988.285644289277;13351.198928064347;13377.799333009818;13409.32953352348;9063.263074318642;10515.67740561102;11189.384583847966;11273.436328183721;11355.772884542428;7633.69465221385;11205.60780372683;11308.151888608223;11433;11437.714057148638 15:38:23 INFO - PROCESS | 1643 | __end_tp_report 15:38:23 INFO - PROCESS | 1643 | __start_cc_report 15:38:23 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,375 15:38:23 INFO - PROCESS | 1643 | __end_cc_report 15:38:23 INFO - PROCESS | 1643 | __startTimestamp1482017903453__endTimestamp 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | ------- Summary: start ------- 15:38:23 INFO - PROCESS | 1643 | Number of tests: 6 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5469.66 Median:5623.41 stddev:680.95 (12.1%) stddev-sans-first:660.74 15:38:23 INFO - PROCESS | 1643 | Values: 3266.8 3294.7 3298.5 3319.0 3334.6 5403.2 5411.7 5432.8 5445.2 5470.0 5362.4 5413.4 5460.0 5514.6 5547.4 5017.4 5056.8 5073.2 5075.3 5104.4 4999.3 5021.1 5097.9 5113.2 5130.6 3867.6 4329.2 4417.3 5111.6 5133.9 4154.4 4928.9 4971.7 4976.3 5041.1 4918.7 5009.5 5017.8 5024.1 5055.1 4276.3 4441.0 5309.0 5393.5 5439.9 5926.6 5999.8 6013.2 6018.5 6033.1 5980.9 5985.0 6000.2 6051.1 6102.5 4676.5 5539.9 5547.9 5555.8 5558.2 6078.8 6127.5 6148.4 6164.2 6172.8 5739.9 5894.6 5903.3 5922.7 5997.9 6091.1 6110.8 6111.1 6117.1 6155.7 5805.1 5859.0 5982.3 6014.6 6041.3 5946.3 5998.8 6033.0 6042.0 6088.5 4721.4 4754.2 4765.8 4784.9 4806.6 4225.8 4235.4 4276.8 4325.7 4345.6 5590.7 5620.9 5621.3 5623.4 5640.1 3937.0 5409.8 5602.6 6060.5 6096.8 5715.2 5729.6 5739.3 5760.5 5788.9 4991.9 5205.2 5221.8 5232.3 5248.3 6148.4 6169.7 6191.7 6212.2 6225.5 6107.3 6123.0 6127.1 6128.7 6139.2 6204.6 6234.8 6240.1 6268.5 6273.4 5810.5 5876.3 5882.0 6003.1 6044.0 5931.1 6021.6 6062.5 6063.0 6080.3 5876.9 5879.5 5912.7 5935.4 5947.8 4324.8 4850.6 5076.7 5321.9 5882.9 4977.7 5375.2 5646.8 5676.1 5690.4 5528.9 5595.3 5611.5 5812.4 5832.5 5515.0 5581.8 5628.3 5634.0 5715.0 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:65782.83 Median:19675.70 stddev:141784.10 (720.6%) stddev-sans-first:142096.95 15:38:23 INFO - PROCESS | 1643 | Values: 435.6 493.8 501.0 525.6 530.2 28651.6 38463.6 38520.4 38589.8 38595.4 39117.8 39668.0 39707.8 39747.6 39787.8 8321.4 8512.0 9081.4 9360.4 11021.6 11071.4 11316.9 11327.7 11330.3 11346.8 11310.0 11361.5 11362.3 11396.1 11435.8 11286.7 11305.5 11313.2 11324.4 11332.6 11197.2 11239.3 11242.3 11243.4 11265.8 38840.8 38937.4 39073.6 39213.6 39255.6 3359.9 3406.3 3515.8 3636.4 3651.7 29213.4 29286.4 29334.3 29589.7 29785.4 3104.4 3154.8 3161.1 3164.3 3241.4 477846.0 484330.0 485952.0 486957.0 488137.0 55528.0 55676.7 55944.4 55962.4 55978.2 57163.9 57958.0 58024.0 58193.4 58292.7 15066.0 15333.2 15650.0 15660.8 15664.8 11057.1 11399.0 12040.9 12298.6 12374.6 14730.9 14759.0 14774.6 14803.8 14899.1 5502.8 5987.0 6077.3 6131.9 6266.7 798.5 868.3 923.4 928.9 983.2 22405.8 28028.4 28193.2 28772.1 28845.0 988.3 1005.1 1013.0 1052.2 1127.9 14086.6 14651.4 14695.2 14742.7 14851.0 593821.0 594248.0 596571.0 600591.0 603981.0 52477.7 55977.4 55997.0 56034.9 56054.0 1344.6 1367.7 1370.9 1377.7 1381.6 22753.0 26903.7 27559.4 28323.9 28333.9 38572.4 41809.8 47511.3 47528.5 47760.5 462163.0 464901.0 520669.0 529613.0 540416.0 32969.3 33951.7 35292.6 36890.6 37971.8 44097.8 44498.9 44580.8 44588.0 45514.1 30974.3 36767.6 37819.0 38076.0 38676.8 29388.4 33352.7 36564.0 37341.3 37829.4 35715.0 36466.3 36597.1 36649.0 36968.0 23196.9 32325.5 33493.7 33594.0 33853.3 19315.0 23135.1 23637.4 24173.0 24280.0 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:35178.72 Median:2581.45 stddev:90569.21 (3508.5%) stddev-sans-first:90785.51 15:38:23 INFO - PROCESS | 1643 | Values: 342.0 368.4 377.3 385.7 395.6 11164.2 11622.8 11748.8 11760.4 18972.1 20591.8 21005.7 21348.5 21590.0 21802.0 6393.0 6794.7 6796.7 6804.4 6841.7 6221.5 6385.6 6618.2 6674.8 6683.5 6002.6 6675.9 6678.2 6681.4 7126.0 7424.7 7666.8 7681.7 7681.9 7699.1 6981.1 7150.0 7212.5 7229.2 7238.3 26747.6 26805.0 26954.1 27397.2 27545.9 2404.2 2563.7 2599.2 2599.6 3236.5 14375.5 15681.8 15794.9 15859.1 16713.9 1818.6 1948.2 1963.4 2018.5 2026.2 92899.0 137180.3 138024.3 140567.3 140980.3 16777.4 16909.2 20033.8 20855.9 21140.5 33605.2 33779.0 33853.0 33898.5 33972.8 10235.4 10440.0 10616.1 10630.3 10760.3 8591.5 8602.6 8626.2 8798.5 8806.9 8363.6 8574.4 8855.0 9245.9 9571.9 3032.7 3309.5 3422.8 3645.6 3810.4 708.5 876.8 879.2 901.5 960.1 17456.8 20064.5 20304.3 20471.5 20644.7 792.9 974.3 974.6 977.7 988.6 8997.7 10680.4 11063.6 11080.3 11084.2 529908.0 537910.0 540166.0 540923.0 541118.0 125495.0 126293.0 126541.0 129446.4 131704.3 956.7 960.8 995.6 999.4 1002.5 15762.0 18275.2 18367.3 18467.6 18815.8 53902.5 54049.2 55009.3 55552.7 56100.6 76885.0 79111.6 83126.0 84835.6 84941.6 11465.9 11939.2 12157.0 12209.9 12214.3 10537.9 11663.8 12515.9 12586.6 12817.0 11086.9 11506.3 11629.1 11926.6 11962.8 10700.5 11120.8 11189.6 11462.7 11645.6 10540.9 11152.3 11383.7 11792.0 11812.7 10578.7 11691.0 11934.3 11935.5 12069.0 11281.7 11452.0 11772.8 11799.6 11844.2 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5356.25 Median:5263.54 stddev:2894.26 (55.0%) stddev-sans-first:2885.42 15:38:23 INFO - PROCESS | 1643 | Values: 1188.1 1499.1 1661.1 1802.4 1861.9 7438.5 7699.0 7953.3 8355.7 8417.7 7874.7 8132.5 8167.0 8265.0 8326.3 2282.4 2332.0 2332.0 2336.0 2344.3 2330.2 2356.9 2360.2 2365.5 2366.1 1962.4 2298.1 2299.4 2326.0 2331.3 2282.0 2294.7 2298.6 2314.1 2320.4 2307.7 2309.3 2340.1 2341.1 2343.8 8176.3 8235.9 8287.1 8294.7 8299.5 5027.7 5189.8 5200.9 5213.6 5221.7 2898.4 3310.9 3362.0 3364.4 3418.0 1037.3 1043.7 1073.8 1077.5 1088.8 10598.9 10656.5 10681.8 10753.7 10755.0 4470.8 4588.3 4635.5 4639.7 4671.2 9274.2 9332.4 9353.4 9365.9 9395.5 5185.6 5186.5 5187.0 5194.5 5199.8 4223.0 4241.8 4685.2 4743.9 4749.5 3782.7 3818.2 3848.4 3870.0 3911.2 1147.8 1248.8 1285.4 1285.9 1290.4 774.0 878.2 898.5 931.2 940.5 4980.4 5551.4 5807.4 5816.7 5920.8 908.4 937.2 939.4 960.0 979.8 3711.9 3770.5 3839.2 3846.7 3849.2 9652.9 9897.6 9958.6 9975.8 9983.5 9928.2 9935.7 9943.9 9973.4 9981.6 7693.3 7804.9 7820.3 7835.1 7840.3 5305.4 5453.6 5564.3 5611.9 5625.1 8109.4 9172.0 9278.3 9309.1 9316.0 7841.6 7850.5 8525.3 9085.9 9251.9 6046.4 6187.1 6340.5 6378.9 6456.5 7780.8 7918.7 7919.5 7931.6 7966.4 6942.7 7072.5 7116.1 7139.6 7194.2 6152.7 7004.0 7038.0 7042.1 7098.6 5373.8 6543.1 6618.4 6707.0 6726.3 4844.2 4874.3 4882.6 4952.0 4978.6 4806.8 4917.6 4933.7 4986.9 4990.0 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4339.45 Median:4789.15 stddev:1330.70 (27.8%) stddev-sans-first:1299.35 15:38:23 INFO - PROCESS | 1643 | Values: 295.4 354.0 358.5 366.8 368.4 5138.6 5274.8 5330.2 5397.9 5398.0 4217.9 4661.5 5134.6 5269.8 5314.9 3708.9 4150.2 4172.7 4176.6 4182.5 3357.5 3745.3 4194.7 4205.9 4215.9 3483.3 3906.6 3964.3 3966.2 3975.3 3090.7 3253.6 3323.9 3475.2 3947.9 3464.1 3474.5 3475.4 3475.6 3482.7 3960.6 4365.4 4754.9 4969.6 5117.8 3995.9 4427.4 4498.3 4499.3 4517.9 4823.0 5102.9 5506.8 5522.9 5537.3 3360.7 3534.3 3768.2 3775.3 3795.4 4637.9 4911.6 5859.6 5861.9 5875.6 3159.9 3598.0 3838.4 3862.7 3868.0 3901.5 3915.4 3920.0 3934.5 3937.6 4196.3 4457.9 4493.7 4532.8 4573.0 4424.3 4683.7 5248.9 5251.0 5609.6 1083.7 1161.2 1243.4 1248.6 1249.6 757.0 845.3 856.2 864.0 886.3 4513.3 4759.6 4788.9 4920.4 4972.8 5039.7 5174.5 5268.5 5504.7 5555.8 4407.6 4521.4 5114.3 5125.8 5135.8 2426.8 2574.7 2847.3 2877.8 2880.6 4896.6 4958.8 5224.2 5670.3 5800.5 4534.5 5045.4 5213.3 5559.9 5597.8 4907.9 5146.2 5154.0 5522.4 5747.4 4710.7 4744.5 4841.3 5262.5 5442.3 4283.7 4648.7 4681.8 4858.5 5360.3 4705.6 5260.0 5767.3 5815.4 5855.0 3281.4 3409.2 3571.9 3621.7 3662.8 5070.4 5250.7 5315.5 5471.8 5498.9 4901.7 5240.7 5428.3 5445.8 5590.7 4645.0 4789.4 5007.6 5646.2 5681.8 4854.6 5185.6 5511.6 5520.8 5549.9 5136.2 5533.1 5578.6 5584.7 5619.4 5488.6 5494.7 5511.6 5517.4 5522.3 15:38:23 INFO - PROCESS | 1643 | 15:38:23 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20174.23 Median:4343.43 stddev:29604.01 (681.6%) stddev-sans-first:29650.76 15:38:23 INFO - PROCESS | 1643 | Values: 650.7 670.3 732.9 772.6 807.3 14421.3 15452.5 15464.5 15480.4 15510.4 12869.2 13222.7 13838.2 14011.6 14480.2 856.6 873.9 883.4 883.9 884.4 864.4 868.9 869.3 873.2 875.2 910.5 913.3 914.3 917.4 920.3 901.4 904.8 905.1 905.3 906.9 668.4 671.1 674.9 677.1 678.9 11844.7 12005.9 12951.2 12972.9 13532.5 10387.0 11591.1 12220.6 12415.4 12622.2 12663.9 12765.6 12816.0 13085.7 13094.7 3564.3 3641.1 3834.5 3849.4 3867.0 109964.5 111602.0 113175.0 113671.4 113821.4 10251.0 10479.7 10520.8 10601.9 10983.3 18777.0 20967.0 21389.3 21403.7 21438.5 5877.6 7122.1 7445.3 7519.4 7663.8 4131.0 4226.3 4284.0 4300.8 4314.7 3330.7 3365.2 3435.8 3457.1 3466.2 564.2 642.7 645.1 650.0 660.5 604.8 639.4 658.2 676.2 690.9 7534.5 8510.9 9029.0 9110.9 9221.7 517.3 561.7 599.3 609.3 627.3 4254.6 4372.1 4514.2 4569.7 4581.6 97615.5 98219.5 99200.0 102726.5 103550.0 68270.7 87747.0 90369.5 90444.0 90833.0 78415.6 78980.2 81809.6 81914.0 82037.8 9391.8 9708.5 9803.7 9820.3 9828.7 39488.8 50866.7 51008.5 51363.2 51373.5 46889.0 61026.7 68881.0 70047.0 71899.6 9805.6 12964.7 12968.7 13298.9 13684.7 14587.6 14716.3 14734.9 14865.6 15048.2 12962.4 13392.5 13649.0 13703.2 13961.2 13324.3 13375.6 13472.7 13501.7 13990.4 12935.8 12988.3 13351.2 13377.8 13409.3 9063.3 10515.7 11189.4 11273.4 11355.8 7633.7 11205.6 11308.2 11433.0 11437.7 15:38:23 INFO - PROCESS | 1643 | -------- Summary: end -------- 15:38:23 INFO - PROCESS | 1643 | 15:38:24 INFO - TEST-INFO | 1643: exit 0 15:38:24 INFO - TEST-OK | dromaeo_css | took 1116785ms 15:38:24 INFO - TEST-START | kraken 15:38:24 INFO - operating with platform_type : mac_ 15:38:24 INFO - Initialising browser for kraken test... 15:38:24 INFO - TEST-INFO | started process 1652 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpLbIX5t/profile http://localhost:49249/getInfo.html) 15:38:26 INFO - PROCESS | 1652 | 2016-12-17 15:38:26.661 plugin-container[1653:11171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x893f, name = 'com.apple.tsm.portname' 15:38:26 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:38:26 INFO - PROCESS | 1652 | 2016-12-17 15:38:26.663 plugin-container[1653:11171] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 15:38:26 INFO - PROCESS | 1652 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:38:26 INFO - PROCESS | 1652 | 2016-12-17 15:38:26.663 plugin-container[1653:11171] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:38:26 INFO - PROCESS | 1652 | __metrics Screen width/height:1600/1200 15:38:26 INFO - PROCESS | 1652 | colorDepth:24 15:38:26 INFO - PROCESS | 1652 | Browser inner width/height: 1024/689 15:38:26 INFO - PROCESS | 1652 | __metrics 15:38:26 ERROR - PROCESS | 1652 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3232: TypeError: this.tabs is undefined 15:38:27 INFO - TEST-INFO | 1652: exit 0 15:38:27 INFO - Browser initialized. 15:38:27 INFO - Running cycle 1/1 for kraken test... 15:38:27 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/T/tmpLbIX5t/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:38:30 INFO - PROCESS | 1654 | 2016-12-17 15:38:30.185 plugin-container[1655:11292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 15:38:30 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:38:30 INFO - PROCESS | 1654 | 2016-12-17 15:38:30.186 plugin-container[1655:11292] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 15:38:30 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:38:30 INFO - PROCESS | 1654 | 2016-12-17 15:38:30.186 plugin-container[1655:11292] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:38:39 INFO - PROCESS | 1654 | RSS: Main: 237154304 15:38:39 INFO - PROCESS | 1654 | 15:38:42 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection) 15:38:43 INFO - PROCESS | 1654 | RSS: Main: 249810944 15:38:43 INFO - PROCESS | 1654 | 15:38:46 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-dft) 15:38:47 INFO - PROCESS | 1654 | RSS: Main: 257286144 15:38:47 INFO - PROCESS | 1654 | 15:38:50 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-fft) 15:38:51 INFO - PROCESS | 1654 | RSS: Main: 260280320 15:38:51 INFO - PROCESS | 1654 | 15:38:53 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator) 15:38:53 INFO - PROCESS | 1654 | RSS: Main: 262303744 15:38:53 INFO - PROCESS | 1654 | 15:38:55 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:38:56 INFO - PROCESS | 1654 | RSS: Main: 262938624 15:38:56 INFO - PROCESS | 1654 | 15:38:59 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom) 15:39:00 INFO - PROCESS | 1654 | RSS: Main: 268312576 15:39:00 INFO - PROCESS | 1654 | 15:39:03 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate) 15:39:03 INFO - PROCESS | 1654 | RSS: Main: 267624448 15:39:03 INFO - PROCESS | 1654 | 15:39:06 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial) 15:39:07 INFO - PROCESS | 1654 | RSS: Main: 267608064 15:39:07 INFO - PROCESS | 1654 | 15:39:09 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:39:09 INFO - PROCESS | 1654 | RSS: Main: 266903552 15:39:09 INFO - PROCESS | 1654 | 15:39:11 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:39:12 INFO - PROCESS | 1654 | RSS: Main: 266956800 15:39:12 INFO - PROCESS | 1654 | 15:39:14 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:39:15 INFO - PROCESS | 1654 | RSS: Main: 266981376 15:39:15 INFO - PROCESS | 1654 | 15:39:18 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:39:19 INFO - PROCESS | 1654 | RSS: Main: 267202560 15:39:19 INFO - PROCESS | 1654 | 15:39:22 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:39:22 INFO - PROCESS | 1654 | RSS: Main: 267042816 15:39:22 INFO - PROCESS | 1654 | 15:39:24 INFO - PROCESS | 1654 | Cycle 1(1): loaded http://localhost:49249/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49249/tests/kraken/driver.html?testName=ai-astar) 15:39:25 INFO - PROCESS | 1654 | RSS: Main: 269385728 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | __start_tp_report 15:39:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load 15:39:25 INFO - PROCESS | 1654 | _x_x_mozilla_page_load_details 15:39:25 INFO - PROCESS | 1654 | |i|pagename|runs| 15:39:25 INFO - PROCESS | 1654 | |0;ai-astar;116;122;173;119;155;172;105;169;137;174 15:39:25 INFO - PROCESS | 1654 | |1;audio-beat-detection;166;142;115;150;102;132;147;149;146;113 15:39:25 INFO - PROCESS | 1654 | |2;audio-dft;250;230;245;220;274;217;196;197;193;205 15:39:25 INFO - PROCESS | 1654 | |3;audio-fft;83;58;59;86;59;61;61;59;71;61 15:39:25 INFO - PROCESS | 1654 | |4;audio-oscillator;89;71;86;70;70;71;84;72;72;88 15:39:25 INFO - PROCESS | 1654 | |5;imaging-gaussian-blur;112;114;111;169;112;114;113;115;111;114 15:39:25 INFO - PROCESS | 1654 | |6;imaging-darkroom;146;139;97;100;96;97;97;131;140;96 15:39:25 INFO - PROCESS | 1654 | |7;imaging-desaturate;94;98;94;92;97;106;143;119;91;97 15:39:25 INFO - PROCESS | 1654 | |8;json-parse-financial;58;58;58;56;59;59;59;58;57;59 15:39:25 INFO - PROCESS | 1654 | |9;json-stringify-tinderbox;69;78;74;82;81;77;71;71;102;108 15:39:25 INFO - PROCESS | 1654 | |10;stanford-crypto-aes;92;89;87;88;91;86;91;87;86;99 15:39:25 INFO - PROCESS | 1654 | |11;stanford-crypto-ccm;169;167;156;151;164;142;145;162;187;182 15:39:25 INFO - PROCESS | 1654 | |12;stanford-crypto-pbkdf2;185;180;176;173;193;239;233;177;177;241 15:39:25 INFO - PROCESS | 1654 | |13;stanford-crypto-sha256-iterative;67;72;87;88;87;88;89;70;65;72 15:39:25 INFO - PROCESS | 1654 | __end_tp_report 15:39:25 INFO - PROCESS | 1654 | __start_cc_report 15:39:25 INFO - PROCESS | 1654 | _x_x_mozilla_cycle_collect,946 15:39:25 INFO - PROCESS | 1654 | __end_cc_report 15:39:25 INFO - PROCESS | 1654 | __startTimestamp1482017965009__endTimestamp 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | ------- Summary: start ------- 15:39:25 INFO - PROCESS | 1654 | Number of tests: 14 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#0] ai-astar Cycles:10 Average:144.20 Median:162.00 stddev:27.35 (16.9%) stddev-sans-first:27.04 15:39:25 INFO - PROCESS | 1654 | Values: 116.0 122.0 173.0 119.0 155.0 172.0 105.0 169.0 137.0 174.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#1] audio-beat-detection Cycles:10 Average:136.20 Median:146.50 stddev:20.18 (13.8%) stddev-sans-first:18.29 15:39:25 INFO - PROCESS | 1654 | Values: 166.0 142.0 115.0 150.0 102.0 132.0 147.0 149.0 146.0 113.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#2] audio-dft Cycles:10 Average:222.70 Median:225.00 stddev:26.95 (12.0%) stddev-sans-first:26.71 15:39:25 INFO - PROCESS | 1654 | Values: 250.0 230.0 245.0 220.0 274.0 217.0 196.0 197.0 193.0 205.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#3] audio-fft Cycles:10 Average:65.80 Median:61.00 stddev:10.54 (17.3%) stddev-sans-first:9.16 15:39:25 INFO - PROCESS | 1654 | Values: 83.0 58.0 59.0 86.0 59.0 61.0 61.0 59.0 71.0 61.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#4] audio-oscillator Cycles:10 Average:77.30 Median:78.00 stddev:8.26 (10.6%) stddev-sans-first:7.60 15:39:25 INFO - PROCESS | 1654 | Values: 89.0 71.0 86.0 70.0 70.0 71.0 84.0 72.0 72.0 88.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#5] imaging-gaussian-blur Cycles:10 Average:118.50 Median:114.00 stddev:17.80 (15.6%) stddev-sans-first:18.72 15:39:25 INFO - PROCESS | 1654 | Values: 112.0 114.0 111.0 169.0 112.0 114.0 113.0 115.0 111.0 114.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#6] imaging-darkroom Cycles:10 Average:113.90 Median:96.00 stddev:21.92 (22.8%) stddev-sans-first:19.94 15:39:25 INFO - PROCESS | 1654 | Values: 146.0 139.0 97.0 100.0 96.0 97.0 97.0 131.0 140.0 96.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#7] imaging-desaturate Cycles:10 Average:103.10 Median:94.00 stddev:16.29 (17.3%) stddev-sans-first:16.94 15:39:25 INFO - PROCESS | 1654 | Values: 94.0 98.0 94.0 92.0 97.0 106.0 143.0 119.0 91.0 97.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#8] json-parse-financial Cycles:10 Average:58.10 Median:58.50 stddev:0.99 (1.7%) stddev-sans-first:1.05 15:39:25 INFO - PROCESS | 1654 | Values: 58.0 58.0 58.0 56.0 59.0 59.0 59.0 58.0 57.0 59.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#9] json-stringify-tinderbox Cycles:10 Average:81.30 Median:75.50 stddev:13.28 (17.6%) stddev-sans-first:13.32 15:39:25 INFO - PROCESS | 1654 | Values: 69.0 78.0 74.0 82.0 81.0 77.0 71.0 71.0 102.0 108.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#10] stanford-crypto-aes Cycles:10 Average:89.60 Median:90.00 stddev:3.95 (4.4%) stddev-sans-first:4.09 15:39:25 INFO - PROCESS | 1654 | Values: 92.0 89.0 87.0 88.0 91.0 86.0 91.0 87.0 86.0 99.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#11] stanford-crypto-ccm Cycles:10 Average:162.50 Median:165.50 stddev:14.71 (8.9%) stddev-sans-first:15.41 15:39:25 INFO - PROCESS | 1654 | Values: 169.0 167.0 156.0 151.0 164.0 142.0 145.0 162.0 187.0 182.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:197.40 Median:189.00 stddev:28.40 (15.0%) stddev-sans-first:29.77 15:39:25 INFO - PROCESS | 1654 | Values: 185.0 180.0 176.0 173.0 193.0 239.0 233.0 177.0 177.0 241.0 15:39:25 INFO - PROCESS | 1654 | 15:39:25 INFO - PROCESS | 1654 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:78.50 Median:87.00 stddev:10.04 (11.5%) stddev-sans-first:9.74 15:39:25 INFO - PROCESS | 1654 | Values: 67.0 72.0 87.0 88.0 87.0 88.0 89.0 70.0 65.0 72.0 15:39:25 INFO - PROCESS | 1654 | -------- Summary: end -------- 15:39:25 INFO - PROCESS | 1654 | 15:39:26 INFO - TEST-INFO | 1654: exit 0 15:39:26 INFO - TEST-OK | kraken | took 61960ms 15:39:26 INFO - SUITE-END | took 1178s 15:39:26 INFO - Completed test suite (00:19:38) 15:39:26 INFO - javascript benchmark 15:39:26 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7234.176959364949, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3266.7876588021777, 3294.68700473455, 3298.505224216369, 3319.0, 3334.566506354174, 5403.21653360263, 5411.675299482029, 5432.782688692452, 5445.210444485557, 5470.0, 5362.383325917511, 5413.404525502201, 5460.044492213867, 5514.558835293177, 5547.389787123413, 5017.42299635542, 5056.786371270905, 5073.188289873621, 5075.289459475676, 5104.387473503185, 4999.250112483121, 5021.146405111122, 5097.872553186163, 5113.2074528447965, 5130.589552835783, 3867.5939026402243, 4329.242382583051, 4417.293233082707, 5111.5529489344135, 5133.897322053556, 4154.376843473473, 4928.876778080541, 4971.676841005359, 4976.253561965698, 5041.092603331399, 4918.729469879156, 5009.524095210949, 5017.824376146854, 5024.070546948789, 5055.090083784918, 4276.31578947367, 4440.955590444087, 5309.0, 5393.541548968343, 5439.918401223985, 5926.555508336892, 5999.790007349766, 6013.218281623404, 6018.518518518508, 6033.09503574463, 5980.910286345708, 5985.042393217064, 6000.189974353494, 6051.1225872248715, 6102.511799056065, 4676.485586585472, 5539.861503462405, 5547.889042219133, 5555.833325000256, 5558.221848941152, 6078.787242446581, 6127.525510585588, 6148.44663980244, 6164.229471316085, 6172.753089876444, 5739.8565035874435, 5894.616849904743, 5903.26209223847, 5922.733476993546, 5997.940020599738, 6091.08633704939, 6110.816675499742, 6111.052786818051, 6117.082437634302, 6155.692215389213, 5805.129230615442, 5859.033259512218, 5982.252218472691, 6014.6391216527145, 6041.335453100155, 5946.256717910262, 5998.790042348497, 6032.969835150796, 6041.969790151021, 6088.452039316483, 4721.409823772028, 4754.168020596382, 4765.809367625326, 4784.880377990508, 4806.615470762331, 4225.809838557272, 4235.385869049032, 4276.786160691953, 4325.697201195886, 4345.630621397217, 5590.748416321276, 5620.9437905621235, 5621.325440947113, 5623.409541998113, 5640.125780503947, 3937.0, 5409.810656627078, 5602.551795856321, 6060.484858787053, 6096.786612468542, 5715.171300161536, 5729.598928074995, 5739.339975902824, 5760.53915686744, 5788.942110578924, 4991.850244492671, 5205.24523944026, 5221.843344699665, 5232.293640358579, 5248.343957005374, 6148.432149801772, 6169.722362493609, 6191.721372538156, 6212.192414986113, 6225.533085018732, 6107.3405344170405, 6122.97760420812, 6127.097902969212, 6128.655903792168, 6139.246828488627, 6204.5967012144065, 6234.781782637768, 6240.126382306572, 6268.523933046512, 6273.404026617646, 5810.506106980785, 5876.265466816648, 5882.0, 6003.129546215687, 6044.0, 5931.051031834886, 6021.578489505692, 6062.454379105901, 6062.999605065115, 6080.300765412034, 5876.911846322394, 5879.529637629065, 5912.704364781658, 5935.376785437639, 5947.762089516371, 4324.762138082436, 4850.6119510438375, 5076.746162691777, 5321.866953326198, 5882.941070607332, 4977.676451030671, 5375.19372094189, 5646.830595082154, 5676.063449530946, 5690.402507736628, 5528.944710552843, 5595.300587426572, 5611.466910643482, 5812.447817457335, 5832.533397328289, 5515.0, 5581.804636837691, 5628.296462942132, 5634.042212823748, 5714.971425142848], "value": 5427.156571234457, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [435.57748983486016, 493.83209708699036, 501.0380070265091, 525.6451894970894, 530.2417542913637, 28651.570226446594, 38463.615363846424, 38520.42219366708, 38589.80705096477, 38595.421068683994, 39117.80441097791, 39668.0, 39707.80146099273, 39747.602523974754, 39787.80106099473, 8321.375896807733, 8512.0, 9081.409708368936, 9360.438373697565, 11021.559137634486, 11071.391073490955, 11316.943415282913, 11327.716807079847, 11330.263532870336, 11346.829797553044, 11309.982101610853, 11361.488733006992, 11362.31826090433, 11396.14528910331, 11435.828462573027, 11286.717832054183, 11305.547778088867, 11313.208075434723, 11324.433778311093, 11332.6033588824, 11197.16021298406, 11239.269447485969, 11242.325460472315, 11243.437828108561, 11265.774684506347, 38840.8347749568, 38937.41593876094, 39073.60926390728, 39213.60786392128, 39255.607443925765, 3359.947435489023, 3406.3017081498233, 3515.753897227195, 3636.399994001013, 3651.7261205409436, 29213.415731685458, 29286.41427171466, 29334.266643333875, 29589.70410295891, 29785.40429191404, 3104.425681248976, 3154.779165458419, 3161.1464904475893, 3164.2563997460807, 3241.432749268892, 477846.0, 484330.0, 485952.0, 486957.0, 488137.0, 55528.0, 55676.72161639247, 55944.44055559474, 55962.44037559654, 55978.16032759512, 57163.85672286531, 57958.0, 58024.0, 58193.41806581965, 58292.708536457045, 15066.020708654121, 15333.233338333039, 15650.0, 15660.765088523682, 15664.843351566566, 11057.06014988735, 11399.0, 12040.939795300967, 12298.569550065704, 12374.566890158982, 14730.852691473163, 14759.0, 14774.556763297118, 14803.777943330859, 14899.106053636817, 5502.752376143083, 5986.970065149646, 6077.3314935357075, 6131.938680613137, 6266.717997690115, 798.5328582779023, 868.3357231717741, 923.4459324405303, 928.9071092890792, 983.2085171436919, 22405.775942240696, 28028.43943121126, 28193.15420537387, 28772.136835894955, 28845.0, 988.2884323287221, 1005.0954141272766, 1012.9797404051877, 1052.2476429353048, 1127.8590176227972, 14086.577402677936, 14651.435998700184, 14695.185356558228, 14742.705145897236, 14851.0, 593821.0, 594248.0, 596571.0, 600590.997045012, 603981.0, 52477.73761131246, 55977.44022559804, 55997.0, 56034.87930241373, 56054.0, 1344.6150465021044, 1367.7196174784146, 1370.9314534273246, 1377.7106807570522, 1381.5924302330836, 22753.0, 26903.730962690905, 27559.448811024067, 28323.858380707963, 28333.858330709038, 38572.421413678254, 41809.79095104505, 47511.287330689374, 47528.524714753796, 47760.52239477561, 462163.0, 464901.0, 520669.0, 529613.0, 540416.0, 32969.340613188084, 33951.66048339584, 35292.64707352893, 36890.63109368872, 37971.81014094912, 44097.77951110224, 44498.88752781206, 44580.777096115606, 44588.0, 45514.089718206116, 30974.28308602982, 36767.63232367642, 37819.0, 38076.0, 38676.80661596674, 29388.412231755672, 33352.66647333593, 36564.0, 37341.25317493689, 37829.43255851109, 35715.0, 36466.27067458583, 36597.08507287339, 36649.0, 36968.0, 23196.88401558049, 32325.51511727279, 33493.66506334906, 33594.0, 33853.3229335407, 19315.0, 23135.07459701593, 23637.409064773517, 24173.0, 24280.0], "value": 19296.83197543559, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [341.99658003419967, 368.3682484539017, 377.30199131606525, 385.71457121729895, 395.5984675554311, 11164.162687798416, 11622.767544649101, 11748.823767643493, 11760.411979401017, 18972.051397430125, 20591.794082059176, 21005.68491472629, 21348.4662883428, 21590.028448719808, 21802.0, 6392.968035159829, 6794.728210871559, 6796.728130874759, 6804.421624161952, 6841.726330946756, 6221.502279817626, 6385.584936979105, 6618.238902526199, 6674.799756007327, 6683.532152749285, 6002.609830361034, 6675.866482670343, 6678.1986161660425, 6681.3986741193285, 7125.9643701781415, 7424.665890034935, 7666.769996900102, 7681.654325555363, 7681.923180768176, 7699.076110866704, 6981.0924579804805, 7150.0, 7212.53118547298, 7229.2047874733735, 7238.335182907961, 26747.57451402802, 26805.0, 26954.056608018822, 27397.17808465749, 27545.862270688718, 2404.158544509425, 2563.717991020986, 2599.1812579037733, 2599.636050952868, 3236.530703048045, 14375.496857610038, 15681.843181568267, 15794.921025394915, 15859.127747973856, 16713.916430417892, 1818.5635447492507, 1948.2207117153253, 1963.3619073801071, 2018.5256464730776, 2026.230032587622, 92899.0, 137180.31409842987, 138024.30987845096, 140567.29716351352, 140980.29509852384, 16777.386218789932, 16909.23908424124, 20033.799662003486, 20855.8957205213, 21140.47148821292, 33605.15987100342, 33779.0, 33853.0, 33898.49152262718, 33972.83013584916, 10235.43705096212, 10440.016396884568, 10616.097631701237, 10630.25588208818, 10760.300580462244, 8591.527465989431, 8602.569871506399, 8626.223639872316, 8798.516081615444, 8806.86789698155, 8363.620002699608, 8574.442661227, 8855.0, 9245.907540924505, 9571.856422153673, 3032.696730326994, 3309.4870295104297, 3422.845971931269, 3645.5989841117457, 3810.4089219330567, 708.455566864241, 876.8202518483696, 879.2438502887512, 901.4501154295874, 960.059142040804, 17456.82543174552, 20064.49838754043, 20304.289349872684, 20471.4882127948, 20644.690329644767, 792.9286364227247, 974.2595627323285, 974.5906719177821, 977.6675930183771, 988.6342053440274, 8997.685081022133, 10680.412577308167, 11063.557457701763, 11080.335179889233, 11084.168687348481, 529908.0, 537910.0, 540166.0, 540923.0, 541118.0, 125495.0, 126293.0, 126541.0, 129446.35276823555, 131704.3414782939, 956.7273327101742, 960.7742180587557, 995.5569771451635, 999.3803841618098, 1002.5037606384741, 15762.0, 18275.167349060917, 18367.265309387476, 18467.630647387246, 18815.811841881954, 53902.460975389746, 54049.18926216031, 55009.34971950688, 55552.72223638856, 56100.63396196241, 76885.0, 79111.60444197743, 83126.0, 84835.57582212296, 84941.57529212315, 11465.885341146482, 11939.164258501822, 12157.0, 12209.938950305546, 12214.267143971034, 10537.946205379707, 11663.825042624538, 12515.874841251834, 12586.559470418491, 12816.974642028801, 11086.889131108908, 11506.252093613888, 11629.127815413709, 11926.642200733992, 11962.820557691468, 10700.518476668723, 11120.777584448428, 11189.552417903518, 11462.656120316404, 11645.59240426581, 10540.945905409704, 11152.330860148417, 11383.658490245305, 11792.0, 11812.70468238301, 10578.682639520826, 11691.0, 11934.283942963102, 11935.52257909674, 12069.034477241976, 11281.71795705114, 11452.026577740986, 11772.764544708887, 11799.646010619696, 11844.230125041844], "value": 10916.053568936859, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1188.1385995153514, 1499.100539676194, 1661.0864024786372, 1802.4052062819271, 1861.906904654767, 7438.516496427728, 7698.961505192467, 7953.323967462766, 8355.665773369066, 8417.705380311683, 7874.685012599503, 8132.471389359687, 8166.959165204165, 8264.95867520663, 8326.333893288538, 2282.417983414227, 2331.9650205246935, 2332.0438620165737, 2335.9649605255936, 2344.2615576093476, 2330.2310237621546, 2356.8821558921986, 2360.24472168906, 2365.4559451326204, 2366.053578568578, 1962.3524237001754, 2298.0577963035157, 2299.367673889677, 2325.9534809303805, 2331.3006098170567, 2281.954360912789, 2294.678744975705, 2298.5747636687265, 2314.0743702518957, 2320.408295884539, 2307.746147923727, 2309.307207837659, 2340.145846765918, 2341.0518739910362, 2343.7539058398793, 8176.3458923286, 8235.876461853077, 8287.088420273765, 8294.709685161051, 8299.502029878227, 5027.723475208862, 5189.818356357509, 5200.8959820803375, 5213.556847667953, 5221.660592061542, 2898.3913378190605, 3310.9172270693184, 3362.0, 3364.428047231952, 3417.9916924507083, 1037.3101887244973, 1043.6712435582767, 1073.8442925775796, 1077.493578018338, 1088.771358014818, 10598.940105989339, 10656.467176641214, 10681.786364272748, 10753.677389678322, 10755.032047115797, 4470.776461176928, 4588.26587745959, 4635.490096089427, 4639.675222734444, 4671.229247174246, 9274.21169200612, 9332.39339442934, 9353.438793672402, 9365.906340936639, 9395.483248421402, 5185.637005409585, 5186.455422180692, 5186.988537235278, 5194.506521880416, 5199.766010529535, 4222.986483243999, 4241.830326786956, 4685.1800934836265, 4743.881402964954, 4749.501302363271, 3782.678472329828, 3818.16000479894, 3848.422736589478, 3869.9613003870163, 3911.1786524830104, 1147.8048731715628, 1248.8126780982925, 1285.4151361130664, 1285.916415432994, 1290.374168528263, 773.961301934901, 878.1789027259533, 898.4654130792168, 931.1759093202429, 940.5109343141528, 4980.352554167935, 5551.389347171888, 5807.448292412214, 5816.726324121684, 5920.79277225295, 908.4322298563397, 937.2174234514257, 939.4123930557337, 960.0, 979.8339975429217, 3711.870084547028, 3770.547534295847, 3839.1745774658584, 3846.6922646188236, 3849.2301539692176, 9652.855207171899, 9897.60409583623, 9958.601655933682, 9975.800483990279, 9983.500824958868, 9928.205743540499, 9935.65225217114, 9943.850842237372, 9973.401595904268, 9981.600735970625, 7693.269139431745, 7804.882926756217, 7820.257075577811, 7835.059792824897, 7840.33357164636, 5305.416404195458, 5453.645513041798, 5564.332280126248, 5611.859703507445, 5625.09998400254, 8109.39179561547, 9172.036936121878, 9278.257739380699, 9309.069093090908, 9316.0, 7841.5687137208, 7850.489718168301, 8525.275351594935, 9085.90914090877, 9251.86122208154, 6046.395360463918, 6187.133801267736, 6340.4927605792345, 6378.904316435164, 6456.451201647725, 7780.844383112193, 7918.722844700408, 7919.524828510308, 7931.603419829101, 7966.402519810921, 6942.652867356713, 7072.504924655224, 7116.1460624723295, 7139.571625702474, 7194.2086370498155, 6152.692365381624, 7003.964980175067, 7037.979492973388, 7042.084529011196, 7098.609576473137, 5373.790897048133, 6543.116679248241, 6618.404343609098, 6707.027481015128, 6726.260111387841, 4844.2007068833245, 4874.317595536557, 4882.609391248762, 4951.97524012392, 4978.551930326288, 4806.7836947336755, 4917.606591472745, 4933.703977761203, 4986.925196121989, 4989.975050124726], "value": 4371.501230929726, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.3664389883699, 354.0032442080041, 358.46230654018973, 366.75977234911136, 368.35905524387556, 5138.563222126124, 5274.841754747355, 5330.173823057427, 5397.892042159155, 5398.0, 4217.936730949031, 4661.533846615337, 5134.64057515974, 5269.841904742854, 5314.867128321794, 3708.944365834508, 4150.232207041692, 4172.7287726297845, 4176.603222693854, 4182.4981002279765, 3357.5467311912944, 3745.325841348556, 4194.748315101104, 4205.948512871782, 4215.873523794291, 3483.3381657484997, 3906.6484016438517, 3964.3260645690157, 3966.1869316790135, 3975.284448799215, 3090.7218350348576, 3253.6420993690676, 3323.8670453181844, 3475.2276339067257, 3947.881563553098, 3464.133966508373, 3474.4788281757687, 3475.3570589441033, 3475.565554305712, 3482.7387945903906, 3960.6435420811977, 4365.432493775789, 4754.881127971828, 4969.602431805447, 5117.84646460603, 3995.860144894915, 4427.446569178853, 4498.347739577775, 4499.257622492285, 4517.864464066083, 4822.96306294145, 5102.923456148161, 5506.779728810843, 5522.889542209174, 5537.345352067616, 3360.6807353301397, 3534.2578058607724, 3768.171002379472, 3775.3388509055926, 3795.35478968576, 4637.930431043537, 4911.557959783601, 5859.6191247569195, 5861.882762344771, 5875.559333050039, 3159.8578063987175, 3597.964020359815, 3838.443425703313, 3862.6909847212155, 3868.0, 3901.51231096113, 3915.35396659548, 3920.0, 3934.5278566571624, 3937.6062393760394, 4196.28663127263, 4457.888552786207, 4493.685442019093, 4532.841350552715, 4573.0, 4424.336349547593, 4683.695559788603, 5248.868778280497, 5250.976059668328, 5609.59760059985, 1083.7453198498347, 1161.1581603337513, 1243.421808858885, 1248.5754843353122, 1249.5813902342636, 756.9848603028018, 845.3026253340895, 856.2293935458138, 863.9956800215958, 886.2599729226168, 4513.345564893138, 4759.643026773006, 4788.946431785002, 4920.4095508538485, 4972.751362431936, 5039.697618142924, 5174.456682048406, 5268.4994925482015, 5504.697241651747, 5555.777768889199, 4407.581279778416, 4521.412216411845, 5114.258432527263, 5125.820596279187, 5135.794568217229, 2426.7937225336045, 2574.6524219230537, 2847.345110624568, 2877.812942158753, 2880.6399200099986, 4896.559309662148, 4958.776855041532, 5224.231157486538, 5670.306604362894, 5800.53595712342, 4534.526278959285, 5045.445001049881, 5213.322268105122, 5559.943610714033, 5597.776088956478, 4907.871189626335, 5146.2280657900865, 5153.974230128975, 5522.392536820947, 5747.425257474303, 4710.657462623136, 4744.5492678195515, 4841.298011788201, 5262.500312351755, 5442.285680010784, 4283.678724095767, 4648.721076735406, 4681.789319480699, 4858.514148585114, 5360.303160589098, 4705.623660051652, 5260.0, 5767.336756273083, 5815.41845815415, 5854.970725146347, 3281.408516869364, 3409.2101646635715, 3571.8571257149424, 3621.6559426854406, 3662.7985460799914, 5070.41690205631, 5250.684958902478, 5315.475149304049, 5471.753771080391, 5498.948083813362, 4901.701049222031, 5240.737963101906, 5428.321459817523, 5445.7549410277425, 5590.748416321357, 4644.9767751161025, 4789.353437286061, 5007.57435617962, 5646.209530665628, 5681.829545113653, 4854.611631069435, 5185.585153187812, 5511.559075274049, 5520.834374968757, 5549.944500554942, 5136.226437723587, 5533.09108357616, 5578.553715702653, 5584.692841893572, 5619.353774316006, 5488.588355873246, 5494.670319780826, 5511.614187007031, 5517.448255174611, 5522.282103326542], "value": 3928.6117202908, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [650.7201903181633, 670.3129292475215, 732.9413646908246, 772.578944475261, 807.2774866494487, 14421.332053570097, 15452.536423907273, 15464.458743943978, 15480.380784768597, 15510.379584816608, 12869.227846329226, 13222.677732226797, 13838.238896860668, 14011.64970875726, 14480.203588802611, 856.5888373580669, 873.9300855931542, 883.3507372081515, 883.9071897450772, 884.3765145572356, 864.369010622243, 868.917452841979, 869.3393021303823, 873.1617647058831, 875.2210532625936, 910.5037754423822, 913.2967614936495, 914.3279689428265, 917.3578495053456, 920.3097676742443, 901.3555307954811, 904.7602385367883, 905.1355955062899, 905.3300557587371, 906.8730377747121, 668.4051194436955, 671.1375881991667, 674.8549061951655, 677.1265068062194, 678.932106789322, 11844.70388240301, 12005.87994120065, 12951.158174718614, 12972.935135324356, 13532.458701651922, 10387.046601128604, 11591.130665200144, 12220.572279970247, 12415.4413051412, 12622.179558328771, 12663.873361266453, 12765.61703148907, 12816.038797090256, 13085.672858178526, 13094.738105237937, 3564.322778672044, 3641.0715267606856, 3834.501514803058, 3849.402497963342, 3867.0332416895776, 109964.4501777494, 111602.0, 113175.0, 113671.43164284209, 113821.430892845, 10251.0, 10479.738006549896, 10520.842187367196, 10601.893981060244, 10983.286086404358, 18777.0, 20966.95165241732, 21389.25137620176, 21403.67894481584, 21438.464038399165, 5877.617954832922, 7122.143718422668, 7445.255474452511, 7519.398448124137, 7663.808404789925, 4131.0, 4226.302660061117, 4283.957160428418, 4300.7634580097765, 4314.719543229743, 3330.701026599628, 3365.192353835062, 3435.7766745161484, 3457.11843479911, 3466.185446420089, 564.1819361925143, 642.6626021338834, 645.0936434309708, 650.0217173154373, 660.46832299998, 604.826636325527, 639.354252205276, 658.154271760785, 676.1548064918851, 690.8721886451023, 7534.472586918972, 8510.872336914952, 9029.0, 9110.86333704995, 9221.723348299562, 517.3300575754407, 561.7247548701107, 599.3167788720862, 609.2840911928466, 627.3444250757968, 4254.553271906467, 4372.147431250875, 4514.232580461329, 4569.725816451024, 4581.587657110809, 97615.51192243994, 98219.50890245503, 99200.0, 102726.48636756769, 103550.0, 68270.65864670744, 87747.0, 90369.54815225882, 90444.0, 90833.0, 78415.60792196002, 78980.23569175166, 81809.59095204606, 81914.0, 82037.76943345973, 9391.765205869908, 9708.5145742714, 9803.656872009731, 9820.263480239153, 9828.655997040356, 39488.80255598704, 50866.728331792, 51008.469745907685, 51363.2295515575, 51373.48626513687, 46889.0, 61026.69486652538, 68881.0, 70047.0, 71899.64050179925, 9805.607775688892, 12964.675883102998, 12968.740625187633, 13298.867011329763, 13684.726305474034, 14587.562373128823, 14716.337764800395, 14734.926325368304, 14865.628359291104, 15048.247587620357, 12962.416691249105, 13392.531261405804, 13648.976326775333, 13703.246321452416, 13961.162330260217, 13324.333783310989, 13375.598732038054, 13472.663183420493, 13501.662458438617, 13990.370433330729, 12935.805962910752, 12988.285644289277, 13351.198928064347, 13377.799333009818, 13409.32953352348, 9063.263074318642, 10515.67740561102, 11189.384583847966, 11273.436328183721, 11355.772884542428, 7633.69465221385, 11205.60780372683, 11308.151888608223, 11433.0, 11437.714057148638], "value": 7299.440014846782, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1649.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 122.0, 173.0, 119.0, 155.0, 172.0, 105.0, 169.0, 137.0, 174.0], "value": 144.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 142.0, 115.0, 150.0, 102.0, 132.0, 147.0, 149.0, 146.0, 113.0], "value": 136.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [250.0, 230.0, 245.0, 220.0, 274.0, 217.0, 196.0, 197.0, 193.0, 205.0], "value": 222.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 58.0, 59.0, 86.0, 59.0, 61.0, 61.0, 59.0, 71.0, 61.0], "value": 65.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 71.0, 86.0, 70.0, 70.0, 71.0, 84.0, 72.0, 72.0, 88.0], "value": 77.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 114.0, 111.0, 169.0, 112.0, 114.0, 113.0, 115.0, 111.0, 114.0], "value": 118.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 139.0, 97.0, 100.0, 96.0, 97.0, 97.0, 131.0, 140.0, 96.0], "value": 113.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 98.0, 94.0, 92.0, 97.0, 106.0, 143.0, 119.0, 91.0, 97.0], "value": 103.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 58.0, 58.0, 56.0, 59.0, 59.0, 59.0, 58.0, 57.0, 59.0], "value": 58.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 78.0, 74.0, 82.0, 81.0, 77.0, 71.0, 71.0, 102.0, 108.0], "value": 81.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 89.0, 87.0, 88.0, 91.0, 86.0, 91.0, 87.0, 86.0, 99.0], "value": 89.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 167.0, 156.0, 151.0, 164.0, 142.0, 145.0, 162.0, 187.0, 182.0], "value": 162.5, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [185.0, 180.0, 176.0, 173.0, 193.0, 239.0, 233.0, 177.0, 177.0, 241.0], "value": 197.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 72.0, 87.0, 88.0, 87.0, 88.0, 89.0, 70.0, 65.0, 72.0], "value": 78.5, "unit": "score"}]}]} 15:39:26 INFO - Return code: 0 15:39:26 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:39:26 ERROR - # TBPL SUCCESS # 15:39:26 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:39:26 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:39:26 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:39:26 INFO - 2016-12-17 15:39:26.359 screenresolution[1659:11685] starting screenresolution argv=screenresolution get 15:39:26 INFO - 2016-12-17 15:39:26.362 screenresolution[1659:11685] Display 0: 1600x1200x32@60 15:39:26 INFO - 2016-12-17 15:39:26.368 screenresolution[1660:11688] starting screenresolution argv=screenresolution list 15:39:26 INFO - Available Modes on Display 0 15:39:26 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:39:26 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:39:26 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:39:26 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:39:26 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:39:26 INFO - Intel Iris: 15:39:26 INFO - Chipset Model: Intel Iris 15:39:26 INFO - Type: GPU 15:39:26 INFO - Bus: Built-In 15:39:26 INFO - VRAM (Dynamic, Max): 1536 MB 15:39:26 INFO - Vendor: Intel (0x8086) 15:39:26 INFO - Device ID: 0x0a2e 15:39:26 INFO - Revision ID: 0x0009 15:39:26 INFO - Displays: 15:39:26 INFO - Display: 15:39:26 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:39:26 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:39:26 INFO - Display Serial Number: 1600x1200 60 15:39:26 INFO - Main Display: Yes 15:39:26 INFO - Mirror: Off 15:39:26 INFO - Online: Yes 15:39:26 INFO - Rotation: Supported 15:39:26 INFO - Return code: 0 15:39:26 INFO - Running post-action listener: _resource_record_post_action 15:39:26 INFO - [mozharness: 2016-12-17 23:39:26.609331Z] Finished run-tests step (success) 15:39:26 INFO - Running post-run listener: _resource_record_post_run 15:39:26 INFO - Total resource usage - Wall time: 1201s; CPU: 55.0%; Read bytes: 34349568; Write bytes: 555846656; Read time: 323; Write time: 1803 15:39:26 INFO - TinderboxPrint: CPU usage
54.6% 15:39:26 INFO - TinderboxPrint: I/O read bytes / time
34,349,568 / 323 15:39:26 INFO - TinderboxPrint: I/O write bytes / time
555,846,656 / 1,803 15:39:26 INFO - TinderboxPrint: CPU idle
2,180.7 (45.4%) 15:39:26 INFO - TinderboxPrint: CPU system
970.0 (20.2%) 15:39:26 INFO - TinderboxPrint: CPU user
1,655.0 (34.4%) 15:39:26 INFO - TinderboxPrint: Swap in / out
280,539,136 / 0 15:39:26 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 250425856; Write bytes: 245966848; Read time: 18240; Write time: 500 15:39:26 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 30134784; Write bytes: 301388800; Read time: 292; Write time: 1275 15:39:27 INFO - Running post-run listener: _upload_blobber_files 15:39:27 INFO - Blob upload gear active. 15:39:27 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:39:27 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 15:39:27 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'] 15:39:27 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 15:39:27 INFO - (blobuploader) - INFO - Open directory for files ... 15:39:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:39:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:28 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:39:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:28 INFO - (blobuploader) - INFO - Done attempting. 15:39:28 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:39:28 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:28 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:29 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:39:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:29 INFO - (blobuploader) - INFO - Done attempting. 15:39:29 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:39:29 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:29 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:30 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:39:30 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:30 INFO - (blobuploader) - INFO - Done attempting. 15:39:30 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:39:30 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:39:30 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:39:32 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:39:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:39:32 INFO - (blobuploader) - INFO - Done attempting. 15:39:32 INFO - (blobuploader) - INFO - Iteration through files over. 15:39:32 INFO - Return code: 0 15:39:32 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:39:32 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:39:32 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df715416cb99528454efcfeaca8717a8537f62240ee2b85ae6cba6b0a0f2e8842a0a1d54b5d32aacd2a62fc02fda55cc60e30ad97ba97fefefc189264a6ff180", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96a59319efb6d84d4ce81e14884ca8025fc8d63769a1d7c4fabb03058434d98e9f2c9e1767e5eef2bbb5f2d98dde05d1b5b2759f0142a8df8691b3c1fde6f511", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e883db01848fcbbc5913cf6b96afb1a3ce5679bbfed33183eef31d6b1f849600519db73bce738c012f4c4fbbbb97a1d7cce84137931a508cb3f4115dc0e45f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5766c30a85a435412d6bd296531d923f4672397f211893782578459740b8ea995f63d352ea1c86aecb7371c537d8b2d37da566a3e66c42ae4b352f4e8dbc942"} 15:39:32 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:39:32 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:39:32 INFO - Contents: 15:39:32 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df715416cb99528454efcfeaca8717a8537f62240ee2b85ae6cba6b0a0f2e8842a0a1d54b5d32aacd2a62fc02fda55cc60e30ad97ba97fefefc189264a6ff180", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96a59319efb6d84d4ce81e14884ca8025fc8d63769a1d7c4fabb03058434d98e9f2c9e1767e5eef2bbb5f2d98dde05d1b5b2759f0142a8df8691b3c1fde6f511", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e883db01848fcbbc5913cf6b96afb1a3ce5679bbfed33183eef31d6b1f849600519db73bce738c012f4c4fbbbb97a1d7cce84137931a508cb3f4115dc0e45f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5766c30a85a435412d6bd296531d923f4672397f211893782578459740b8ea995f63d352ea1c86aecb7371c537d8b2d37da566a3e66c42ae4b352f4e8dbc942"} 15:39:32 INFO - Running post-run listener: copy_logs_to_upload_dir 15:39:32 INFO - Copying logs to upload dir... 15:39:32 INFO - mkdir: /builds/slave/test/build/upload/logs 15:39:32 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1263.086879 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 3 secs) (at 2016-12-17 15:39:32.431512) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 15:39:32.434801) ========= 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.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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/df715416cb99528454efcfeaca8717a8537f62240ee2b85ae6cba6b0a0f2e8842a0a1d54b5d32aacd2a62fc02fda55cc60e30ad97ba97fefefc189264a6ff180", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96a59319efb6d84d4ce81e14884ca8025fc8d63769a1d7c4fabb03058434d98e9f2c9e1767e5eef2bbb5f2d98dde05d1b5b2759f0142a8df8691b3c1fde6f511", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e883db01848fcbbc5913cf6b96afb1a3ce5679bbfed33183eef31d6b1f849600519db73bce738c012f4c4fbbbb97a1d7cce84137931a508cb3f4115dc0e45f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5766c30a85a435412d6bd296531d923f4672397f211893782578459740b8ea995f63d352ea1c86aecb7371c537d8b2d37da566a3e66c42ae4b352f4e8dbc942"} build_url:https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011194 build_url: 'https://queue.taskcluster.net/v1/task/ELPwFSZbTCmLWGtPtFJjXg/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/df715416cb99528454efcfeaca8717a8537f62240ee2b85ae6cba6b0a0f2e8842a0a1d54b5d32aacd2a62fc02fda55cc60e30ad97ba97fefefc189264a6ff180", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/96a59319efb6d84d4ce81e14884ca8025fc8d63769a1d7c4fabb03058434d98e9f2c9e1767e5eef2bbb5f2d98dde05d1b5b2759f0142a8df8691b3c1fde6f511", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/e883db01848fcbbc5913cf6b96afb1a3ce5679bbfed33183eef31d6b1f849600519db73bce738c012f4c4fbbbb97a1d7cce84137931a508cb3f4115dc0e45f50", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f5766c30a85a435412d6bd296531d923f4672397f211893782578459740b8ea995f63d352ea1c86aecb7371c537d8b2d37da566a3e66c42ae4b352f4e8dbc942"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-17 15:39:32.470437) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:39:32.470839) ========= 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.kc2sLuuNsW/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.hIExWrR62s/Listeners TMPDIR=/var/folders/v_/vkyzkmvs35919vlc7qy0wc4h00000w/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.005063 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-17 15:39:32.519929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 15:39:32.520267) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-17 15:39:32.520663) ========= ========= Total master_lag: 0.25 =========