builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0015 starttime: 1493954966.75 results: success (0) buildid: 20170504190325 builduid: ef109f44c1b04ef295d8fc7d27bf136a revision: 4b40534bf2f8bd6a62201bca15649cb613f84f37 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.749368) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.749800) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.750077) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.777571) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.778014) ========= 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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-05-04 20:29:26-- 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% 15.1M=0.001s 2017-05-04 20:29:26 (15.1 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.165286 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.969002) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:26.969330) ========= 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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.040238 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:27.032541) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 20:29:27.032919) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4b40534bf2f8bd6a62201bca15649cb613f84f37 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev 4b40534bf2f8bd6a62201bca15649cb613f84f37 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-05-04 20:29:27,160 truncating revision to first 12 chars 2017-05-04 20:29:27,160 Setting DEBUG logging. 2017-05-04 20:29:27,160 attempt 1/10 2017-05-04 20:29:27,160 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/4b40534bf2f8?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-05-04 20:29:28,313 unpacking tar archive at: mozilla-beta-4b40534bf2f8/testing/mozharness/ program finished with exit code 0 elapsedTime=1.445725 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-05-04 20:29:28.503589) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:28.503920) ========= script_repo_revision: 4b40534bf2f8bd6a62201bca15649cb613f84f37 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:28.504308) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:28.504572) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-05-04 20:29:28.528710) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-04 20:29:28.529083) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 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', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:29:28 INFO - MultiFileLogger online at 20170504 20:29:28 in /builds/slave/test 20:29:28 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 20:29:28 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 20:29:28 INFO - {'append_to_log': False, 20:29:28 INFO - 'base_work_dir': '/builds/slave/test', 20:29:28 INFO - 'blob_upload_branch': 'Mozilla-Beta', 20:29:28 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 20:29:28 INFO - 'branch': 'Mozilla-Beta', 20:29:28 INFO - 'buildbot_json_path': 'buildprops.json', 20:29:28 INFO - 'config_files': ('talos/mac_config.py',), 20:29:28 INFO - 'default_actions': ('clobber', 20:29:28 INFO - 'read-buildbot-config', 20:29:28 INFO - 'download-and-extract', 20:29:28 INFO - 'populate-webroot', 20:29:28 INFO - 'create-virtualenv', 20:29:28 INFO - 'install', 20:29:28 INFO - 'run-tests'), 20:29:28 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 20:29:28 INFO - 'download_minidump_stackwalk': True, 20:29:28 INFO - 'download_symbols': 'ondemand', 20:29:28 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 20:29:28 INFO - 'tooltool.py': '/tools/tooltool.py', 20:29:28 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 20:29:28 INFO - '/tools/misc-python/virtualenv.py')}, 20:29:28 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 20:29:28 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 20:29:28 INFO - 'gecko_profile': False, 20:29:28 INFO - 'gecko_profile_interval': 0, 20:29:28 INFO - 'installer_path': 'installer.exe', 20:29:28 INFO - 'log_level': 'info', 20:29:28 INFO - 'log_name': 'talos', 20:29:28 INFO - 'log_to_console': True, 20:29:28 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 20:29:28 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 20:29:28 INFO - 'opt_config_files': (), 20:29:28 INFO - 'pip_index': False, 20:29:28 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:28 INFO - 'cmd': ('bash', 20:29:28 INFO - '-c', 20:29:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:28 INFO - 'enabled': True, 20:29:28 INFO - 'halt_on_failure': False, 20:29:28 INFO - 'name': 'check_screen_resolution'},), 20:29:28 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 20:29:28 INFO - 'cmd': ('bash', 20:29:28 INFO - '-c', 20:29:28 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 20:29:28 INFO - 'enabled': True, 20:29:28 INFO - 'halt_on_failure': False, 20:29:28 INFO - 'name': 'check_screen_resolution'},), 20:29:28 INFO - 'run_cmd_checks_enabled': True, 20:29:28 INFO - 'suite': 'dromaeojs-e10s', 20:29:28 INFO - 'system_bits': '32', 20:29:28 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 20:29:28 INFO - 'title': 't-yosemite-r7-0015', 20:29:28 INFO - 'tooltool_cache': '/builds/tooltool_cache', 20:29:28 INFO - 'use_talos_json': True, 20:29:28 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 20:29:28 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 20:29:28 INFO - 'work_dir': 'build'} 20:29:28 INFO - [mozharness: 2017-05-05 03:29:28.740316Z] Running clobber step. 20:29:28 INFO - Running pre-action listener: _resource_record_pre_action 20:29:28 INFO - Running main action method: clobber 20:29:28 INFO - rmtree: /builds/slave/test/build 20:29:28 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 20:29:31 INFO - Running post-action listener: _resource_record_post_action 20:29:31 INFO - [mozharness: 2017-05-05 03:29:31.269550Z] Finished clobber step (success) 20:29:31 INFO - [mozharness: 2017-05-05 03:29:31.269684Z] Running read-buildbot-config step. 20:29:31 INFO - Running pre-action listener: _resource_record_pre_action 20:29:31 INFO - Running main action method: read_buildbot_config 20:29:31 INFO - Using buildbot properties: 20:29:31 INFO - { 20:29:31 INFO - "project": "", 20:29:31 INFO - "product": "firefox", 20:29:31 INFO - "script_repo_revision": "production", 20:29:31 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 20:29:31 INFO - "repository": "", 20:29:31 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 20:29:31 INFO - "buildid": "20170504190325", 20:29:31 INFO - "pgo_build": "False", 20:29:31 INFO - "basedir": "/builds/slave/test", 20:29:31 INFO - "buildnumber": 43, 20:29:31 INFO - "slavename": "t-yosemite-r7-0015", 20:29:31 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 20:29:31 INFO - "platform": "yosemite_r7", 20:29:31 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 20:29:31 INFO - "branch": "mozilla-beta", 20:29:31 INFO - "repo_path": "releases/mozilla-beta", 20:29:31 INFO - "revision": "4b40534bf2f8bd6a62201bca15649cb613f84f37", 20:29:31 INFO - "stage_platform": "macosx64", 20:29:31 INFO - "builduid": "ef109f44c1b04ef295d8fc7d27bf136a", 20:29:31 INFO - "slavebuilddir": "test" 20:29:31 INFO - } 20:29:31 INFO - Found installer url https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg. 20:29:31 INFO - Running post-action listener: _resource_record_post_action 20:29:31 INFO - [mozharness: 2017-05-05 03:29:31.272905Z] Finished read-buildbot-config step (success) 20:29:31 INFO - [mozharness: 2017-05-05 03:29:31.273044Z] Running download-and-extract step. 20:29:31 INFO - Running pre-action listener: _resource_record_pre_action 20:29:31 INFO - Running main action method: download_and_extract 20:29:31 INFO - mkdir: /builds/slave/test/build/tests 20:29:31 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:29:31 INFO - trying https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json 20:29:31 INFO - Downloading https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 20:29:31 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json'}, attempt #1 20:29:32 INFO - Downloaded 1287 bytes. 20:29:32 INFO - Reading from file /builds/slave/test/build/firefox-54.0.en-US.mac.test_packages.json 20:29:32 INFO - Using the following test package requirements: 20:29:32 INFO - {u'awsy': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.awsy.tests.zip'], 20:29:32 INFO - u'common': [u'firefox-54.0.en-US.mac.common.tests.zip'], 20:29:32 INFO - u'cppunittest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.cppunittest.tests.zip'], 20:29:32 INFO - u'gtest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.gtest.tests.zip'], 20:29:32 INFO - u'jittest': [u'firefox-54.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 20:29:32 INFO - u'mochitest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.mochitest.tests.zip'], 20:29:32 INFO - u'mozbase': [u'firefox-54.0.en-US.mac.common.tests.zip'], 20:29:32 INFO - u'reftest': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.reftest.tests.zip'], 20:29:32 INFO - u'talos': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.talos.tests.zip'], 20:29:32 INFO - u'web-platform': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.web-platform.tests.zip'], 20:29:32 INFO - u'xpcshell': [u'firefox-54.0.en-US.mac.common.tests.zip', 20:29:32 INFO - u'firefox-54.0.en-US.mac.xpcshell.tests.zip']} 20:29:32 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip'] for test suite category: common 20:29:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 20:29:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 20:29:32 INFO - Fetch https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 20:29:34 INFO - Expected file size: 51225205 20:29:34 INFO - Obtained file size: 51225205 20:29:50 INFO - Downloading packages: [u'firefox-54.0.en-US.mac.common.tests.zip', u'firefox-54.0.en-US.mac.talos.tests.zip'] for test suite category: talos 20:29:50 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip 20:29:50 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip'}, attempt #1 20:29:50 INFO - Fetch https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.common.tests.zip into memory 20:29:53 INFO - Expected file size: 51225205 20:29:53 INFO - Obtained file size: 51225205 20:30:08 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip 20:30:08 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip'}, attempt #1 20:30:08 INFO - Fetch https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.talos.tests.zip into memory 20:30:09 INFO - Expected file size: 14404449 20:30:09 INFO - Obtained file size: 14404449 20:30:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:09 INFO - trying https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:30:09 INFO - Downloading https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg to /builds/slave/test/build/firefox-54.0.en-US.mac.dmg 20:30:09 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg'}, attempt #1 20:30:11 INFO - Downloaded 59561512 bytes. 20:30:11 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:30:11 INFO - mkdir: /builds/slave/test/properties 20:30:11 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 20:30:11 INFO - Writing to file /builds/slave/test/properties/build_url 20:30:11 INFO - Contents: 20:30:11 INFO - build_url:https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg 20:30:12 INFO - Running post-action listener: _resource_record_post_action 20:30:12 INFO - Running post-action listener: set_extra_try_arguments 20:30:12 INFO - [mozharness: 2017-05-05 03:30:12.252950Z] Finished download-and-extract step (success) 20:30:12 INFO - [mozharness: 2017-05-05 03:30:12.253110Z] Running populate-webroot step. 20:30:12 INFO - Running pre-action listener: _resource_record_pre_action 20:30:12 INFO - Running main action method: populate_webroot 20:30:12 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 20:30:12 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 20:30:12 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 20:30:12 INFO - '--tppagecycles', 20:30:12 INFO - '7'], 20:30:12 INFO - 'tests': ['tsvgm']}}, 20:30:12 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['tresize', 'tcanvasmark']}, 20:30:12 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 20:30:12 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['dromaeo_css', 'kraken']}, 20:30:12 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 20:30:12 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:12 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 20:30:12 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['damp', 'tps']}, 20:30:12 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'tests': ['damp', 'tps']}, 20:30:12 INFO - 'g3': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['dromaeo_dom']}, 20:30:12 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 20:30:12 INFO - 'g4': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['basic_compositor_video']}, 20:30:12 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 20:30:12 INFO - 'other': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'other-e10s': {'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'other-e10s_l64': {'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['a11yr', 20:30:12 INFO - 'ts_paint', 20:30:12 INFO - 'tpaint', 20:30:12 INFO - 'sessionrestore', 20:30:12 INFO - 'sessionrestore_no_auto_restore', 20:30:12 INFO - 'tabpaint']}, 20:30:12 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['tsvgx', 20:30:12 INFO - 'tsvgr_opacity', 20:30:12 INFO - 'tart', 20:30:12 INFO - 'tscrollx', 20:30:12 INFO - 'cart', 20:30:12 INFO - 'tsvg_static']}, 20:30:12 INFO - 'svgr-e10s': {'tests': ['tsvgx', 20:30:12 INFO - 'tsvgr_opacity', 20:30:12 INFO - 'tart', 20:30:12 INFO - 'tscrollx', 20:30:12 INFO - 'cart', 20:30:12 INFO - 'tsvg_static']}, 20:30:12 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'talos_options': ['--disable-e10s'], 20:30:12 INFO - 'tests': ['tp5o']}, 20:30:12 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 20:30:12 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'talos_options': ['--disable-e10s', 20:30:12 INFO - '--xperf_path', 20:30:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:12 INFO - 'tests': ['tp5n']}, 20:30:12 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 20:30:12 INFO - 'talos_options': ['--xperf_path', 20:30:12 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 20:30:12 INFO - 'tests': ['tp5n']}}, 20:30:12 INFO - 'talos.zip': {'path': '', 20:30:12 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 20:30:12 INFO - Running post-action listener: _resource_record_post_action 20:30:12 INFO - [mozharness: 2017-05-05 03:30:12.273090Z] Finished populate-webroot step (success) 20:30:12 INFO - [mozharness: 2017-05-05 03:30:12.273228Z] Running create-virtualenv step. 20:30:12 INFO - Running pre-action listener: _resource_record_pre_action 20:30:12 INFO - Running main action method: create_virtualenv 20:30:12 INFO - Creating virtualenv /builds/slave/test/build/venv 20:30:12 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 20:30:12 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 20:30:12 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 20:30:12 INFO - Using real prefix '/tools/python27' 20:30:12 INFO - New python executable in /builds/slave/test/build/venv/bin/python 20:30:13 INFO - Installing distribute.............................................................................................................................................................................................done. 20:30:15 INFO - Installing pip.................done. 20:30:15 INFO - Return code: 0 20:30:15 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 20:30:15 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 20:30:16 INFO - Reading from file tmpfile_stdout 20:30:16 INFO - Output received: 20:30:16 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) 20:30:16 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 20:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:16 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:16 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 20:30:16 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 20:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:16 INFO - 'HOME': '/Users/cltbld', 20:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:16 INFO - 'LOGNAME': 'cltbld', 20:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:16 INFO - 'MOZ_NO_REMOTE': '1', 20:30:16 INFO - 'NO_EM_RESTART': '1', 20:30:16 INFO - 'PAGER': '/bin/cat', 20:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:16 INFO - 'PWD': '/builds/slave/test', 20:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:16 INFO - 'SHELL': '/bin/bash', 20:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:16 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:16 INFO - 'USER': 'cltbld', 20:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:16 INFO - 'XPC_FLAGS': '0x0', 20:30:16 INFO - 'XPC_SERVICE_NAME': '0', 20:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:16 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 20:30:16 INFO - Cleaning up... 20:30:16 INFO - Return code: 0 20:30:16 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 20:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:16 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:16 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:16 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 20:30:16 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 20:30:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:16 INFO - 'HOME': '/Users/cltbld', 20:30:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:16 INFO - 'LOGNAME': 'cltbld', 20:30:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:16 INFO - 'MOZ_NO_REMOTE': '1', 20:30:16 INFO - 'NO_EM_RESTART': '1', 20:30:16 INFO - 'PAGER': '/bin/cat', 20:30:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:16 INFO - 'PWD': '/builds/slave/test', 20:30:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:16 INFO - 'SHELL': '/bin/bash', 20:30:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:16 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:16 INFO - 'USER': 'cltbld', 20:30:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:16 INFO - 'XPC_FLAGS': '0x0', 20:30:16 INFO - 'XPC_SERVICE_NAME': '0', 20:30:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:16 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:16 INFO - Downloading/unpacking psutil>=3.1.1 20:30:16 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 20:30:16 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 20:30:16 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 20:30:16 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 20:30:16 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 20:30:16 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 20:30:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 20:30:20 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:20 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:20 INFO - Installing collected packages: psutil 20:30:20 INFO - Running setup.py install for psutil 20:30:20 INFO - building 'psutil._psutil_osx' extension 20:30:20 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 20:30:21 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 20:30:21 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 20:30:21 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 20:30:21 INFO - building 'psutil._psutil_posix' extension 20:30:21 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 20:30:21 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 20:30:21 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 20:30:21 INFO - ^ 20:30:21 INFO - 1 warning generated. 20:30:21 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 20:30:21 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 20:30:21 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 20:30:21 INFO - Successfully installed psutil 20:30:21 INFO - Cleaning up... 20:30:21 INFO - Return code: 0 20:30:21 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 20:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:21 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:21 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 20:30:21 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 20:30:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:21 INFO - 'HOME': '/Users/cltbld', 20:30:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:21 INFO - 'LOGNAME': 'cltbld', 20:30:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:21 INFO - 'MOZ_NO_REMOTE': '1', 20:30:21 INFO - 'NO_EM_RESTART': '1', 20:30:21 INFO - 'PAGER': '/bin/cat', 20:30:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:21 INFO - 'PWD': '/builds/slave/test', 20:30:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:21 INFO - 'SHELL': '/bin/bash', 20:30:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:21 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:21 INFO - 'USER': 'cltbld', 20:30:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:21 INFO - 'XPC_FLAGS': '0x0', 20:30:21 INFO - 'XPC_SERVICE_NAME': '0', 20:30:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:22 INFO - Downloading/unpacking mozsystemmonitor==0.3 20:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:22 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:22 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:22 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:22 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:23 INFO - Downloading mozsystemmonitor-0.3.tar.gz 20:30:23 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 20:30:23 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 20:30:23 INFO - Installing collected packages: mozsystemmonitor 20:30:23 INFO - Running setup.py install for mozsystemmonitor 20:30:23 INFO - Successfully installed mozsystemmonitor 20:30:23 INFO - Cleaning up... 20:30:23 INFO - Return code: 0 20:30:23 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 20:30: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')]} 20:30:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30: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')]} 20:30:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:23 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:23 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 20:30:23 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 20:30:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:23 INFO - 'HOME': '/Users/cltbld', 20:30:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:23 INFO - 'LOGNAME': 'cltbld', 20:30:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:23 INFO - 'MOZ_NO_REMOTE': '1', 20:30:23 INFO - 'NO_EM_RESTART': '1', 20:30:23 INFO - 'PAGER': '/bin/cat', 20:30:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:23 INFO - 'PWD': '/builds/slave/test', 20:30:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:23 INFO - 'SHELL': '/bin/bash', 20:30:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:23 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:23 INFO - 'USER': 'cltbld', 20:30:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:23 INFO - 'XPC_FLAGS': '0x0', 20:30:23 INFO - 'XPC_SERVICE_NAME': '0', 20:30:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:23 INFO - Downloading/unpacking jsonschema==2.5.1 20:30:23 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 20:30:23 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 20:30:23 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 20:30:23 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 20:30:23 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 20:30:23 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 20:30:25 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 20:30:25 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 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 20:30:25 INFO - Downloading functools32-3.2.3-2.tar.gz 20:30:25 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 20:30:25 INFO - warning: no files found matching '*.txt' 20:30:25 INFO - no previously-included directories found matching 'build' 20:30:25 INFO - no previously-included directories found matching 'dist' 20:30:25 INFO - no previously-included directories found matching '.git*' 20:30:25 INFO - Installing collected packages: jsonschema, functools32 20:30:25 INFO - Running setup.py install for functools32 20:30:25 INFO - warning: no files found matching '*.txt' 20:30:25 INFO - no previously-included directories found matching 'build' 20:30:25 INFO - no previously-included directories found matching 'dist' 20:30:25 INFO - no previously-included directories found matching '.git*' 20:30:25 INFO - Successfully installed jsonschema functools32 20:30:25 INFO - Cleaning up... 20:30:25 INFO - Return code: 0 20:30:25 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 20:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:25 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:25 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:25 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:25 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:25 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 20:30:25 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 20:30:25 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:25 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:25 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:25 INFO - 'HOME': '/Users/cltbld', 20:30:25 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:25 INFO - 'LOGNAME': 'cltbld', 20:30:25 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:25 INFO - 'MOZ_NO_REMOTE': '1', 20:30:25 INFO - 'NO_EM_RESTART': '1', 20:30:25 INFO - 'PAGER': '/bin/cat', 20:30:25 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:25 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:25 INFO - 'PWD': '/builds/slave/test', 20:30:25 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:25 INFO - 'SHELL': '/bin/bash', 20:30:25 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:25 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:25 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:25 INFO - 'USER': 'cltbld', 20:30:25 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:25 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:25 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:25 INFO - 'XPC_FLAGS': '0x0', 20:30:25 INFO - 'XPC_SERVICE_NAME': '0', 20:30:25 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:26 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 20:30:26 INFO - Cleaning up... 20:30:26 INFO - Return code: 0 20:30:26 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 20:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 20:30:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 20:30:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:26 INFO - 'HOME': '/Users/cltbld', 20:30:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:26 INFO - 'LOGNAME': 'cltbld', 20:30:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:26 INFO - 'MOZ_NO_REMOTE': '1', 20:30:26 INFO - 'NO_EM_RESTART': '1', 20:30:26 INFO - 'PAGER': '/bin/cat', 20:30:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:26 INFO - 'PWD': '/builds/slave/test', 20:30:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:26 INFO - 'SHELL': '/bin/bash', 20:30:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:26 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:26 INFO - 'USER': 'cltbld', 20:30:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:26 INFO - 'XPC_FLAGS': '0x0', 20:30:26 INFO - 'XPC_SERVICE_NAME': '0', 20:30:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:26 INFO - Downloading/unpacking blobuploader==1.2.4 20:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:27 INFO - Downloading blobuploader-1.2.4.tar.gz 20:30:27 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 20:30:27 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 20:30:27 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 20:30:27 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 20:30:27 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 20:30:27 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 20:30:27 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 20:30:27 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 20:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 20:30:28 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 20:30:28 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 20:30:28 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 20:30:28 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 20:30:28 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 20:30:28 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 20:30:28 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 20:30:28 INFO - Downloading docopt-0.6.1.tar.gz 20:30:28 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 20:30:28 INFO - Installing collected packages: blobuploader, requests, docopt 20:30:28 INFO - Running setup.py install for blobuploader 20:30:28 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 20:30:28 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 20:30:28 INFO - Running setup.py install for requests 20:30:29 INFO - Running setup.py install for docopt 20:30:29 INFO - Successfully installed blobuploader requests docopt 20:30:29 INFO - Cleaning up... 20:30:29 INFO - Return code: 0 20:30:29 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:29 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:29 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:29 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 20:30:29 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 20:30:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:29 INFO - 'HOME': '/Users/cltbld', 20:30:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:29 INFO - 'LOGNAME': 'cltbld', 20:30:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:29 INFO - 'MOZ_NO_REMOTE': '1', 20:30:29 INFO - 'NO_EM_RESTART': '1', 20:30:29 INFO - 'PAGER': '/bin/cat', 20:30:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:29 INFO - 'PWD': '/builds/slave/test', 20:30:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:29 INFO - 'SHELL': '/bin/bash', 20:30:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:29 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:29 INFO - 'USER': 'cltbld', 20:30:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:29 INFO - 'XPC_FLAGS': '0x0', 20:30:29 INFO - 'XPC_SERVICE_NAME': '0', 20:30:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:29 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-4PcWDV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:29 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:29 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-FeILbc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-7YvT5t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-5eh7fh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-QBrSJH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-_RBBnL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-dr4kKQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-aC5lhT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-lLyx0N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-pDg1B0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Y3scDP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:30 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:30 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-fxbFWs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-MSn7dT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-5wsx9h-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-PkrmPD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-BlHwle-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:31 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:31 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-DTjk5r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:31 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 20:30:31 INFO - Running setup.py install for manifestparser 20:30:31 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 20:30:31 INFO - Running setup.py install for mozcrash 20:30:31 INFO - Running setup.py install for mozdebug 20:30:31 INFO - Running setup.py install for mozdevice 20:30:32 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Installing dm script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Running setup.py install for mozfile 20:30:32 INFO - Running setup.py install for mozhttpd 20:30:32 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Running setup.py install for mozinfo 20:30:32 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Running setup.py install for mozInstall 20:30:32 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 20:30:32 INFO - Running setup.py install for mozleak 20:30:32 INFO - Running setup.py install for mozlog 20:30:33 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Running setup.py install for moznetwork 20:30:33 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Running setup.py install for mozprocess 20:30:33 INFO - Running setup.py install for mozprofile 20:30:33 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Running setup.py install for mozrunner 20:30:33 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 20:30:33 INFO - Running setup.py install for mozscreenshot 20:30:33 INFO - Running setup.py install for moztest 20:30:33 INFO - Running setup.py install for mozversion 20:30:34 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 20:30:34 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 20:30:34 INFO - Cleaning up... 20:30:34 INFO - Return code: 0 20:30:34 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:34 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:34 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 20:30:34 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 20:30:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:34 INFO - 'HOME': '/Users/cltbld', 20:30:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:34 INFO - 'LOGNAME': 'cltbld', 20:30:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:34 INFO - 'MOZ_NO_REMOTE': '1', 20:30:34 INFO - 'NO_EM_RESTART': '1', 20:30:34 INFO - 'PAGER': '/bin/cat', 20:30:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:34 INFO - 'PWD': '/builds/slave/test', 20:30:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:34 INFO - 'SHELL': '/bin/bash', 20:30:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:34 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:34 INFO - 'USER': 'cltbld', 20:30:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:34 INFO - 'XPC_FLAGS': '0x0', 20:30:34 INFO - 'XPC_SERVICE_NAME': '0', 20:30:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-fRehyc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 20:30:34 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)) 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Tde3FV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 20:30:34 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)) 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-L7AbUQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 20:30:34 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)) 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-w11EMO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 20:30:34 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)) 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-PQojAJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 20:30:34 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)) 20:30:34 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 20:30:34 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-aeuadf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-JyGXWk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-E0iZsQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-qKt9bT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-0tISoU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-VlZf8l-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-Dgn8to-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 20:30:35 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-XlVSka-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-VTPhGg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-hbAvgN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 20:30:35 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)) 20:30:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 20:30:35 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-M7EIQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 20:30:36 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 20:30:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 20:30:36 INFO - Running setup.py (path:/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/pip-MaDW5b-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 20:30:36 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)) 20:30:36 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)) 20:30:36 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)) 20:30:36 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)) 20:30:36 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)) 20:30:36 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)) 20:30:36 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 20:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:36 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:36 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 20:30:36 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 20:30:36 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 20:30:37 INFO - Downloading blessings-1.6.tar.gz 20:30:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 20:30:37 INFO - Installing collected packages: blessings 20:30:37 INFO - Running setup.py install for blessings 20:30:37 INFO - Successfully installed blessings 20:30:37 INFO - Cleaning up... 20:30:37 INFO - Return code: 0 20:30:37 INFO - Done creating virtualenv /builds/slave/test/build/venv. 20:30:37 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:38 INFO - Reading from file tmpfile_stdout 20:30:38 INFO - Current package versions: 20:30:38 INFO - blessings == 1.6 20:30:38 INFO - blobuploader == 1.2.4 20:30:38 INFO - docopt == 0.6.1 20:30:38 INFO - functools32 == 3.2.3-2 20:30:38 INFO - jsonschema == 2.5.1 20:30:38 INFO - manifestparser == 1.1 20:30:38 INFO - mozInstall == 1.12 20:30:38 INFO - mozcrash == 1.0 20:30:38 INFO - mozdebug == 0.1 20:30:38 INFO - mozdevice == 0.49 20:30:38 INFO - mozfile == 1.2 20:30:38 INFO - mozhttpd == 0.7 20:30:38 INFO - mozinfo == 0.9 20:30:38 INFO - mozleak == 0.1 20:30:38 INFO - mozlog == 3.4 20:30:38 INFO - moznetwork == 0.27 20:30:38 INFO - mozprocess == 0.25 20:30:38 INFO - mozprofile == 0.28 20:30:38 INFO - mozrunner == 6.13 20:30:38 INFO - mozscreenshot == 0.1 20:30:38 INFO - mozsystemmonitor == 0.3 20:30:38 INFO - moztest == 0.8 20:30:38 INFO - mozversion == 1.4 20:30:38 INFO - psutil == 3.1.1 20:30:38 INFO - requests == 1.2.3 20:30:38 INFO - wsgiref == 0.1.2 20:30:38 INFO - Installing None into virtualenv /builds/slave/test/build/venv 20:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:38 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:38 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 20:30:38 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 20:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:38 INFO - 'HOME': '/Users/cltbld', 20:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:38 INFO - 'LOGNAME': 'cltbld', 20:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:38 INFO - 'MOZ_NO_REMOTE': '1', 20:30:38 INFO - 'NO_EM_RESTART': '1', 20:30:38 INFO - 'PAGER': '/bin/cat', 20:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:38 INFO - 'PWD': '/builds/slave/test', 20:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:38 INFO - 'SHELL': '/bin/bash', 20:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:38 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:38 INFO - 'USER': 'cltbld', 20:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:38 INFO - 'XPC_FLAGS': '0x0', 20:30:38 INFO - 'XPC_SERVICE_NAME': '0', 20:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 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)) 20:30:38 INFO - Cleaning up... 20:30:38 INFO - Return code: 0 20:30:38 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 20:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:38 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 20:30:38 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:38 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 20:30:38 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 20:30:38 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 0x110336b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110339768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc7b0da2270>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, '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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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 20:30:38 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 20:30:38 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 20:30:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:38 INFO - 'HOME': '/Users/cltbld', 20:30:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:38 INFO - 'LOGNAME': 'cltbld', 20:30:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:38 INFO - 'MOZ_NO_REMOTE': '1', 20:30:38 INFO - 'NO_EM_RESTART': '1', 20:30:38 INFO - 'PAGER': '/bin/cat', 20:30:38 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:38 INFO - 'PWD': '/builds/slave/test', 20:30:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:38 INFO - 'SHELL': '/bin/bash', 20:30:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:38 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:38 INFO - 'USER': 'cltbld', 20:30:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:38 INFO - 'XPC_FLAGS': '0x0', 20:30:38 INFO - 'XPC_SERVICE_NAME': '0', 20:30:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:38 INFO - Ignoring indexes: https://pypi.python.org/simple/ 20:30:38 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 20:30:38 INFO - Cleaning up... 20:30:38 INFO - Return code: 0 20:30:38 INFO - Running post-action listener: _resource_record_post_action 20:30:38 INFO - Running post-action listener: _start_resource_monitoring 20:30:38 INFO - Starting resource monitoring. 20:30:38 INFO - [mozharness: 2017-05-05 03:30:38.685614Z] Finished create-virtualenv step (success) 20:30:38 INFO - [mozharness: 2017-05-05 03:30:38.686569Z] Running install step. 20:30:38 INFO - Running pre-action listener: _resource_record_pre_action 20:30:38 INFO - Running main action method: install 20:30:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 20:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 20:30:38 INFO - Reading from file tmpfile_stdout 20:30:38 INFO - Detecting whether we're running mozinstall >=1.0... 20:30:38 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 20:30:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 20:30:39 INFO - Reading from file tmpfile_stdout 20:30:39 INFO - Output received: 20:30:39 INFO - Usage: mozinstall [options] installer 20:30:39 INFO - Options: 20:30:39 INFO - -h, --help show this help message and exit 20:30:39 INFO - -d DEST, --destination=DEST 20:30:39 INFO - Directory to install application into. [default: 20:30:39 INFO - "/builds/slave/test"] 20:30:39 INFO - --app=APP Application being installed. [default: firefox] 20:30:39 INFO - mkdir: /builds/slave/test/build/application 20:30:39 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-54.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 20:30:39 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-54.0.en-US.mac.dmg --destination /builds/slave/test/build/application 20:30:53 INFO - Reading from file tmpfile_stdout 20:30:53 INFO - Output received: 20:30:53 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 20:30:53 INFO - Running post-action listener: _resource_record_post_action 20:30:53 INFO - [mozharness: 2017-05-05 03:30:53.954796Z] Finished install step (success) 20:30:53 INFO - [mozharness: 2017-05-05 03:30:53.954907Z] Running run-tests step. 20:30:53 INFO - Running pre-action listener: _resource_record_pre_action 20:30:53 INFO - Running main action method: run_tests 20:30:53 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:30:53 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:30:53 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:30:53 INFO - 2017-05-04 20:30:53.969 screenresolution[1631:7852] starting screenresolution argv=screenresolution get 20:30:53 INFO - 2017-05-04 20:30:53.972 screenresolution[1631:7852] Display 0: 1600x1200x32@60 20:30:53 INFO - 2017-05-04 20:30:53.977 screenresolution[1632:7854] starting screenresolution argv=screenresolution list 20:30:53 INFO - Available Modes on Display 0 20:30:53 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:30:53 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:30:53 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:30:53 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:30:54 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:30:54 INFO - Intel Iris: 20:30:54 INFO - Chipset Model: Intel Iris 20:30:54 INFO - Type: GPU 20:30:54 INFO - Bus: Built-In 20:30:54 INFO - VRAM (Dynamic, Max): 1536 MB 20:30:54 INFO - Vendor: Intel (0x8086) 20:30:54 INFO - Device ID: 0x0a2e 20:30:54 INFO - Revision ID: 0x0009 20:30:54 INFO - Displays: 20:30:54 INFO - Display: 20:30:54 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:30:54 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:30:54 INFO - Display Serial Number: 1600x1200 60 20:30:54 INFO - Main Display: Yes 20:30:54 INFO - Mirror: Off 20:30:54 INFO - Online: Yes 20:30:54 INFO - Rotation: Supported 20:30:54 INFO - Return code: 0 20:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 20:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 20:30:54 INFO - Python 2.7.3 20:30:54 INFO - Return code: 0 20:30:54 INFO - grabbing minidump binary from tooltool 20:30:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 20:30:54 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 0x110114d30>, '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 0x11003bdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x110109be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 20:30:54 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 20:30:54 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 20:30:54 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 20:30:54 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 20:30:54 INFO - Return code: 0 20:30:54 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 20:30:54 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 20:30:54 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 20:30:54 INFO - ENV: RUST_BACKTRACE is now 1 20:30:54 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 20:30:54 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 20:30:54 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 20:30:54 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0015 --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 20:30:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.E6ENeHtSV4/Render', 20:30:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 20:30:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 20:30:54 INFO - 'HOME': '/Users/cltbld', 20:30:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 20:30:54 INFO - 'LOGNAME': 'cltbld', 20:30:54 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 20:30:54 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 20:30:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 20:30:54 INFO - 'MOZ_NO_REMOTE': '1', 20:30:54 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 20:30:54 INFO - 'NO_EM_RESTART': '1', 20:30:54 INFO - 'PAGER': '/bin/cat', 20:30:54 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 20:30:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 20:30:54 INFO - 'PWD': '/builds/slave/test', 20:30:54 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 20:30:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 20:30:54 INFO - 'RUST_BACKTRACE': '1', 20:30:54 INFO - 'SHELL': '/bin/bash', 20:30:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.p6hEXcaf2N/Listeners', 20:30:54 INFO - 'TMPDIR': '/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/', 20:30:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 20:30:54 INFO - 'USER': 'cltbld', 20:30:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 20:30:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 20:30:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 20:30:54 INFO - 'XPC_FLAGS': '0x0', 20:30:54 INFO - 'XPC_SERVICE_NAME': '0', 20:30:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 20:30:54 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0015', '--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 20:30:54 INFO - mozversion application_buildid: 20170504190325 20:30:54 INFO - mozversion application_changeset: 4b40534bf2f8bd6a62201bca15649cb613f84f37 20:30:54 INFO - mozversion application_display_name: Firefox 20:30:54 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 20:30:54 INFO - mozversion application_name: Firefox 20:30:54 INFO - mozversion application_remotingname: firefox 20:30:54 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 20:30:54 INFO - mozversion application_vendor: Mozilla 20:30:54 INFO - mozversion application_version: 54.0 20:30:54 INFO - mozversion platform_buildid: 20170504190325 20:30:54 INFO - mozversion platform_changeset: 4b40534bf2f8bd6a62201bca15649cb613f84f37 20:30:54 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 20:30:54 INFO - mozversion platform_version: 54.0 20:30:54 INFO - using testdate: 1493955054 20:30:54 INFO - actual date: 1493955054 20:30:54 INFO - starting webserver on 'localhost:49237' 20:30:54 INFO - SUITE-START | Running 2 tests 20:30:54 INFO - TEST-START | dromaeo_css 20:30:54 INFO - operating with platform_type : mac_ 20:30:54 INFO - Initialising browser for dromaeo_css test... 20:30:54 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmptAHezq/profile http://localhost:49237/getInfo.html) 20:30:57 INFO - PROCESS | 1642 | 2017-05-04 20:30:57.453 plugin-container[1645:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b3f, name = 'com.apple.tsm.portname' 20:30:57 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:30:57 INFO - PROCESS | 1642 | 2017-05-04 20:30:57.454 plugin-container[1645:8026] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c1b, name = 'com.apple.CFPasteboardClient' 20:30:57 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:30:57 INFO - PROCESS | 1642 | 2017-05-04 20:30:57.454 plugin-container[1645:8026] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:30:57 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 20:30:57 INFO - PROCESS | 1642 | colorDepth:24 20:30:57 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 20:30:57 INFO - PROCESS | 1642 | __metrics 20:30:57 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 20:30:58 INFO - TEST-INFO | 1642: exit 0 20:30:58 INFO - Browser initialized. 20:30:58 INFO - Running cycle 1/1 for dromaeo_css test... 20:30:58 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmptAHezq/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:31:00 INFO - PROCESS | 1647 | 2017-05-04 20:31:00.782 plugin-container[1648:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x454b, name = 'com.apple.tsm.portname' 20:31:00 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:00 INFO - PROCESS | 1647 | 2017-05-04 20:31:00.783 plugin-container[1648:8159] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x870b, name = 'com.apple.CFPasteboardClient' 20:31:00 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:31:00 INFO - PROCESS | 1647 | 2017-05-04 20:31:00.783 plugin-container[1648:8159] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:31:10 INFO - PROCESS | 1647 | RSS: Main: 174137344 20:31:10 INFO - PROCESS | 1647 | 20:34:13 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49237/tests/dromaeo/cssquery-ext.html) 20:34:15 INFO - PROCESS | 1647 | RSS: Main: 189292544 20:34:15 INFO - PROCESS | 1647 | 20:37:17 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-ext.html (next: http://localhost:49237/tests/dromaeo/cssquery-jquery.html) 20:37:18 INFO - PROCESS | 1647 | RSS: Main: 203833344 20:37:18 INFO - PROCESS | 1647 | 20:40:20 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49237/tests/dromaeo/cssquery-mootools.html) 20:40:21 INFO - PROCESS | 1647 | RSS: Main: 205357056 20:40:21 INFO - PROCESS | 1647 | 20:43:22 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49237/tests/dromaeo/cssquery-prototype.html) 20:43:23 INFO - PROCESS | 1647 | RSS: Main: 208474112 20:43:23 INFO - PROCESS | 1647 | 20:46:26 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49237/tests/dromaeo/cssquery-yui.html) 20:46:27 INFO - PROCESS | 1647 | RSS: Main: 210956288 20:46:27 INFO - PROCESS | 1647 | 20:49:29 INFO - PROCESS | 1647 | Cycle 1(1): loaded http://localhost:49237/tests/dromaeo/cssquery-yui.html (next: http://localhost:49237/tests/dromaeo/cssquery-dojo.html) 20:49:30 INFO - PROCESS | 1647 | RSS: Main: 209027072 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | __start_tp_report 20:49:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load 20:49:30 INFO - PROCESS | 1647 | _x_x_mozilla_page_load_details 20:49:30 INFO - PROCESS | 1647 | |i|pagename|runs| 20:49:30 INFO - PROCESS | 1647 | |0;dojo.html;3526.1889765353967;3592.2635859648776;3597.982010089951;3599.208174201675;3603.2793441311746;6083.117947897552;6136.969315153424;6137.846553836156;6147.415995480422;6170.629762214259;5028.974855125728;6134.0798880167995;6203.503719702424;6222;6240.750369985195;5104.2088476286235;5486;5490.313710786152;5496.0931446311315;5570.498655121039;4551.658625603077;5540.252065971103;5549.19536667183;5568.6748311274705;5589.636673616223;5363.865903352428;5370.731463426813;5548;5563;5582.078956972115;4461.531539188403;5415.891682166354;5439.292891924025;5441.211024401479;5447.618666693333;5268.394134674523;5408.080626293539;5427;5448.101063324547;5488.313960754906;6165.136880836686;6218.378162183791;6247.9687601562155;6254.249490061176;6263.185785847811;6018;6181.752729890799;6430.807075787734;6475;6509.6419696916655;6261.686915654244;6309.400606942333;6400.647964361959;6471.935280647181;6580.835479113012;6241.375862413768;6287.779927702509;6297.685115744194;6297.811065668083;6317.463015643676;6712.295209003082;6726.428253598499;6743.3593808589085;6744.089547911068;6783.864322713617;6293.811185664437;6310.873782524323;6311.958526843111;6439.356064393617;6447.903281450782;6504.1869766279215;6560.737570497126;6567.638779867153;6570.868582628416;6587.044878492592;6472.579282346633;6501.544891857525;6502.057201705727;6502.60984340941;6523.836904077436;6319.873602527924;6322.620642761357;6435.839104022343;6438.742450301936;6500.837479062967;5163.251328557338;5185;5225.608079394061;5267.631265811357;5278;4495.685302028826;4547.386102876136;4569.109023740339;4576.244919588231;4698.812047518061;6151.569390142737;6232.937670623236;6261.812145635638;6279.215098112736;6291.087792270096;6552.541322107502;6613.702383392759;6628.403443689995;6645.235797883209;6647.434968027772;6130.887362650998;6158.353372895871;6212.223472065992;6325.399087086719;6375.458086062644;5776.989026920272;5799.449052340021;5809;5824.155497452845;5863.472287494146;6780.966095169492;6817.863642727118;6825.658717064028;6826.146731658543;6851.075104860979;6312.3056463788025;6313.116163736989;6538.411542961157;6587.670616469253;6634.402903738686;6696.665166741741;6711.899321510084;6787;6824.829379265558;6840.794776156723;6465.1595292611655;6487.124238227968;6487.18910136233;6488.091667166506;6504.577202481823;5038.395392552917;6241.500679945684;6545.869082618226;6575.80272591823;6620.569662971891;6275.717592708248;6293.339199383999;6301.968490157703;6337.8732425352155;6339.207599050119;6336.271328797247;6392.840178995562;6393.87212255743;6402.6158430494315;6416.262129854939;6160.13758073861;6263.592866463848;6300.684965751602;6325.96837015812;6326.493880489642;6226.097215903578;6235.812925612239;6314.778982735582;6337.873242535031;6452.161219041494;5292.576593872404;6268.561200715906;6289.371062893674;6327.873442531031;6335.714892829926 20:49:30 INFO - PROCESS | 1647 | |1;ext.html;711.8327193109618;752.7579493835171;851.4550687559961;1000.4347543637849;1001.4291853643417;40140.19719605606;41021.3846792298;42882.78558607202;42924;42950.35574466385;40612.79693601535;40945;40991.79504102475;41019;41087;7559;8304.211099945496;11269.605563805268;11513.251638643504;11726;11319.943400282988;11394.373309468017;11402.885971140266;11409.144314176428;11424.71438214043;11410.315381077162;11416.71458213543;11429.02853257474;11440;11451;9533.332666713333;11370.204085713962;11372.090232781358;11420.371879546621;11460.541578336857;11111.277766945122;11169.72075698114;11171;11193.048590869786;11202.775944481145;36039.4594081089;36091.45862812034;36158.45762313568;36186.733464328885;36191;3870.845166193377;3897.610238976108;3908.530976282836;3914.1388894443176;3920.2355540669582;26692;26926.730732692813;27456.45087098267;27550.5867411989;27646.585301220497;3205.9198520036953;3271.6401195868434;3289.6838070903923;3291.53918451417;3296.225387034045;352067.2396638026;357686;357880.4211957873;358817;359444;45931.311030334575;46197.53802461999;46220.306695399595;46234.537654623695;46279.76860115678;47988.280175797394;48067.519324807006;48069;48187.759061204466;48212.7589362058;14588.562343129512;14931.477398291008;15122.621934451727;15147.772783408258;15148.01537900033;12794.808077878839;12843.550475733491;12871.099023068482;12894.48422063107;12905.677358066123;12217.877821221851;12248.510059597695;12285.69285767845;12289.955353794849;12422.37888105591;4839.249916262985;5650.4402226596985;5717.2281741965135;5729.427057294237;5738.77044918207;881.8192270584515;949.5252373813094;960.8126415349079;993.821112199811;1021.1116328793848;29038.564421533694;29303.267418314288;29538.409231815673;29623.555646665318;30064.24839379033;1052.137247457093;1071.919606029551;1091.8143915534379;1109.4619109731773;1111.360967443723;14622.415103395957;14918.477853275082;15176.544703658908;15344.079355238722;15350.155741434328;478491;481677;484601;485279;485348.57325713144;51571.22643160356;58721.412785871595;59357.361050700114;59488.10767838486;59661.403385966456;1483.5549335199396;1489.63503941535;1507.4422463688286;1510.9924450377898;1520.3158578639657;27628.894844206006;28144.577831332135;29016.274593135342;29174.305545205043;29250.976215832343;43359.566404336816;43576.56423435852;43589.34615980699;43623.345649815914;43726.78136609297;468954;471771;473649.631751839;475520;476825;30342.84828575843;30420.695793041785;30474.238144046576;30645.08064758061;30896.227594310323;40470.798569726336;40527.59472405238;41127.794361028005;41453.79273103615;41465.58534414617;28575.42849143047;29245.561316579842;29951.400971980875;30217.395652087274;30385;27650.585241220997;29191;29521.114366569036;29574.408511829213;30118.69881301159;33813.830930845186;34421.96734097981;34547.82726086354;35272.647273526934;35836.82081589576;22529.159906925946;29187.7081229185;29431.558526622546;29462.41075178527;30915.845420772752;19779.604407912047;22798.31605051851;23876.641850372605;23944.28167154988;24301.784910754744 20:49:30 INFO - PROCESS | 1647 | |2;jquery.html;424.52241228617805;437.11484244405074;437.22610978567934;445.3942638012303;446.80787261477565;20753;22184.88907555462;22412.991415386306;23500.647490287618;23673.289801305946;19517.804821951813;22631.54736905261;23539.76460235397;24168.03327866883;24361.512769744593;6481.898077326842;6606.14120164378;6763.594184348943;6982.197047339545;7030.789076327693;6779.118714567099;6866.313368663119;6886.10480637519;6891.896621550681;6917.239103698589;6617.239017512999;6935.306469353075;6954.026436298922;7011.964940175292;7031.929680703179;6265.185525881606;6753.797386078401;6887.242403335629;6901.6204108774;6988.54574452664;5757.366689664133;6830.282820303896;6833.897491537631;6843.589384636888;6845.904655255135;27641.447171056465;27664.723352766418;27755.722442775517;27820.721792782217;28460.861565537354;2419.4677171022345;2700.351915540276;3103.8137711737368;3112.1908303840937;3146.889858854929;15071.623209419742;16805;17219.397321093827;17289.567760805956;17552.210150543127;2074.8962551872346;2114.2600089968387;2116.5766846630736;2117.8729276243475;2119.6714509250937;135785;140879.5912040877;164758;166675;173728.99999999875;16623.91688041552;16875.746863797052;18717.97051162172;19898.50253743668;21188.364349069554;30233.848830755705;32580;32998.83500582482;33020;33171.50242746361;10536.103501369675;10766.623168189075;10950.452477376259;10976.286541374786;11115.83326250107;8018.719344822904;9009.513430284062;9147.313951453667;9176.357654964222;9305.06890329544;9389.765255868659;9466.715998519918;9543.711598934195;9583.760405989906;9591.184749296388;3730.906727331784;4075.735077219971;4109.760180701524;4123.690723195772;4140.896477588084;866.9089745576624;967.8337603188141;990.5790039233441;994.751312171957;1039.1323245090434;21955.058537828274;23968.880155599112;24195.39511512191;24252.75747242505;24295.63556546653;891.6299735609779;938.0806809326898;966.8356379415517;971.1890571372983;980.7057882635181;6935.237123916365;7155.713771449084;7189.964050179716;7317.5609463432365;7351.816204594821;823273;828496;838446;838690;858296.7085164534;159156;162968.92577685651;163204.36795632128;166188;170608;1325.6752095736606;1371.430856194688;1392.7005693775886;1397.3851505337618;1399.4052527675901;12744;12822.23066616006;13059;13235.27206003679;13313;56163.15755263756;58948.70525647516;59007.114893277496;64122.679386602766;64369;86575;88396.55801721207;89800;98044;98763;10759.838602420814;11228.508596561114;11576.305421674726;11708.70728231801;11757.470913808727;10653.733656658336;11494.195406321478;13174.60476185716;13296.601101966957;13593.59219223425;11431.428428578703;11468.254563453349;11669.416529173677;11706;11739.354335511287;8997.460152390877;10721.142308615448;10940.726481838017;10941.671749847517;10987.066099381642;9863.506824658882;11780.52877884875;12010.339431331011;12040;12128.211666241667;11736.882631173921;11777.293362398283;11858.584949526725;11899.107566932687;12043.819342710041;9646.42121472714;11424.885751142383;11659.708507287385;11780.116491263017;11857.881421185677 20:49:30 INFO - PROCESS | 1647 | |3;mootools.html;1361.8842398396137;1393.944242230311;1413;1617.4500669772283;1631.3882294139698;10932.672019839398;11495.965363117317;11520.596779112733;11669.533218671253;11894.167408281424;11218.831717524243;11304.773904521926;11361.091112710985;11377.2035957483;11379.658610241706;2474.604063349864;2506.3608779761225;2510.046182450666;2511.4977004599064;2515.962260566093;2367.183321753992;2451.9877400613063;2495.463475352799;2502.687164104487;2502.849829010256;2437.8049756019477;2476.616124500696;2483.6150396688454;2485.0184177249976;2486.9129580464687;2088.979110208894;2422.454947636785;2454.901803927841;2459.7294297627245;2466.087547607379;2473.196211231357;2479.690038745157;2499.5000999800113;2501.062101711858;2506.3608779761225;11280.2667826591;11419;11442.656720298404;11480.655580332603;11524.481398337095;5939.910901336483;6048.727807248684;6074.5747797654185;6126.846828829271;6145.477634401037;3156.921076973071;3695.3533131701843;3798.867039653599;3839.4816699745456;3854.69162467002;1226.6197478781605;1259.9118061735683;1273.4078653426202;1286.6783304173957;1320.4190156331183;13923.512677056255;14158.858411415857;15088;15531.844681553153;15711.900166988315;4783.043391321749;4866.683665561727;4911.189653707098;4917.459079501252;4920.21276595743;11905.702357441134;11930.940345298219;12059.397030148457;12062.638120856389;12073.758524829454;5741.540676745851;6031.849203769853;6032.939670603238;6034.24571928509;6036.2756469223095;5378.677279363173;5546.168074788814;5555.694436806015;5563.749631266603;5597.216389705444;3919.42474389284;4034.9798251008556;4058.939115913264;4108.055147316134;4114.506259248909;933.9766505837408;1079.0396547073112;1086.3536195963388;1089.6022951622601;1100.1418893263267;966.3863446711389;977.8582105594649;983.8868530118984;990.960361585543;996.4220751963844;6628.105205797252;6700.262971073178;6763.154605674291;6807.795766127024;6852.143482064742;1021.3055122516612;1054.3463052907246;1054.5373378636502;1062.1078294232882;1063.643854086031;4163.167366526707;4210.789460527023;4215.178040282116;4259.936100958488;4292.463442069741;12388.690282743004;13756.65608359799;13765.655858603615;13767.655808604864;13769.242691651854;10561.524731387106;13609.455621775216;13629.591112266648;13638.727225455435;13739.076529285909;10190.643327483502;10459.372437653765;10460.372377657364;10487.475626218658;10507;6835.384815366641;6863.039174515671;6888.862222755617;6964.616946067812;6984.441244700311;11094;11918.821217681569;12204.877951220375;12218.20581662189;12222.388880555758;11372.43137843121;11634.94182529082;12304.81542776877;12322.445489952794;12432.12975091735;9402.905970940203;9635.903640963501;9669.129778319984;9669.806603867743;9677.322587418814;10440.477976101012;10555.683329500127;10646.520906559068;10650.147988161083;10688.25182237236;9068.637254509746;9160.908390916273;9171.77070573241;9250.398724082914;9340.486273255035;8445.87331190045;8954.104589540993;8992;9047.411918225294;9074.137956894085;6788.86422271542;8134.308583770446;8147.633356498854;8150.184981501802;8158.83682326362;5012.824551140692;5084.669496482717;5910.024845900378;5941.910871336847;6005.6396616203165;5669.574781891292;5947.226860508279;6003.129546215687;6042.909356359746;6102.847428814138 20:49:30 INFO - PROCESS | 1647 | |4;prototype.html;390.2370864959002;392.51720383927767;392.8114505037582;399.83806558343883;400.1842432406999;4969.8260560880335;5437.118327286535;5448.264484294619;5502.697351645658;5527.447255274471;4240.809163587638;5210.478952104797;5258;5288.149147798269;5370.812021579236;4092.774897380643;4117.464729585165;4204.138151678899;4204.684648651348;4209.536950935387;3444.2939197464584;3864.574896761354;4200.600942910419;4205.369194620801;4221.936670949938;3930.2313958718546;4174.958250417488;4185.455890734201;4188.225178342016;4212.599803018708;3639.7452178347526;3644.489771432002;3753.080495278647;4024.738392004525;4113.197926404352;3336.1492819331156;3433.7081348085444;3534.734894882894;3570.0896271450874;3713.885834249714;4736.786844592001;5315;5328.4671532846405;5343.786248550054;5344.465553444663;4405.955940440586;4407.933880991788;4798.160321943646;4836.564709176156;4858.562729354339;5185.455527169631;5657.765182752831;5746.023176060038;5794.420557944214;5796.449337312949;3525.9950913989646;3619.963800361989;3839.155385815116;4039.8182081806094;4155.732501587015;5084.542391184774;5683.886322273532;5763.685880618661;5980.43185897339;6097.481714054311;4323.416338794285;4729.716217026989;4876.609871210295;5044.344235249394;5106.770195341219;5108.770105345268;5163.922541161885;5179.948200518022;5212.3223980883;5261.500157485033;4781.569658730661;4911.8280860170435;5251.044667329;5261.789528418897;5411.918821217685;4737.928931066037;5189.742462028954;5384.703841288767;5564.387917329091;5793.362730099686;1563;1634.3625985865522;1753.65803668286;1775.5383600264024;1787.2850859656241;1279.449836570265;1282.8652991435952;1283.5186804948144;1404.592668126232;1440.5462279381961;4656.115338085753;5172.637915345889;5329.214713071087;5580.888382232332;5605.8598535036135;5314.62797604164;6000.539778690716;6004.159417681528;6033.190042987087;6061.848453788691;4710.717356958525;4856.514348565186;5011.498850115032;5151.716655583904;5523.917141242885;2615.953618552594;2722.68689100752;2788.18767800927;3096.396202740489;3141.596678540918;5388.7905958557585;6130.540209484307;6171.290301615282;6192.783252586138;6231.189945307081;5510;5792.478676919098;5928.436798504222;6045.063015232646;6131.816045518642;5237;5430.945690543123;5626.774929002876;6205.379462053758;6297.953964194425;5028.446870844277;5397.730113494231;5593.692346920959;5645.322561292671;5969.791057312973;4857.7938560549865;5167.0547787760615;5327.680339179662;5498.532624726943;5844.707764611838;5090.959949649894;5210.557102646318;5546.889062218814;6047.0929360596265;6246.875062498633;4659.850864794354;4901.9019619608125;5049.495050495068;5052.72210028452;5052.747362631927;5271.4464981176425;5328.920066199088;5400.216968539618;5429;5558.749856256559;4786.7845946931775;5133.666311689728;5502.844402675483;5660.320761508646;5694.743736531949;5193.70199923044;5362.812301569426;5668.943310566841;5756.30924289088;5778.27771528559;4661.557152070548;4783.9475315430545;4916.70499770015;5571.415001424792;5710.17202505626;5038.571721403721;5237.134409461428;5805.593608447537;5848.356680765028;5853.619514731528;5048.444671086104;5049.798008079636;5148.995945790535;5576.888462230814;5697.800576979786 20:49:30 INFO - PROCESS | 1647 | |5;yui.html;812.926936443894;814.0760237130862;863.9179277968594;876.7720392697898;879.5250564695064;16114.952528085667;17194.226259818308;17214.91392543037;17227.655446891054;17301.740473892885;15818.209089545506;15977.360905563792;16030.919845400786;16038;16053.759193612075;987.185571903181;1023.4575674892319;1030.6547306652244;1035.4201647077623;1040.0795296162912;1032.4837581209395;1032.917366610673;1034.3173505486382;1035.5961175141701;1035.8498017787417;1063.8244689626201;1074.9946250268738;1080.983785243222;1081.4808891731973;1085.7285678580356;1072.2012100984778;1072.8337107748312;1074.0011789036193;1076.0799516413433;1076.8277075667895;793.4525177627419;793.5833687314149;799.588212070784;800.1518390506023;801.6633014134078;17144.57138571533;17173.055481948613;17874.46376608691;18117.909410452863;18281.634367312712;13953;14774.482893098797;14776.704465910621;14817.629559260997;14856.851431485655;11649.485566876336;11792;11803.822942655866;12010.579629713005;12122.575709850202;3823.0633494793674;3833.310004199243;3856.421536769507;3881.5342158940825;3998.620131087542;111419.44290278497;112363;113420;113824.43087784508;114170.42914785456;8691.043985161627;11286.266392684449;11440.599579014695;11481.770364592829;11644.825327620092;24213.63679544808;24242.272731818073;24300.513989720108;24328;24390.39024024378;8056.516609003478;8087.47431416956;8159.388045896581;8192.877106843402;8249.422540422232;4536.977315113403;4631.652626052992;4643.674942753974;4651.67438279324;4701.7414042228;2860.728230818069;3332.3002169544666;3420.504026916083;3430.1767575782133;3458.948115778265;584.231735268121;585.4701495146899;593.4154857465393;609.1106983803608;612.3233826621608;676.8849295619757;681.4139839737821;701.5545128843221;716.989245161323;734.522560335786;10615.946920265349;10834.677761739002;10913.454327283764;11013.803474099217;11063.668089957153;701.2671758012889;704;731.5135434935814;735.716749051619;751.4927423988786;5019.397672279277;5215.55667768244;5281.9207711884355;5289.20661900718;5305.920411193835;83028.75456868151;83218.66586897924;97771;103457.48271258746;106924;84991;89723;92949.5352523233;94757.5262123685;95163.57254641186;85980;87825.12174878297;89109.55445222862;90385;90722.54638726765;11256.380899050631;11353.659390218307;11401.486933087865;11552.6534203974;11632;42127.789361053;56252.71873640606;56918;57081;57612.1358179636;70714.64642676753;70845;73957;74456.88314675176;75423.62288188524;14571.271436428347;14679.339429725474;14701.92649036748;14824.62938426548;14831.851681483047;15312.540623781304;15765.605859853595;15916.124613146389;16383.426580069641;16602.08688522143;14265.57203283903;14464.493742718954;14742;15091.094534327976;15278.694426111639;14627.780583291047;14681.926590367408;14778.408863645334;14873.92563037178;15040.35398405702;13115.409806558919;13272.402741876831;13489.932550337186;14132.010759247165;14686.512770234958;10060.84908726355;10454.009228517862;10483.580656773644;10584.788304234025;11091.667249982513;10324.690259292232;10648.680539583826;11883.779601220442;11901;12081.154319197572 20:49:30 INFO - PROCESS | 1647 | __end_tp_report 20:49:30 INFO - PROCESS | 1647 | __start_cc_report 20:49:30 INFO - PROCESS | 1647 | _x_x_mozilla_cycle_collect,391 20:49:30 INFO - PROCESS | 1647 | __end_cc_report 20:49:30 INFO - PROCESS | 1647 | __startTimestamp1493956170071__endTimestamp 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | ------- Summary: start ------- 20:49:30 INFO - PROCESS | 1647 | Number of tests: 6 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#0] dojo.html Cycles:165 Average:6039.01 Median:6291.09 stddev:701.25 (11.1%) stddev-sans-first:675.13 20:49:30 INFO - PROCESS | 1647 | Values: 3526.2 3592.3 3598.0 3599.2 3603.3 6083.1 6137.0 6137.8 6147.4 6170.6 5029.0 6134.1 6203.5 6222.0 6240.8 5104.2 5486.0 5490.3 5496.1 5570.5 4551.7 5540.3 5549.2 5568.7 5589.6 5363.9 5370.7 5548.0 5563.0 5582.1 4461.5 5415.9 5439.3 5441.2 5447.6 5268.4 5408.1 5427.0 5448.1 5488.3 6165.1 6218.4 6248.0 6254.2 6263.2 6018.0 6181.8 6430.8 6475.0 6509.6 6261.7 6309.4 6400.6 6471.9 6580.8 6241.4 6287.8 6297.7 6297.8 6317.5 6712.3 6726.4 6743.4 6744.1 6783.9 6293.8 6310.9 6312.0 6439.4 6447.9 6504.2 6560.7 6567.6 6570.9 6587.0 6472.6 6501.5 6502.1 6502.6 6523.8 6319.9 6322.6 6435.8 6438.7 6500.8 5163.3 5185.0 5225.6 5267.6 5278.0 4495.7 4547.4 4569.1 4576.2 4698.8 6151.6 6232.9 6261.8 6279.2 6291.1 6552.5 6613.7 6628.4 6645.2 6647.4 6130.9 6158.4 6212.2 6325.4 6375.5 5777.0 5799.4 5809.0 5824.2 5863.5 6781.0 6817.9 6825.7 6826.1 6851.1 6312.3 6313.1 6538.4 6587.7 6634.4 6696.7 6711.9 6787.0 6824.8 6840.8 6465.2 6487.1 6487.2 6488.1 6504.6 5038.4 6241.5 6545.9 6575.8 6620.6 6275.7 6293.3 6302.0 6337.9 6339.2 6336.3 6392.8 6393.9 6402.6 6416.3 6160.1 6263.6 6300.7 6326.0 6326.5 6226.1 6235.8 6314.8 6337.9 6452.2 5292.6 6268.6 6289.4 6327.9 6335.7 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#1] ext.html Cycles:180 Average:57155.34 Median:30447.47 stddev:117305.55 (385.3%) stddev-sans-first:117558.07 20:49:30 INFO - PROCESS | 1647 | Values: 711.8 752.8 851.5 1000.4 1001.4 40140.2 41021.4 42882.8 42924.0 42950.4 40612.8 40945.0 40991.8 41019.0 41087.0 7559.0 8304.2 11269.6 11513.3 11726.0 11319.9 11394.4 11402.9 11409.1 11424.7 11410.3 11416.7 11429.0 11440.0 11451.0 9533.3 11370.2 11372.1 11420.4 11460.5 11111.3 11169.7 11171.0 11193.0 11202.8 36039.5 36091.5 36158.5 36186.7 36191.0 3870.8 3897.6 3908.5 3914.1 3920.2 26692.0 26926.7 27456.5 27550.6 27646.6 3205.9 3271.6 3289.7 3291.5 3296.2 352067.2 357686.0 357880.4 358817.0 359444.0 45931.3 46197.5 46220.3 46234.5 46279.8 47988.3 48067.5 48069.0 48187.8 48212.8 14588.6 14931.5 15122.6 15147.8 15148.0 12794.8 12843.6 12871.1 12894.5 12905.7 12217.9 12248.5 12285.7 12290.0 12422.4 4839.2 5650.4 5717.2 5729.4 5738.8 881.8 949.5 960.8 993.8 1021.1 29038.6 29303.3 29538.4 29623.6 30064.2 1052.1 1071.9 1091.8 1109.5 1111.4 14622.4 14918.5 15176.5 15344.1 15350.2 478491.0 481677.0 484601.0 485279.0 485348.6 51571.2 58721.4 59357.4 59488.1 59661.4 1483.6 1489.6 1507.4 1511.0 1520.3 27628.9 28144.6 29016.3 29174.3 29251.0 43359.6 43576.6 43589.3 43623.3 43726.8 468954.0 471771.0 473649.6 475520.0 476825.0 30342.8 30420.7 30474.2 30645.1 30896.2 40470.8 40527.6 41127.8 41453.8 41465.6 28575.4 29245.6 29951.4 30217.4 30385.0 27650.6 29191.0 29521.1 29574.4 30118.7 33813.8 34422.0 34547.8 35272.6 35836.8 22529.2 29187.7 29431.6 29462.4 30915.8 19779.6 22798.3 23876.6 23944.3 24301.8 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#2] jquery.html Cycles:180 Average:45910.86 Median:27653.09 stddev:139360.72 (504.0%) stddev-sans-first:139709.81 20:49:30 INFO - PROCESS | 1647 | Values: 424.5 437.1 437.2 445.4 446.8 20753.0 22184.9 22413.0 23500.6 23673.3 19517.8 22631.5 23539.8 24168.0 24361.5 6481.9 6606.1 6763.6 6982.2 7030.8 6779.1 6866.3 6886.1 6891.9 6917.2 6617.2 6935.3 6954.0 7012.0 7031.9 6265.2 6753.8 6887.2 6901.6 6988.5 5757.4 6830.3 6833.9 6843.6 6845.9 27641.4 27664.7 27755.7 27820.7 28460.9 2419.5 2700.4 3103.8 3112.2 3146.9 15071.6 16805.0 17219.4 17289.6 17552.2 2074.9 2114.3 2116.6 2117.9 2119.7 135785.0 140879.6 164758.0 166675.0 173729.0 16623.9 16875.7 18718.0 19898.5 21188.4 30233.8 32580.0 32998.8 33020.0 33171.5 10536.1 10766.6 10950.5 10976.3 11115.8 8018.7 9009.5 9147.3 9176.4 9305.1 9389.8 9466.7 9543.7 9583.8 9591.2 3730.9 4075.7 4109.8 4123.7 4140.9 866.9 967.8 990.6 994.8 1039.1 21955.1 23968.9 24195.4 24252.8 24295.6 891.6 938.1 966.8 971.2 980.7 6935.2 7155.7 7190.0 7317.6 7351.8 823273.0 828496.0 838446.0 838690.0 858296.7 159156.0 162968.9 163204.4 166188.0 170608.0 1325.7 1371.4 1392.7 1397.4 1399.4 12744.0 12822.2 13059.0 13235.3 13313.0 56163.2 58948.7 59007.1 64122.7 64369.0 86575.0 88396.6 89800.0 98044.0 98763.0 10759.8 11228.5 11576.3 11708.7 11757.5 10653.7 11494.2 13174.6 13296.6 13593.6 11431.4 11468.3 11669.4 11706.0 11739.4 8997.5 10721.1 10940.7 10941.7 10987.1 9863.5 11780.5 12010.3 12040.0 12128.2 11736.9 11777.3 11858.6 11899.1 12043.8 9646.4 11424.9 11659.7 11780.1 11857.9 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#3] mootools.html Cycles:180 Average:6832.86 Median:2500.28 stddev:4193.04 (167.7%) stddev-sans-first:4184.65 20:49:30 INFO - PROCESS | 1647 | Values: 1361.9 1393.9 1413.0 1617.5 1631.4 10932.7 11496.0 11520.6 11669.5 11894.2 11218.8 11304.8 11361.1 11377.2 11379.7 2474.6 2506.4 2510.0 2511.5 2516.0 2367.2 2452.0 2495.5 2502.7 2502.8 2437.8 2476.6 2483.6 2485.0 2486.9 2089.0 2422.5 2454.9 2459.7 2466.1 2473.2 2479.7 2499.5 2501.1 2506.4 11280.3 11419.0 11442.7 11480.7 11524.5 5939.9 6048.7 6074.6 6126.8 6145.5 3156.9 3695.4 3798.9 3839.5 3854.7 1226.6 1259.9 1273.4 1286.7 1320.4 13923.5 14158.9 15088.0 15531.8 15711.9 4783.0 4866.7 4911.2 4917.5 4920.2 11905.7 11930.9 12059.4 12062.6 12073.8 5741.5 6031.8 6032.9 6034.2 6036.3 5378.7 5546.2 5555.7 5563.7 5597.2 3919.4 4035.0 4058.9 4108.1 4114.5 934.0 1079.0 1086.4 1089.6 1100.1 966.4 977.9 983.9 991.0 996.4 6628.1 6700.3 6763.2 6807.8 6852.1 1021.3 1054.3 1054.5 1062.1 1063.6 4163.2 4210.8 4215.2 4259.9 4292.5 12388.7 13756.7 13765.7 13767.7 13769.2 10561.5 13609.5 13629.6 13638.7 13739.1 10190.6 10459.4 10460.4 10487.5 10507.0 6835.4 6863.0 6888.9 6964.6 6984.4 11094.0 11918.8 12204.9 12218.2 12222.4 11372.4 11634.9 12304.8 12322.4 12432.1 9402.9 9635.9 9669.1 9669.8 9677.3 10440.5 10555.7 10646.5 10650.1 10688.3 9068.6 9160.9 9171.8 9250.4 9340.5 8445.9 8954.1 8992.0 9047.4 9074.1 6788.9 8134.3 8147.6 8150.2 8158.8 5012.8 5084.7 5910.0 5941.9 6005.6 5669.6 5947.2 6003.1 6042.9 6102.8 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#4] prototype.html Cycles:180 Average:4709.23 Median:5165.49 stddev:1331.41 (25.8%) stddev-sans-first:1295.08 20:49:30 INFO - PROCESS | 1647 | Values: 390.2 392.5 392.8 399.8 400.2 4969.8 5437.1 5448.3 5502.7 5527.4 4240.8 5210.5 5258.0 5288.1 5370.8 4092.8 4117.5 4204.1 4204.7 4209.5 3444.3 3864.6 4200.6 4205.4 4221.9 3930.2 4175.0 4185.5 4188.2 4212.6 3639.7 3644.5 3753.1 4024.7 4113.2 3336.1 3433.7 3534.7 3570.1 3713.9 4736.8 5315.0 5328.5 5343.8 5344.5 4406.0 4407.9 4798.2 4836.6 4858.6 5185.5 5657.8 5746.0 5794.4 5796.4 3526.0 3620.0 3839.2 4039.8 4155.7 5084.5 5683.9 5763.7 5980.4 6097.5 4323.4 4729.7 4876.6 5044.3 5106.8 5108.8 5163.9 5179.9 5212.3 5261.5 4781.6 4911.8 5251.0 5261.8 5411.9 4737.9 5189.7 5384.7 5564.4 5793.4 1563.0 1634.4 1753.7 1775.5 1787.3 1279.4 1282.9 1283.5 1404.6 1440.5 4656.1 5172.6 5329.2 5580.9 5605.9 5314.6 6000.5 6004.2 6033.2 6061.8 4710.7 4856.5 5011.5 5151.7 5523.9 2616.0 2722.7 2788.2 3096.4 3141.6 5388.8 6130.5 6171.3 6192.8 6231.2 5510.0 5792.5 5928.4 6045.1 6131.8 5237.0 5430.9 5626.8 6205.4 6298.0 5028.4 5397.7 5593.7 5645.3 5969.8 4857.8 5167.1 5327.7 5498.5 5844.7 5091.0 5210.6 5546.9 6047.1 6246.9 4659.9 4901.9 5049.5 5052.7 5052.7 5271.4 5328.9 5400.2 5429.0 5558.7 4786.8 5133.7 5502.8 5660.3 5694.7 5193.7 5362.8 5668.9 5756.3 5778.3 4661.6 4783.9 4916.7 5571.4 5710.2 5038.6 5237.1 5805.6 5848.4 5853.6 5048.4 5049.8 5149.0 5576.9 5697.8 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - PROCESS | 1647 | [#5] yui.html Cycles:180 Average:21640.75 Median:16873.33 stddev:30480.22 (180.6%) stddev-sans-first:30525.60 20:49:30 INFO - PROCESS | 1647 | Values: 812.9 814.1 863.9 876.8 879.5 16115.0 17194.2 17214.9 17227.7 17301.7 15818.2 15977.4 16030.9 16038.0 16053.8 987.2 1023.5 1030.7 1035.4 1040.1 1032.5 1032.9 1034.3 1035.6 1035.8 1063.8 1075.0 1081.0 1081.5 1085.7 1072.2 1072.8 1074.0 1076.1 1076.8 793.5 793.6 799.6 800.2 801.7 17144.6 17173.1 17874.5 18117.9 18281.6 13953.0 14774.5 14776.7 14817.6 14856.9 11649.5 11792.0 11803.8 12010.6 12122.6 3823.1 3833.3 3856.4 3881.5 3998.6 111419.4 112363.0 113420.0 113824.4 114170.4 8691.0 11286.3 11440.6 11481.8 11644.8 24213.6 24242.3 24300.5 24328.0 24390.4 8056.5 8087.5 8159.4 8192.9 8249.4 4537.0 4631.7 4643.7 4651.7 4701.7 2860.7 3332.3 3420.5 3430.2 3458.9 584.2 585.5 593.4 609.1 612.3 676.9 681.4 701.6 717.0 734.5 10615.9 10834.7 10913.5 11013.8 11063.7 701.3 704.0 731.5 735.7 751.5 5019.4 5215.6 5281.9 5289.2 5305.9 83028.8 83218.7 97771.0 103457.5 106924.0 84991.0 89723.0 92949.5 94757.5 95163.6 85980.0 87825.1 89109.6 90385.0 90722.5 11256.4 11353.7 11401.5 11552.7 11632.0 42127.8 56252.7 56918.0 57081.0 57612.1 70714.6 70845.0 73957.0 74456.9 75423.6 14571.3 14679.3 14701.9 14824.6 14831.9 15312.5 15765.6 15916.1 16383.4 16602.1 14265.6 14464.5 14742.0 15091.1 15278.7 14627.8 14681.9 14778.4 14873.9 15040.4 13115.4 13272.4 13489.9 14132.0 14686.5 10060.8 10454.0 10483.6 10584.8 11091.7 10324.7 10648.7 11883.8 11901.0 12081.2 20:49:30 INFO - PROCESS | 1647 | -------- Summary: end -------- 20:49:30 INFO - PROCESS | 1647 | 20:49:30 INFO - TEST-INFO | 1647: exit 0 20:49:30 INFO - TEST-OK | dromaeo_css | took 1116061ms 20:49:30 INFO - TEST-START | kraken 20:49:30 INFO - operating with platform_type : mac_ 20:49:30 INFO - Initialising browser for kraken test... 20:49:30 INFO - TEST-INFO | started process 1654 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpLvmlC3/profile http://localhost:49237/getInfo.html) 20:49:33 INFO - PROCESS | 1654 | 2017-05-04 20:49:33.152 plugin-container[1656:10449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4643, name = 'com.apple.tsm.portname' 20:49:33 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:49:33 INFO - PROCESS | 1654 | 2017-05-04 20:49:33.152 plugin-container[1656:10449] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x891f, name = 'com.apple.CFPasteboardClient' 20:49:33 INFO - PROCESS | 1654 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:49:33 INFO - PROCESS | 1654 | 2017-05-04 20:49:33.152 plugin-container[1656:10449] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:49:33 INFO - PROCESS | 1654 | __metrics Screen width/height:1600/1200 20:49:33 INFO - PROCESS | 1654 | colorDepth:24 20:49:33 INFO - PROCESS | 1654 | Browser inner width/height: 1024/689 20:49:33 INFO - PROCESS | 1654 | __metrics 20:49:33 ERROR - PROCESS | 1654 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3371: TypeError: this.tabs is undefined 20:49:33 INFO - TEST-INFO | 1654: exit 0 20:49:33 INFO - Browser initialized. 20:49:33 INFO - Running cycle 1/1 for kraken test... 20:49:33 INFO - TEST-INFO | started process 1657 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/T/tmpLvmlC3/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 20:49:36 INFO - PROCESS | 1657 | 2017-05-04 20:49:36.516 plugin-container[1658:10572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4547, name = 'com.apple.tsm.portname' 20:49:36 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:49:36 INFO - PROCESS | 1657 | 2017-05-04 20:49:36.517 plugin-container[1658:10572] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 20:49:36 INFO - PROCESS | 1657 | See /usr/include/servers/bootstrap_defs.h for the error codes. 20:49:36 INFO - PROCESS | 1657 | 2017-05-04 20:49:36.517 plugin-container[1658:10572] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 20:49:46 INFO - PROCESS | 1657 | RSS: Main: 174075904 20:49:46 INFO - PROCESS | 1657 | 20:49:49 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection) 20:49:50 INFO - PROCESS | 1657 | RSS: Main: 187531264 20:49:50 INFO - PROCESS | 1657 | 20:49:52 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-dft) 20:49:53 INFO - PROCESS | 1657 | RSS: Main: 195571712 20:49:53 INFO - PROCESS | 1657 | 20:49:56 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-fft) 20:49:57 INFO - PROCESS | 1657 | RSS: Main: 199495680 20:49:57 INFO - PROCESS | 1657 | 20:49:59 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator) 20:50:00 INFO - PROCESS | 1657 | RSS: Main: 200810496 20:50:00 INFO - PROCESS | 1657 | 20:50:02 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur) 20:50:02 INFO - PROCESS | 1657 | RSS: Main: 201187328 20:50:02 INFO - PROCESS | 1657 | 20:50:05 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom) 20:50:06 INFO - PROCESS | 1657 | RSS: Main: 205045760 20:50:06 INFO - PROCESS | 1657 | 20:50:09 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate) 20:50:10 INFO - PROCESS | 1657 | RSS: Main: 204455936 20:50:10 INFO - PROCESS | 1657 | 20:50:13 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial) 20:50:13 INFO - PROCESS | 1657 | RSS: Main: 204648448 20:50:13 INFO - PROCESS | 1657 | 20:50:15 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox) 20:50:16 INFO - PROCESS | 1657 | RSS: Main: 204333056 20:50:16 INFO - PROCESS | 1657 | 20:50:18 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes) 20:50:18 INFO - PROCESS | 1657 | RSS: Main: 204369920 20:50:18 INFO - PROCESS | 1657 | 20:50:20 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm) 20:50:21 INFO - PROCESS | 1657 | RSS: Main: 204382208 20:50:21 INFO - PROCESS | 1657 | 20:50:24 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 20:50:25 INFO - PROCESS | 1657 | RSS: Main: 204800000 20:50:25 INFO - PROCESS | 1657 | 20:50:27 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 20:50:28 INFO - PROCESS | 1657 | RSS: Main: 204517376 20:50:28 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | Cycle 1(1): loaded http://localhost:49237/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49237/tests/kraken/driver.html?testName=ai-astar) 20:50:30 INFO - PROCESS | 1657 | RSS: Main: 206295040 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | __start_tp_report 20:50:30 INFO - PROCESS | 1657 | _x_x_mozilla_page_load 20:50:30 INFO - PROCESS | 1657 | _x_x_mozilla_page_load_details 20:50:30 INFO - PROCESS | 1657 | |i|pagename|runs| 20:50:30 INFO - PROCESS | 1657 | |0;ai-astar;111;122;123;121;122;122;120;121;129;152 20:50:30 INFO - PROCESS | 1657 | |1;audio-beat-detection;143;111;112;147;111;109;117;127;129;110 20:50:30 INFO - PROCESS | 1657 | |2;audio-dft;198;204;198;197;222;219;275;204;194;198 20:50:30 INFO - PROCESS | 1657 | |3;audio-fft;74;52;66;116;90;55;85;86;99;84 20:50:30 INFO - PROCESS | 1657 | |4;audio-oscillator;84;70;85;74;72;72;82;70;72;84 20:50:30 INFO - PROCESS | 1657 | |5;imaging-gaussian-blur;89;90;95;89;91;83;90;90;90;88 20:50:30 INFO - PROCESS | 1657 | |6;imaging-darkroom;122;159;140;96;97;99;96;112;153;116 20:50:30 INFO - PROCESS | 1657 | |7;imaging-desaturate;120;138;145;93;125;139;90;90;92;98 20:50:30 INFO - PROCESS | 1657 | |8;json-parse-financial;65;64;65;69;68;69;69;68;69;68 20:50:30 INFO - PROCESS | 1657 | |9;json-stringify-tinderbox;57;73;47;71;87;86;85;86;86;58 20:50:30 INFO - PROCESS | 1657 | |10;stanford-crypto-aes;84;85;88;83;83;82;87;88;90;87 20:50:30 INFO - PROCESS | 1657 | |11;stanford-crypto-ccm;173;145;140;134;131;136;133;139;183;135 20:50:30 INFO - PROCESS | 1657 | |12;stanford-crypto-pbkdf2;168;165;163;166;165;165;166;167;170;165 20:50:30 INFO - PROCESS | 1657 | |13;stanford-crypto-sha256-iterative;59;82;87;65;65;65;65;64;64;65 20:50:30 INFO - PROCESS | 1657 | __end_tp_report 20:50:30 INFO - PROCESS | 1657 | __start_cc_report 20:50:30 INFO - PROCESS | 1657 | _x_x_mozilla_cycle_collect,1001 20:50:30 INFO - PROCESS | 1657 | __end_cc_report 20:50:30 INFO - PROCESS | 1657 | __startTimestamp1493956230578__endTimestamp 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | ------- Summary: start ------- 20:50:30 INFO - PROCESS | 1657 | Number of tests: 14 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#0] ai-astar Cycles:10 Average:124.30 Median:122.00 stddev:10.67 (8.7%) stddev-sans-first:10.17 20:50:30 INFO - PROCESS | 1657 | Values: 111.0 122.0 123.0 121.0 122.0 122.0 120.0 121.0 129.0 152.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#1] audio-beat-detection Cycles:10 Average:121.60 Median:122.00 stddev:14.21 (11.7%) stddev-sans-first:12.79 20:50:30 INFO - PROCESS | 1657 | Values: 143.0 111.0 112.0 147.0 111.0 109.0 117.0 127.0 129.0 110.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#2] audio-dft Cycles:10 Average:210.90 Median:204.00 stddev:24.43 (12.0%) stddev-sans-first:25.46 20:50:30 INFO - PROCESS | 1657 | Values: 198.0 204.0 198.0 197.0 222.0 219.0 275.0 204.0 194.0 198.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#3] audio-fft Cycles:10 Average:80.70 Median:84.50 stddev:19.64 (23.2%) stddev-sans-first:20.68 20:50:30 INFO - PROCESS | 1657 | Values: 74.0 52.0 66.0 116.0 90.0 55.0 85.0 86.0 99.0 84.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#4] audio-oscillator Cycles:10 Average:76.50 Median:78.00 stddev:6.38 (8.2%) stddev-sans-first:6.16 20:50:30 INFO - PROCESS | 1657 | Values: 84.0 70.0 85.0 74.0 72.0 72.0 82.0 70.0 72.0 84.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:2.95 (3.3%) stddev-sans-first:3.13 20:50:30 INFO - PROCESS | 1657 | Values: 89.0 90.0 95.0 89.0 91.0 83.0 90.0 90.0 90.0 88.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#6] imaging-darkroom Cycles:10 Average:119.00 Median:127.50 stddev:24.05 (18.9%) stddev-sans-first:25.49 20:50:30 INFO - PROCESS | 1657 | Values: 122.0 159.0 140.0 96.0 97.0 99.0 96.0 112.0 153.0 116.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#7] imaging-desaturate Cycles:10 Average:113.00 Median:90.00 stddev:22.71 (25.2%) stddev-sans-first:23.95 20:50:30 INFO - PROCESS | 1657 | Values: 120.0 138.0 145.0 93.0 125.0 139.0 90.0 90.0 92.0 98.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#8] json-parse-financial Cycles:10 Average:67.40 Median:68.50 stddev:1.96 (2.9%) stddev-sans-first:1.87 20:50:30 INFO - PROCESS | 1657 | Values: 65.0 64.0 65.0 69.0 68.0 69.0 69.0 68.0 69.0 68.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#9] json-stringify-tinderbox Cycles:10 Average:73.60 Median:85.50 stddev:14.92 (17.5%) stddev-sans-first:14.57 20:50:30 INFO - PROCESS | 1657 | Values: 57.0 73.0 47.0 71.0 87.0 86.0 85.0 86.0 86.0 58.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#10] stanford-crypto-aes Cycles:10 Average:85.70 Median:87.00 stddev:2.67 (3.1%) stddev-sans-first:2.76 20:50:30 INFO - PROCESS | 1657 | Values: 84.0 85.0 88.0 83.0 83.0 82.0 87.0 88.0 90.0 87.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#11] stanford-crypto-ccm Cycles:10 Average:144.90 Median:139.50 stddev:18.05 (12.9%) stddev-sans-first:16.02 20:50:30 INFO - PROCESS | 1657 | Values: 173.0 145.0 140.0 134.0 131.0 136.0 133.0 139.0 183.0 135.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.00 stddev:1.94 (1.2%) stddev-sans-first:1.92 20:50:30 INFO - PROCESS | 1657 | Values: 168.0 165.0 163.0 166.0 165.0 165.0 166.0 167.0 170.0 165.0 20:50:30 INFO - PROCESS | 1657 | 20:50:30 INFO - PROCESS | 1657 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:68.10 Median:65.00 stddev:8.91 (13.7%) stddev-sans-first:8.82 20:50:30 INFO - PROCESS | 1657 | Values: 59.0 82.0 87.0 65.0 65.0 65.0 65.0 64.0 64.0 65.0 20:50:30 INFO - PROCESS | 1657 | -------- Summary: end -------- 20:50:30 INFO - PROCESS | 1657 | 20:50:31 INFO - TEST-INFO | 1657: exit 0 20:50:31 INFO - TEST-OK | kraken | took 60760ms 20:50:31 INFO - SUITE-END | took 1176s 20:50:31 INFO - Completed test suite (00:19:37) 20:50:31 INFO - javascript benchmark 20:50:31 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7891.970528782991, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3526.1889765353967, 3592.2635859648776, 3597.982010089951, 3599.208174201675, 3603.2793441311746, 6083.117947897552, 6136.969315153424, 6137.846553836156, 6147.415995480422, 6170.629762214259, 5028.974855125728, 6134.0798880167995, 6203.503719702424, 6222.0, 6240.750369985195, 5104.2088476286235, 5486.0, 5490.313710786152, 5496.0931446311315, 5570.498655121039, 4551.658625603077, 5540.252065971103, 5549.19536667183, 5568.6748311274705, 5589.636673616223, 5363.865903352428, 5370.731463426813, 5548.0, 5563.0, 5582.078956972115, 4461.531539188403, 5415.891682166354, 5439.292891924025, 5441.211024401479, 5447.618666693333, 5268.394134674523, 5408.080626293539, 5427.0, 5448.101063324547, 5488.313960754906, 6165.136880836686, 6218.378162183791, 6247.9687601562155, 6254.249490061176, 6263.185785847811, 6018.0, 6181.752729890799, 6430.807075787734, 6475.0, 6509.6419696916655, 6261.686915654244, 6309.400606942333, 6400.647964361959, 6471.935280647181, 6580.835479113012, 6241.375862413768, 6287.779927702509, 6297.685115744194, 6297.811065668083, 6317.463015643676, 6712.295209003082, 6726.428253598499, 6743.3593808589085, 6744.089547911068, 6783.864322713617, 6293.811185664437, 6310.873782524323, 6311.958526843111, 6439.356064393617, 6447.903281450782, 6504.1869766279215, 6560.737570497126, 6567.638779867153, 6570.868582628416, 6587.044878492592, 6472.579282346633, 6501.544891857525, 6502.057201705727, 6502.60984340941, 6523.836904077436, 6319.873602527924, 6322.620642761357, 6435.839104022343, 6438.742450301936, 6500.837479062967, 5163.251328557338, 5185.0, 5225.608079394061, 5267.631265811357, 5278.0, 4495.685302028826, 4547.386102876136, 4569.109023740339, 4576.244919588231, 4698.812047518061, 6151.569390142737, 6232.937670623236, 6261.812145635638, 6279.215098112736, 6291.087792270096, 6552.541322107502, 6613.702383392759, 6628.403443689995, 6645.235797883209, 6647.434968027772, 6130.887362650998, 6158.353372895871, 6212.223472065992, 6325.399087086719, 6375.458086062644, 5776.989026920272, 5799.449052340021, 5809.0, 5824.155497452845, 5863.472287494146, 6780.966095169492, 6817.863642727118, 6825.658717064028, 6826.146731658543, 6851.075104860979, 6312.3056463788025, 6313.116163736989, 6538.411542961157, 6587.670616469253, 6634.402903738686, 6696.665166741741, 6711.899321510084, 6787.0, 6824.829379265558, 6840.794776156723, 6465.1595292611655, 6487.124238227968, 6487.18910136233, 6488.091667166506, 6504.577202481823, 5038.395392552917, 6241.500679945684, 6545.869082618226, 6575.80272591823, 6620.569662971891, 6275.717592708248, 6293.339199383999, 6301.968490157703, 6337.8732425352155, 6339.207599050119, 6336.271328797247, 6392.840178995562, 6393.87212255743, 6402.6158430494315, 6416.262129854939, 6160.13758073861, 6263.592866463848, 6300.684965751602, 6325.96837015812, 6326.493880489642, 6226.097215903578, 6235.812925612239, 6314.778982735582, 6337.873242535031, 6452.161219041494, 5292.576593872404, 6268.561200715906, 6289.371062893674, 6327.873442531031, 6335.714892829926], "value": 5994.677140444967, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [711.8327193109618, 752.7579493835171, 851.4550687559961, 1000.4347543637849, 1001.4291853643417, 40140.19719605606, 41021.3846792298, 42882.78558607202, 42924.0, 42950.35574466385, 40612.79693601535, 40945.0, 40991.79504102475, 41019.0, 41087.0, 7559.0, 8304.211099945496, 11269.605563805268, 11513.251638643504, 11726.0, 11319.943400282988, 11394.373309468017, 11402.885971140266, 11409.144314176428, 11424.71438214043, 11410.315381077162, 11416.71458213543, 11429.02853257474, 11440.0, 11451.0, 9533.332666713333, 11370.204085713962, 11372.090232781358, 11420.371879546621, 11460.541578336857, 11111.277766945122, 11169.72075698114, 11171.0, 11193.048590869786, 11202.775944481145, 36039.4594081089, 36091.45862812034, 36158.45762313568, 36186.733464328885, 36191.0, 3870.845166193377, 3897.610238976108, 3908.530976282836, 3914.1388894443176, 3920.2355540669582, 26692.0, 26926.730732692813, 27456.45087098267, 27550.5867411989, 27646.585301220497, 3205.9198520036953, 3271.6401195868434, 3289.6838070903923, 3291.53918451417, 3296.225387034045, 352067.2396638026, 357686.0, 357880.4211957873, 358817.0, 359444.0, 45931.311030334575, 46197.53802461999, 46220.306695399595, 46234.537654623695, 46279.76860115678, 47988.280175797394, 48067.519324807006, 48069.0, 48187.759061204466, 48212.7589362058, 14588.562343129512, 14931.477398291008, 15122.621934451727, 15147.772783408258, 15148.01537900033, 12794.808077878839, 12843.550475733491, 12871.099023068482, 12894.48422063107, 12905.677358066123, 12217.877821221851, 12248.510059597695, 12285.69285767845, 12289.955353794849, 12422.37888105591, 4839.249916262985, 5650.4402226596985, 5717.2281741965135, 5729.427057294237, 5738.77044918207, 881.8192270584515, 949.5252373813094, 960.8126415349079, 993.821112199811, 1021.1116328793848, 29038.564421533694, 29303.267418314288, 29538.409231815673, 29623.555646665318, 30064.24839379033, 1052.137247457093, 1071.919606029551, 1091.8143915534379, 1109.4619109731773, 1111.360967443723, 14622.415103395957, 14918.477853275082, 15176.544703658908, 15344.079355238722, 15350.155741434328, 478491.0, 481677.0, 484601.0, 485279.0, 485348.57325713144, 51571.22643160356, 58721.412785871595, 59357.361050700114, 59488.10767838486, 59661.403385966456, 1483.5549335199396, 1489.63503941535, 1507.4422463688286, 1510.9924450377898, 1520.3158578639657, 27628.894844206006, 28144.577831332135, 29016.274593135342, 29174.305545205043, 29250.976215832343, 43359.566404336816, 43576.56423435852, 43589.34615980699, 43623.345649815914, 43726.78136609297, 468954.0, 471771.0, 473649.631751839, 475520.0, 476825.0, 30342.84828575843, 30420.695793041785, 30474.238144046576, 30645.08064758061, 30896.227594310323, 40470.798569726336, 40527.59472405238, 41127.794361028005, 41453.79273103615, 41465.58534414617, 28575.42849143047, 29245.561316579842, 29951.400971980875, 30217.395652087274, 30385.0, 27650.585241220997, 29191.0, 29521.114366569036, 29574.408511829213, 30118.69881301159, 33813.830930845186, 34421.96734097981, 34547.82726086354, 35272.647273526934, 35836.82081589576, 22529.159906925946, 29187.7081229185, 29431.558526622546, 29462.41075178527, 30915.845420772752, 19779.604407912047, 22798.31605051851, 23876.641850372605, 23944.28167154988, 24301.784910754744], "value": 18896.21931279694, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [424.52241228617805, 437.11484244405074, 437.22610978567934, 445.3942638012303, 446.80787261477565, 20753.0, 22184.88907555462, 22412.991415386306, 23500.647490287618, 23673.289801305946, 19517.804821951813, 22631.54736905261, 23539.76460235397, 24168.03327866883, 24361.512769744593, 6481.898077326842, 6606.14120164378, 6763.594184348943, 6982.197047339545, 7030.789076327693, 6779.118714567099, 6866.313368663119, 6886.10480637519, 6891.896621550681, 6917.239103698589, 6617.239017512999, 6935.306469353075, 6954.026436298922, 7011.964940175292, 7031.929680703179, 6265.185525881606, 6753.797386078401, 6887.242403335629, 6901.6204108774, 6988.54574452664, 5757.366689664133, 6830.282820303896, 6833.897491537631, 6843.589384636888, 6845.904655255135, 27641.447171056465, 27664.723352766418, 27755.722442775517, 27820.721792782217, 28460.861565537354, 2419.4677171022345, 2700.351915540276, 3103.8137711737368, 3112.1908303840937, 3146.889858854929, 15071.623209419742, 16805.0, 17219.397321093827, 17289.567760805956, 17552.210150543127, 2074.8962551872346, 2114.2600089968387, 2116.5766846630736, 2117.8729276243475, 2119.6714509250937, 135785.0, 140879.5912040877, 164758.0, 166675.0, 173728.99999999875, 16623.91688041552, 16875.746863797052, 18717.97051162172, 19898.50253743668, 21188.364349069554, 30233.848830755705, 32580.0, 32998.83500582482, 33020.0, 33171.50242746361, 10536.103501369675, 10766.623168189075, 10950.452477376259, 10976.286541374786, 11115.83326250107, 8018.719344822904, 9009.513430284062, 9147.313951453667, 9176.357654964222, 9305.06890329544, 9389.765255868659, 9466.715998519918, 9543.711598934195, 9583.760405989906, 9591.184749296388, 3730.906727331784, 4075.735077219971, 4109.760180701524, 4123.690723195772, 4140.896477588084, 866.9089745576624, 967.8337603188141, 990.5790039233441, 994.751312171957, 1039.1323245090434, 21955.058537828274, 23968.880155599112, 24195.39511512191, 24252.75747242505, 24295.63556546653, 891.6299735609779, 938.0806809326898, 966.8356379415517, 971.1890571372983, 980.7057882635181, 6935.237123916365, 7155.713771449084, 7189.964050179716, 7317.5609463432365, 7351.816204594821, 823273.0, 828496.0, 838446.0, 838690.0, 858296.7085164534, 159156.0, 162968.92577685651, 163204.36795632128, 166188.0, 170608.0, 1325.6752095736606, 1371.430856194688, 1392.7005693775886, 1397.3851505337618, 1399.4052527675901, 12744.0, 12822.23066616006, 13059.0, 13235.27206003679, 13313.0, 56163.15755263756, 58948.70525647516, 59007.114893277496, 64122.679386602766, 64369.0, 86575.0, 88396.55801721207, 89800.0, 98044.0, 98763.0, 10759.838602420814, 11228.508596561114, 11576.305421674726, 11708.70728231801, 11757.470913808727, 10653.733656658336, 11494.195406321478, 13174.60476185716, 13296.601101966957, 13593.59219223425, 11431.428428578703, 11468.254563453349, 11669.416529173677, 11706.0, 11739.354335511287, 8997.460152390877, 10721.142308615448, 10940.726481838017, 10941.671749847517, 10987.066099381642, 9863.506824658882, 11780.52877884875, 12010.339431331011, 12040.0, 12128.211666241667, 11736.882631173921, 11777.293362398283, 11858.584949526725, 11899.107566932687, 12043.819342710041, 9646.42121472714, 11424.885751142383, 11659.708507287385, 11780.116491263017, 11857.881421185677], "value": 11571.168793975652, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1361.8842398396137, 1393.944242230311, 1413.0, 1617.4500669772283, 1631.3882294139698, 10932.672019839398, 11495.965363117317, 11520.596779112733, 11669.533218671253, 11894.167408281424, 11218.831717524243, 11304.773904521926, 11361.091112710985, 11377.2035957483, 11379.658610241706, 2474.604063349864, 2506.3608779761225, 2510.046182450666, 2511.4977004599064, 2515.962260566093, 2367.183321753992, 2451.9877400613063, 2495.463475352799, 2502.687164104487, 2502.849829010256, 2437.8049756019477, 2476.616124500696, 2483.6150396688454, 2485.0184177249976, 2486.9129580464687, 2088.979110208894, 2422.454947636785, 2454.901803927841, 2459.7294297627245, 2466.087547607379, 2473.196211231357, 2479.690038745157, 2499.5000999800113, 2501.062101711858, 2506.3608779761225, 11280.2667826591, 11419.0, 11442.656720298404, 11480.655580332603, 11524.481398337095, 5939.910901336483, 6048.727807248684, 6074.5747797654185, 6126.846828829271, 6145.477634401037, 3156.921076973071, 3695.3533131701843, 3798.867039653599, 3839.4816699745456, 3854.69162467002, 1226.6197478781605, 1259.9118061735683, 1273.4078653426202, 1286.6783304173957, 1320.4190156331183, 13923.512677056255, 14158.858411415857, 15088.0, 15531.844681553153, 15711.900166988315, 4783.043391321749, 4866.683665561727, 4911.189653707098, 4917.459079501252, 4920.21276595743, 11905.702357441134, 11930.940345298219, 12059.397030148457, 12062.638120856389, 12073.758524829454, 5741.540676745851, 6031.849203769853, 6032.939670603238, 6034.24571928509, 6036.2756469223095, 5378.677279363173, 5546.168074788814, 5555.694436806015, 5563.749631266603, 5597.216389705444, 3919.42474389284, 4034.9798251008556, 4058.939115913264, 4108.055147316134, 4114.506259248909, 933.9766505837408, 1079.0396547073112, 1086.3536195963388, 1089.6022951622601, 1100.1418893263267, 966.3863446711389, 977.8582105594649, 983.8868530118984, 990.960361585543, 996.4220751963844, 6628.105205797252, 6700.262971073178, 6763.154605674291, 6807.795766127024, 6852.143482064742, 1021.3055122516612, 1054.3463052907246, 1054.5373378636502, 1062.1078294232882, 1063.643854086031, 4163.167366526707, 4210.789460527023, 4215.178040282116, 4259.936100958488, 4292.463442069741, 12388.690282743004, 13756.65608359799, 13765.655858603615, 13767.655808604864, 13769.242691651854, 10561.524731387106, 13609.455621775216, 13629.591112266648, 13638.727225455435, 13739.076529285909, 10190.643327483502, 10459.372437653765, 10460.372377657364, 10487.475626218658, 10507.0, 6835.384815366641, 6863.039174515671, 6888.862222755617, 6964.616946067812, 6984.441244700311, 11094.0, 11918.821217681569, 12204.877951220375, 12218.20581662189, 12222.388880555758, 11372.43137843121, 11634.94182529082, 12304.81542776877, 12322.445489952794, 12432.12975091735, 9402.905970940203, 9635.903640963501, 9669.129778319984, 9669.806603867743, 9677.322587418814, 10440.477976101012, 10555.683329500127, 10646.520906559068, 10650.147988161083, 10688.25182237236, 9068.637254509746, 9160.908390916273, 9171.77070573241, 9250.398724082914, 9340.486273255035, 8445.87331190045, 8954.104589540993, 8992.0, 9047.411918225294, 9074.137956894085, 6788.86422271542, 8134.308583770446, 8147.633356498854, 8150.184981501802, 8158.83682326362, 5012.824551140692, 5084.669496482717, 5910.024845900378, 5941.910871336847, 6005.6396616203165, 5669.574781891292, 5947.226860508279, 6003.129546215687, 6042.909356359746, 6102.847428814138], "value": 5245.978437473594, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [390.2370864959002, 392.51720383927767, 392.8114505037582, 399.83806558343883, 400.1842432406999, 4969.8260560880335, 5437.118327286535, 5448.264484294619, 5502.697351645658, 5527.447255274471, 4240.809163587638, 5210.478952104797, 5258.0, 5288.149147798269, 5370.812021579236, 4092.774897380643, 4117.464729585165, 4204.138151678899, 4204.684648651348, 4209.536950935387, 3444.2939197464584, 3864.574896761354, 4200.600942910419, 4205.369194620801, 4221.936670949938, 3930.2313958718546, 4174.958250417488, 4185.455890734201, 4188.225178342016, 4212.599803018708, 3639.7452178347526, 3644.489771432002, 3753.080495278647, 4024.738392004525, 4113.197926404352, 3336.1492819331156, 3433.7081348085444, 3534.734894882894, 3570.0896271450874, 3713.885834249714, 4736.786844592001, 5315.0, 5328.4671532846405, 5343.786248550054, 5344.465553444663, 4405.955940440586, 4407.933880991788, 4798.160321943646, 4836.564709176156, 4858.562729354339, 5185.455527169631, 5657.765182752831, 5746.023176060038, 5794.420557944214, 5796.449337312949, 3525.9950913989646, 3619.963800361989, 3839.155385815116, 4039.8182081806094, 4155.732501587015, 5084.542391184774, 5683.886322273532, 5763.685880618661, 5980.43185897339, 6097.481714054311, 4323.416338794285, 4729.716217026989, 4876.609871210295, 5044.344235249394, 5106.770195341219, 5108.770105345268, 5163.922541161885, 5179.948200518022, 5212.3223980883, 5261.500157485033, 4781.569658730661, 4911.8280860170435, 5251.044667329, 5261.789528418897, 5411.918821217685, 4737.928931066037, 5189.742462028954, 5384.703841288767, 5564.387917329091, 5793.362730099686, 1563.0, 1634.3625985865522, 1753.65803668286, 1775.5383600264024, 1787.2850859656241, 1279.449836570265, 1282.8652991435952, 1283.5186804948144, 1404.592668126232, 1440.5462279381961, 4656.115338085753, 5172.637915345889, 5329.214713071087, 5580.888382232332, 5605.8598535036135, 5314.62797604164, 6000.539778690716, 6004.159417681528, 6033.190042987087, 6061.848453788691, 4710.717356958525, 4856.514348565186, 5011.498850115032, 5151.716655583904, 5523.917141242885, 2615.953618552594, 2722.68689100752, 2788.18767800927, 3096.396202740489, 3141.596678540918, 5388.7905958557585, 6130.540209484307, 6171.290301615282, 6192.783252586138, 6231.189945307081, 5510.0, 5792.478676919098, 5928.436798504222, 6045.063015232646, 6131.816045518642, 5237.0, 5430.945690543123, 5626.774929002876, 6205.379462053758, 6297.953964194425, 5028.446870844277, 5397.730113494231, 5593.692346920959, 5645.322561292671, 5969.791057312973, 4857.7938560549865, 5167.0547787760615, 5327.680339179662, 5498.532624726943, 5844.707764611838, 5090.959949649894, 5210.557102646318, 5546.889062218814, 6047.0929360596265, 6246.875062498633, 4659.850864794354, 4901.9019619608125, 5049.495050495068, 5052.72210028452, 5052.747362631927, 5271.4464981176425, 5328.920066199088, 5400.216968539618, 5429.0, 5558.749856256559, 4786.7845946931775, 5133.666311689728, 5502.844402675483, 5660.320761508646, 5694.743736531949, 5193.70199923044, 5362.812301569426, 5668.943310566841, 5756.30924289088, 5778.27771528559, 4661.557152070548, 4783.9475315430545, 4916.70499770015, 5571.415001424792, 5710.17202505626, 5038.571721403721, 5237.134409461428, 5805.593608447537, 5848.356680765028, 5853.619514731528, 5048.444671086104, 5049.798008079636, 5148.995945790535, 5576.888462230814, 5697.800576979786], "value": 4335.299950562542, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [812.926936443894, 814.0760237130862, 863.9179277968594, 876.7720392697898, 879.5250564695064, 16114.952528085667, 17194.226259818308, 17214.91392543037, 17227.655446891054, 17301.740473892885, 15818.209089545506, 15977.360905563792, 16030.919845400786, 16038.0, 16053.759193612075, 987.185571903181, 1023.4575674892319, 1030.6547306652244, 1035.4201647077623, 1040.0795296162912, 1032.4837581209395, 1032.917366610673, 1034.3173505486382, 1035.5961175141701, 1035.8498017787417, 1063.8244689626201, 1074.9946250268738, 1080.983785243222, 1081.4808891731973, 1085.7285678580356, 1072.2012100984778, 1072.8337107748312, 1074.0011789036193, 1076.0799516413433, 1076.8277075667895, 793.4525177627419, 793.5833687314149, 799.588212070784, 800.1518390506023, 801.6633014134078, 17144.57138571533, 17173.055481948613, 17874.46376608691, 18117.909410452863, 18281.634367312712, 13953.0, 14774.482893098797, 14776.704465910621, 14817.629559260997, 14856.851431485655, 11649.485566876336, 11792.0, 11803.822942655866, 12010.579629713005, 12122.575709850202, 3823.0633494793674, 3833.310004199243, 3856.421536769507, 3881.5342158940825, 3998.620131087542, 111419.44290278497, 112363.0, 113420.0, 113824.43087784508, 114170.42914785456, 8691.043985161627, 11286.266392684449, 11440.599579014695, 11481.770364592829, 11644.825327620092, 24213.63679544808, 24242.272731818073, 24300.513989720108, 24328.0, 24390.39024024378, 8056.516609003478, 8087.47431416956, 8159.388045896581, 8192.877106843402, 8249.422540422232, 4536.977315113403, 4631.652626052992, 4643.674942753974, 4651.67438279324, 4701.7414042228, 2860.728230818069, 3332.3002169544666, 3420.504026916083, 3430.1767575782133, 3458.948115778265, 584.231735268121, 585.4701495146899, 593.4154857465393, 609.1106983803608, 612.3233826621608, 676.8849295619757, 681.4139839737821, 701.5545128843221, 716.989245161323, 734.522560335786, 10615.946920265349, 10834.677761739002, 10913.454327283764, 11013.803474099217, 11063.668089957153, 701.2671758012889, 704.0, 731.5135434935814, 735.716749051619, 751.4927423988786, 5019.397672279277, 5215.55667768244, 5281.9207711884355, 5289.20661900718, 5305.920411193835, 83028.75456868151, 83218.66586897924, 97771.0, 103457.48271258746, 106924.0, 84991.0, 89723.0, 92949.5352523233, 94757.5262123685, 95163.57254641186, 85980.0, 87825.12174878297, 89109.55445222862, 90385.0, 90722.54638726765, 11256.380899050631, 11353.659390218307, 11401.486933087865, 11552.6534203974, 11632.0, 42127.789361053, 56252.71873640606, 56918.0, 57081.0, 57612.1358179636, 70714.64642676753, 70845.0, 73957.0, 74456.88314675176, 75423.62288188524, 14571.271436428347, 14679.339429725474, 14701.92649036748, 14824.62938426548, 14831.851681483047, 15312.540623781304, 15765.605859853595, 15916.124613146389, 16383.426580069641, 16602.08688522143, 14265.57203283903, 14464.493742718954, 14742.0, 15091.094534327976, 15278.694426111639, 14627.780583291047, 14681.926590367408, 14778.408863645334, 14873.92563037178, 15040.35398405702, 13115.409806558919, 13272.402741876831, 13489.932550337186, 14132.010759247165, 14686.512770234958, 10060.84908726355, 10454.009228517862, 10483.580656773644, 10584.788304234025, 11091.667249982513, 10324.690259292232, 10648.680539583826, 11883.779601220442, 11901.0, 12081.154319197572], "value": 8104.193557951557, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1541.2, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 122.0, 123.0, 121.0, 122.0, 122.0, 120.0, 121.0, 129.0, 152.0], "value": 124.3, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 111.0, 112.0, 147.0, 111.0, 109.0, 117.0, 127.0, 129.0, 110.0], "value": 121.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 204.0, 198.0, 197.0, 222.0, 219.0, 275.0, 204.0, 194.0, 198.0], "value": 210.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [74.0, 52.0, 66.0, 116.0, 90.0, 55.0, 85.0, 86.0, 99.0, 84.0], "value": 80.7, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 70.0, 85.0, 74.0, 72.0, 72.0, 82.0, 70.0, 72.0, 84.0], "value": 76.5, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 90.0, 95.0, 89.0, 91.0, 83.0, 90.0, 90.0, 90.0, 88.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [122.0, 159.0, 140.0, 96.0, 97.0, 99.0, 96.0, 112.0, 153.0, 116.0], "value": 119.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [120.0, 138.0, 145.0, 93.0, 125.0, 139.0, 90.0, 90.0, 92.0, 98.0], "value": 113.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 64.0, 65.0, 69.0, 68.0, 69.0, 69.0, 68.0, 69.0, 68.0], "value": 67.4, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [57.0, 73.0, 47.0, 71.0, 87.0, 86.0, 85.0, 86.0, 86.0, 58.0], "value": 73.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 88.0, 83.0, 83.0, 82.0, 87.0, 88.0, 90.0, 87.0], "value": 85.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [173.0, 145.0, 140.0, 134.0, 131.0, 136.0, 133.0, 139.0, 183.0, 135.0], "value": 144.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 165.0, 163.0, 166.0, 165.0, 165.0, 166.0, 167.0, 170.0, 165.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [59.0, 82.0, 87.0, 65.0, 65.0, 65.0, 65.0, 64.0, 64.0, 65.0], "value": 68.1, "unit": "score"}]}]} 20:50:31 INFO - Return code: 0 20:50:31 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 20:50:31 ERROR - # TBPL SUCCESS # 20:50:31 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 20:50:31 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 20:50:31 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 20:50:31 INFO - 2017-05-04 20:50:31.933 screenresolution[1662:10960] starting screenresolution argv=screenresolution get 20:50:31 INFO - 2017-05-04 20:50:31.936 screenresolution[1662:10960] Display 0: 1600x1200x32@60 20:50:31 INFO - 2017-05-04 20:50:31.942 screenresolution[1663:10963] starting screenresolution argv=screenresolution list 20:50:31 INFO - Available Modes on Display 0 20:50:31 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 20:50:31 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 20:50:31 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 20:50:31 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 20:50:32 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 20:50:32 INFO - Intel Iris: 20:50:32 INFO - Chipset Model: Intel Iris 20:50:32 INFO - Type: GPU 20:50:32 INFO - Bus: Built-In 20:50:32 INFO - VRAM (Dynamic, Max): 1536 MB 20:50:32 INFO - Vendor: Intel (0x8086) 20:50:32 INFO - Device ID: 0x0a2e 20:50:32 INFO - Revision ID: 0x0009 20:50:32 INFO - Displays: 20:50:32 INFO - Display: 20:50:32 INFO - Resolution: 1600 x 1200 @ 60 Hz 20:50:32 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 20:50:32 INFO - Display Serial Number: 1600x1200 60 20:50:32 INFO - Main Display: Yes 20:50:32 INFO - Mirror: Off 20:50:32 INFO - Online: Yes 20:50:32 INFO - Rotation: Supported 20:50:32 INFO - Return code: 0 20:50:32 INFO - Running post-action listener: _resource_record_post_action 20:50:32 INFO - [mozharness: 2017-05-05 03:50:32.182429Z] Finished run-tests step (success) 20:50:32 INFO - Running post-run listener: _resource_record_post_run 20:50:32 INFO - Total resource usage - Wall time: 1193s; CPU: 54.0%; Read bytes: 35610624; Write bytes: 427521536; Read time: 325; Write time: 1937 20:50:32 INFO - TinderboxPrint: CPU usage
54.3% 20:50:32 INFO - TinderboxPrint: I/O read bytes / time
35,610,624 / 325 20:50:32 INFO - TinderboxPrint: I/O write bytes / time
427,521,536 / 1,937 20:50:32 INFO - TinderboxPrint: CPU idle
2,181.0 (45.7%) 20:50:32 INFO - TinderboxPrint: CPU system
955.6 (20.0%) 20:50:32 INFO - TinderboxPrint: CPU user
1,637.1 (34.3%) 20:50:32 INFO - TinderboxPrint: Swap in / out
153,358,336 / 0 20:50:32 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 121676288; Write bytes: 114923520; Read time: 11007; Write time: 375 20:50:32 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 33468416; Write bytes: 299642368; Read time: 299; Write time: 1525 20:50:32 INFO - Running post-run listener: _upload_blobber_files 20:50:32 INFO - Blob upload gear active. 20:50:32 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 20:50:32 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 20:50:32 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 20:50:32 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 20:50:33 INFO - (blobuploader) - INFO - Open directory for files ... 20:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 20:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 20:50:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:33 INFO - (blobuploader) - INFO - Done attempting. 20:50:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 20:50:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:34 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 20:50:34 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:34 INFO - (blobuploader) - INFO - Done attempting. 20:50:34 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 20:50:34 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:34 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:35 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 20:50:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:35 INFO - (blobuploader) - INFO - Done attempting. 20:50:35 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 20:50:35 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 20:50:35 INFO - (blobuploader) - INFO - Uploading, attempt #1. 20:50:40 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 20:50:40 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 20:50:40 INFO - (blobuploader) - INFO - Done attempting. 20:50:40 INFO - (blobuploader) - INFO - Iteration through files over. 20:50:40 INFO - Return code: 0 20:50:40 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 20:50:40 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 20:50:40 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7496116d41006e40e97bb5c0bd790a6324798a126fb934b092b93d9bc08f7353d5be86d4a7f1e198d6e54a3b75af65b47fa147970362cf87abf5c94c0e78e3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5989c6c389ae025eba86cac29f5c79cd5e0668da9561c750abdef83c4ff2b8d65bffb49a6a8c90610d4c7f0ea17c2aec6eebcf515f7884c30d1beb6bba4fa56", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f18c13204a76735cb0af98c68632f43ba95899e46cc639785b887b71752140deb615c8d4c8f33ca131f75fbe20308dce9902ccef885f6114a627d554bd79ca61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2496f119107feb622934be24bd56a054081120e2b4d98c77b2286a24abf796e31d7ba8fc633b712a7fafbbb48a5af79beb01a196cc73e5e8e9c3c2126463da"} 20:50:40 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 20:50:40 INFO - Writing to file /builds/slave/test/properties/blobber_files 20:50:40 INFO - Contents: 20:50:40 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7496116d41006e40e97bb5c0bd790a6324798a126fb934b092b93d9bc08f7353d5be86d4a7f1e198d6e54a3b75af65b47fa147970362cf87abf5c94c0e78e3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5989c6c389ae025eba86cac29f5c79cd5e0668da9561c750abdef83c4ff2b8d65bffb49a6a8c90610d4c7f0ea17c2aec6eebcf515f7884c30d1beb6bba4fa56", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f18c13204a76735cb0af98c68632f43ba95899e46cc639785b887b71752140deb615c8d4c8f33ca131f75fbe20308dce9902ccef885f6114a627d554bd79ca61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2496f119107feb622934be24bd56a054081120e2b4d98c77b2286a24abf796e31d7ba8fc633b712a7fafbbb48a5af79beb01a196cc73e5e8e9c3c2126463da"} 20:50:40 INFO - Running post-run listener: copy_logs_to_upload_dir 20:50:40 INFO - Copying logs to upload dir... 20:50:40 INFO - mkdir: /builds/slave/test/build/upload/logs 20:50:40 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1271.585744 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 11 secs) (at 2017-05-04 20:50:40.155156) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 20:50:40.159184) ========= 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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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/Mozilla-Beta/sha512/e7496116d41006e40e97bb5c0bd790a6324798a126fb934b092b93d9bc08f7353d5be86d4a7f1e198d6e54a3b75af65b47fa147970362cf87abf5c94c0e78e3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5989c6c389ae025eba86cac29f5c79cd5e0668da9561c750abdef83c4ff2b8d65bffb49a6a8c90610d4c7f0ea17c2aec6eebcf515f7884c30d1beb6bba4fa56", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f18c13204a76735cb0af98c68632f43ba95899e46cc639785b887b71752140deb615c8d4c8f33ca131f75fbe20308dce9902ccef885f6114a627d554bd79ca61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2496f119107feb622934be24bd56a054081120e2b4d98c77b2286a24abf796e31d7ba8fc633b712a7fafbbb48a5af79beb01a196cc73e5e8e9c3c2126463da"} build_url:https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011060 build_url: 'https://queue.taskcluster.net/v1/task/e1eOHc6SR1qZEPy3mTLPbw/artifacts/public/build/firefox-54.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/e7496116d41006e40e97bb5c0bd790a6324798a126fb934b092b93d9bc08f7353d5be86d4a7f1e198d6e54a3b75af65b47fa147970362cf87abf5c94c0e78e3f", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/d5989c6c389ae025eba86cac29f5c79cd5e0668da9561c750abdef83c4ff2b8d65bffb49a6a8c90610d4c7f0ea17c2aec6eebcf515f7884c30d1beb6bba4fa56", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/f18c13204a76735cb0af98c68632f43ba95899e46cc639785b887b71752140deb615c8d4c8f33ca131f75fbe20308dce9902ccef885f6114a627d554bd79ca61", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/4c2496f119107feb622934be24bd56a054081120e2b4d98c77b2286a24abf796e31d7ba8fc633b712a7fafbbb48a5af79beb01a196cc73e5e8e9c3c2126463da"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-05-04 20:50:40.194341) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:50:40.194688) ========= 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.E6ENeHtSV4/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.p6hEXcaf2N/Listeners TMPDIR=/var/folders/0k/x84zh_fj74941yslkv8b5zhm00000w/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.005209 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-05-04 20:50:40.244017) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 20:50:40.244362) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-05-04 20:50:40.244702) ========= ========= Total master_lag: 0.18 =========