builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0314 starttime: 1477948932.97 results: success (0) buildid: 20161031115704 builduid: 367e98a0ef1e47349a805daccf50e7a2 revision: 37ab1d54a08e7e1431660b22377428b74dcd090a ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:12.972593) ========= master: http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:12.973078) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:12.973360) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.001366) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.001776) ========= 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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-31 14:22:13-- 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% 21.2M=0.001s 2016-10-31 14:22:13 (21.2 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.086348 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.111500) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.114883) ========= 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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.025314 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.163595) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:13.167025) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 37ab1d54a08e7e1431660b22377428b74dcd090a --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 37ab1d54a08e7e1431660b22377428b74dcd090a --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-31 14:22:13,250 truncating revision to first 12 chars 2016-10-31 14:22:13,250 Setting DEBUG logging. 2016-10-31 14:22:13,250 attempt 1/10 2016-10-31 14:22:13,250 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/37ab1d54a08e?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-31 14:22:13,896 unpacking tar archive at: mozilla-central-37ab1d54a08e/testing/mozharness/ program finished with exit code 0 elapsedTime=0.911601 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:14.102984) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:14.103345) ========= script_repo_revision: 37ab1d54a08e7e1431660b22377428b74dcd090a ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:14.103764) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:14.104036) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-31 14:22:14.122651) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-31 14:22:14.123052) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 14:22:14 INFO - MultiFileLogger online at 20161031 14:22:14 in /builds/slave/test 14:22:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 14:22:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:22:14 INFO - {'append_to_log': False, 14:22:14 INFO - 'base_work_dir': '/builds/slave/test', 14:22:14 INFO - 'blob_upload_branch': 'Firefox', 14:22:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:22:14 INFO - 'branch': 'Firefox', 14:22:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:22:14 INFO - 'config_files': ('talos/mac_config.py',), 14:22:14 INFO - 'default_actions': ('clobber', 14:22:14 INFO - 'read-buildbot-config', 14:22:14 INFO - 'download-and-extract', 14:22:14 INFO - 'populate-webroot', 14:22:14 INFO - 'create-virtualenv', 14:22:14 INFO - 'install', 14:22:14 INFO - 'run-tests'), 14:22:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:22:14 INFO - 'download_minidump_stackwalk': True, 14:22:14 INFO - 'download_symbols': 'ondemand', 14:22:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:22:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:22:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:22:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:22:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:22:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:22:14 INFO - 'installer_path': 'installer.exe', 14:22:14 INFO - 'log_level': 'info', 14:22:14 INFO - 'log_name': 'talos', 14:22:14 INFO - 'log_to_console': True, 14:22:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:22:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:22:14 INFO - 'opt_config_files': (), 14:22:14 INFO - 'pip_index': False, 14:22:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:14 INFO - 'cmd': ('bash', 14:22:14 INFO - '-c', 14:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:14 INFO - 'enabled': True, 14:22:14 INFO - 'halt_on_failure': False, 14:22:14 INFO - 'name': 'check_screen_resolution'},), 14:22:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:22:14 INFO - 'cmd': ('bash', 14:22:14 INFO - '-c', 14:22:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:22:14 INFO - 'enabled': True, 14:22:14 INFO - 'halt_on_failure': False, 14:22:14 INFO - 'name': 'check_screen_resolution'},), 14:22:14 INFO - 'run_cmd_checks_enabled': True, 14:22:14 INFO - 'sps_profile': False, 14:22:14 INFO - 'sps_profile_interval': 0, 14:22:14 INFO - 'suite': 'dromaeojs-e10s', 14:22:14 INFO - 'system_bits': '32', 14:22:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:22:14 INFO - 'title': 't-yosemite-r7-0314', 14:22:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:22:14 INFO - 'use_talos_json': True, 14:22:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:22:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:22:14 INFO - 'work_dir': 'build'} 14:22:14 INFO - [mozharness: 2016-10-31 21:22:14.313599Z] Running clobber step. 14:22:14 INFO - Running pre-action listener: _resource_record_pre_action 14:22:14 INFO - Running main action method: clobber 14:22:14 INFO - rmtree: /builds/slave/test/build 14:22:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:22:16 INFO - Running post-action listener: _resource_record_post_action 14:22:16 INFO - [mozharness: 2016-10-31 21:22:16.360193Z] Finished clobber step (success) 14:22:16 INFO - [mozharness: 2016-10-31 21:22:16.360348Z] Running read-buildbot-config step. 14:22:16 INFO - Running pre-action listener: _resource_record_pre_action 14:22:16 INFO - Running main action method: read_buildbot_config 14:22:16 INFO - Using buildbot properties: 14:22:16 INFO - { 14:22:16 INFO - "project": "", 14:22:16 INFO - "product": "firefox", 14:22:16 INFO - "script_repo_revision": "production", 14:22:16 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 14:22:16 INFO - "repository": "", 14:22:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 14:22:16 INFO - "buildid": "20161031115704", 14:22:16 INFO - "pgo_build": "False", 14:22:16 INFO - "basedir": "/builds/slave/test", 14:22:16 INFO - "buildnumber": 159, 14:22:16 INFO - "slavename": "t-yosemite-r7-0314", 14:22:16 INFO - "master": "http://buildbot-master133.bb.releng.scl3.mozilla.com:8201/", 14:22:16 INFO - "platform": "yosemite_r7", 14:22:16 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 14:22:16 INFO - "branch": "mozilla-central", 14:22:16 INFO - "repo_path": "mozilla-central", 14:22:16 INFO - "revision": "37ab1d54a08e7e1431660b22377428b74dcd090a", 14:22:16 INFO - "stage_platform": "macosx64", 14:22:16 INFO - "builduid": "367e98a0ef1e47349a805daccf50e7a2", 14:22:16 INFO - "slavebuilddir": "test" 14:22:16 INFO - } 14:22:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 14:22:16 INFO - Running post-action listener: _resource_record_post_action 14:22:16 INFO - [mozharness: 2016-10-31 21:22:16.363558Z] Finished read-buildbot-config step (success) 14:22:16 INFO - [mozharness: 2016-10-31 21:22:16.363698Z] Running download-and-extract step. 14:22:16 INFO - Running pre-action listener: _resource_record_pre_action 14:22:16 INFO - Running main action method: download_and_extract 14:22:16 INFO - mkdir: /builds/slave/test/build/tests 14:22: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')]} 14:22:16 INFO - trying https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:16 INFO - Downloading https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 14:22:17 INFO - Downloaded 1199 bytes. 14:22:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 14:22:17 INFO - Using the following test package requirements: 14:22:17 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:22:17 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 14:22:17 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 14:22:17 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'jsshell-mac.zip'], 14:22:17 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 14:22:17 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 14:22:17 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 14:22:17 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 14:22:17 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 14:22:17 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 14:22:17 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 14:22:17 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 14:22:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:22:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:22:17 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:22:18 INFO - Http code: 200 14:22:18 INFO - via: 1.1 032d42677fbf05c24a4bb71369581429.cloudfront.net (CloudFront) 14:22:18 INFO - x-amz-cf-id: L_N6rA9LFE1wI4y-mk0pzuPFh71_Xv961fUdaW5wQ71smwk3IAcz3g== 14:22:18 INFO - x-amz-version-id: 6Dyb.7DWnWe58sJTGX6GphcEy0mEDMWf 14:22:18 INFO - Expected file size: 16404185 14:22:18 INFO - Obtained file size: 16404185 14:22:25 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 14:22:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 14:22:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 14:22:25 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 14:22:26 INFO - Http code: 200 14:22:26 INFO - via: 1.1 0cf1d7257c633ae75623c5e75bf3805e.cloudfront.net (CloudFront) 14:22:26 INFO - x-amz-cf-id: mchYXRsSUSHrKmGWydXvxOG81AXjcIuD_H9OjS3mznaAss3qzU9SnA== 14:22:26 INFO - x-amz-version-id: 6Dyb.7DWnWe58sJTGX6GphcEy0mEDMWf 14:22:26 INFO - Expected file size: 16404185 14:22:26 INFO - Obtained file size: 16404185 14:22:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 14:22:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 14:22:32 INFO - Fetch https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 14:22:33 INFO - Http code: 200 14:22:33 INFO - via: 1.1 93ab344e1a3fa44edbf3f1bc5729427a.cloudfront.net (CloudFront) 14:22:33 INFO - x-amz-cf-id: MjTgBZ3v-xhyUJlVR9diaRfR9P6EpQ4nFjaWNq0BbSSUCA5THCUI2w== 14:22:33 INFO - x-amz-version-id: 7NIkiugcKNyoiwUPJLByQNxbQYnvg9MM 14:22:33 INFO - Expected file size: 14409038 14:22:33 INFO - Obtained file size: 14409038 14:22:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:34 INFO - trying https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:22:34 INFO - Downloading https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 14:22:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 14:22:36 INFO - Downloaded 112071794 bytes. 14:22:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:22:36 INFO - mkdir: /builds/slave/test/properties 14:22:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:22:36 INFO - Writing to file /builds/slave/test/properties/build_url 14:22:36 INFO - Contents: 14:22:36 INFO - build_url:https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 14:22:37 INFO - Running post-action listener: _resource_record_post_action 14:22:37 INFO - Running post-action listener: set_extra_try_arguments 14:22:37 INFO - [mozharness: 2016-10-31 21:22:37.645294Z] Finished download-and-extract step (success) 14:22:37 INFO - [mozharness: 2016-10-31 21:22:37.645417Z] Running populate-webroot step. 14:22:37 INFO - Running pre-action listener: _resource_record_pre_action 14:22:37 INFO - Running main action method: populate_webroot 14:22:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:22:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:22:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:22:37 INFO - '--tppagecycles', 14:22:37 INFO - '7'], 14:22:37 INFO - 'tests': ['tsvgm']}}, 14:22:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:22:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:22:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:22:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:22:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:22:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['damp', 'tps']}, 14:22:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'tests': ['damp', 'tps']}, 14:22:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['dromaeo_dom']}, 14:22:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:22:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['basic_compositor_video']}, 14:22:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:22:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['a11yr', 14:22:37 INFO - 'ts_paint', 14:22:37 INFO - 'tpaint', 14:22:37 INFO - 'sessionrestore', 14:22:37 INFO - 'sessionrestore_no_auto_restore', 14:22:37 INFO - 'tabpaint']}, 14:22:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['tsvgx', 14:22:37 INFO - 'tsvgr_opacity', 14:22:37 INFO - 'tart', 14:22:37 INFO - 'tscrollx', 14:22:37 INFO - 'cart']}, 14:22:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:22:37 INFO - 'tsvgr_opacity', 14:22:37 INFO - 'tart', 14:22:37 INFO - 'tscrollx', 14:22:37 INFO - 'cart']}, 14:22:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'talos_options': ['--disable-e10s'], 14:22:37 INFO - 'tests': ['tp5o']}, 14:22:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'tests': ['tp5o']}, 14:22:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'talos_options': ['--disable-e10s', 14:22:37 INFO - '--xperf_path', 14:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:37 INFO - 'tests': ['tp5n']}, 14:22:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:22:37 INFO - 'talos_options': ['--xperf_path', 14:22:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:22:37 INFO - 'tests': ['tp5n']}}, 14:22:37 INFO - 'talos.zip': {'path': '', 14:22:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:22:37 INFO - Running post-action listener: _resource_record_post_action 14:22:37 INFO - [mozharness: 2016-10-31 21:22:37.664115Z] Finished populate-webroot step (success) 14:22:37 INFO - [mozharness: 2016-10-31 21:22:37.664243Z] Running create-virtualenv step. 14:22:37 INFO - Running pre-action listener: _resource_record_pre_action 14:22:37 INFO - Running main action method: create_virtualenv 14:22:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:22:37 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 14:22:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:22:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:22:37 INFO - Using real prefix '/tools/python27' 14:22:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:22:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:22:41 INFO - Installing pip.................done. 14:22:41 INFO - Return code: 0 14:22:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:22:41 INFO - Reading from file tmpfile_stdout 14:22:41 INFO - Output received: 14:22:41 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) 14:22:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:41 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 14:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:41 INFO - 'HOME': '/Users/cltbld', 14:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:41 INFO - 'LOGNAME': 'cltbld', 14:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:41 INFO - 'MOZ_NO_REMOTE': '1', 14:22:41 INFO - 'NO_EM_RESTART': '1', 14:22:41 INFO - 'PAGER': '/bin/cat', 14:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:41 INFO - 'PWD': '/builds/slave/test', 14:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:41 INFO - 'SHELL': '/bin/bash', 14:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:41 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:41 INFO - 'USER': 'cltbld', 14:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:41 INFO - 'XPC_FLAGS': '0x0', 14:22:41 INFO - 'XPC_SERVICE_NAME': '0', 14:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:41 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 14:22:41 INFO - Cleaning up... 14:22:41 INFO - Return code: 0 14:22:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:41 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 14:22:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 14:22:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:41 INFO - 'HOME': '/Users/cltbld', 14:22:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:41 INFO - 'LOGNAME': 'cltbld', 14:22:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:41 INFO - 'MOZ_NO_REMOTE': '1', 14:22:41 INFO - 'NO_EM_RESTART': '1', 14:22:41 INFO - 'PAGER': '/bin/cat', 14:22:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:41 INFO - 'PWD': '/builds/slave/test', 14:22:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:41 INFO - 'SHELL': '/bin/bash', 14:22:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:41 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:41 INFO - 'USER': 'cltbld', 14:22:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:41 INFO - 'XPC_FLAGS': '0x0', 14:22:41 INFO - 'XPC_SERVICE_NAME': '0', 14:22:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:42 INFO - Downloading/unpacking psutil>=3.1.1 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:42 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:42 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:22:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:45 INFO - Installing collected packages: psutil 14:22:45 INFO - Running setup.py install for psutil 14:22:45 INFO - building 'psutil._psutil_osx' extension 14:22:45 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:22:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 14:22:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 14:22:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 14:22:46 INFO - building 'psutil._psutil_posix' extension 14:22:46 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 14:22:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:22:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:22:46 INFO - ^ 14:22:46 INFO - 1 warning generated. 14:22:46 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 14:22:46 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:22:46 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:22:46 INFO - Successfully installed psutil 14:22:46 INFO - Cleaning up... 14:22:46 INFO - Return code: 0 14:22:46 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:46 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 14:22:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 14:22:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:46 INFO - 'HOME': '/Users/cltbld', 14:22:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:46 INFO - 'LOGNAME': 'cltbld', 14:22:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:46 INFO - 'MOZ_NO_REMOTE': '1', 14:22:46 INFO - 'NO_EM_RESTART': '1', 14:22:46 INFO - 'PAGER': '/bin/cat', 14:22:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:46 INFO - 'PWD': '/builds/slave/test', 14:22:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:46 INFO - 'SHELL': '/bin/bash', 14:22:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:46 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:46 INFO - 'USER': 'cltbld', 14:22:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:46 INFO - 'XPC_FLAGS': '0x0', 14:22:46 INFO - 'XPC_SERVICE_NAME': '0', 14:22:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:49 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:22:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:22:49 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:22:49 INFO - Installing collected packages: mozsystemmonitor 14:22:49 INFO - Running setup.py install for mozsystemmonitor 14:22:49 INFO - Successfully installed mozsystemmonitor 14:22:49 INFO - Cleaning up... 14:22:49 INFO - Return code: 0 14:22:49 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:49 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 14:22:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 14:22:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:49 INFO - 'HOME': '/Users/cltbld', 14:22:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:49 INFO - 'LOGNAME': 'cltbld', 14:22:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:49 INFO - 'MOZ_NO_REMOTE': '1', 14:22:49 INFO - 'NO_EM_RESTART': '1', 14:22:49 INFO - 'PAGER': '/bin/cat', 14:22:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:49 INFO - 'PWD': '/builds/slave/test', 14:22:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:49 INFO - 'SHELL': '/bin/bash', 14:22:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:49 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:49 INFO - 'USER': 'cltbld', 14:22:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:49 INFO - 'XPC_FLAGS': '0x0', 14:22:49 INFO - 'XPC_SERVICE_NAME': '0', 14:22:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:50 INFO - Downloading/unpacking jsonschema==2.5.1 14:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:52 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:22:52 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:53 INFO - Downloading functools32-3.2.3-2.tar.gz 14:22:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:22:53 INFO - warning: no files found matching '*.txt' 14:22:53 INFO - no previously-included directories found matching 'build' 14:22:53 INFO - no previously-included directories found matching 'dist' 14:22:53 INFO - no previously-included directories found matching '.git*' 14:22:53 INFO - Installing collected packages: jsonschema, functools32 14:22:53 INFO - Running setup.py install for functools32 14:22:53 INFO - warning: no files found matching '*.txt' 14:22:53 INFO - no previously-included directories found matching 'build' 14:22:53 INFO - no previously-included directories found matching 'dist' 14:22:53 INFO - no previously-included directories found matching '.git*' 14:22:53 INFO - Successfully installed jsonschema functools32 14:22:53 INFO - Cleaning up... 14:22:53 INFO - Return code: 0 14:22:53 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 14:22:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:53 INFO - 'HOME': '/Users/cltbld', 14:22:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:53 INFO - 'LOGNAME': 'cltbld', 14:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:53 INFO - 'MOZ_NO_REMOTE': '1', 14:22:53 INFO - 'NO_EM_RESTART': '1', 14:22:53 INFO - 'PAGER': '/bin/cat', 14:22:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:53 INFO - 'PWD': '/builds/slave/test', 14:22:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:53 INFO - 'SHELL': '/bin/bash', 14:22:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:53 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:53 INFO - 'USER': 'cltbld', 14:22:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:53 INFO - 'XPC_FLAGS': '0x0', 14:22:53 INFO - 'XPC_SERVICE_NAME': '0', 14:22:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:53 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:22:53 INFO - Cleaning up... 14:22:53 INFO - Return code: 0 14:22:53 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:53 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 14:22:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 14:22:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:53 INFO - 'HOME': '/Users/cltbld', 14:22:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:53 INFO - 'LOGNAME': 'cltbld', 14:22:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:53 INFO - 'MOZ_NO_REMOTE': '1', 14:22:53 INFO - 'NO_EM_RESTART': '1', 14:22:53 INFO - 'PAGER': '/bin/cat', 14:22:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:53 INFO - 'PWD': '/builds/slave/test', 14:22:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:53 INFO - 'SHELL': '/bin/bash', 14:22:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:53 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:53 INFO - 'USER': 'cltbld', 14:22:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:53 INFO - 'XPC_FLAGS': '0x0', 14:22:53 INFO - 'XPC_SERVICE_NAME': '0', 14:22:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:54 INFO - Downloading/unpacking blobuploader==1.2.4 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:54 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:54 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:56 INFO - Downloading blobuploader-1.2.4.tar.gz 14:22:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:22:56 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:22:57 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:57 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:57 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:22:57 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:22:57 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:22:57 INFO - Downloading docopt-0.6.1.tar.gz 14:22:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:22:57 INFO - Installing collected packages: blobuploader, requests, docopt 14:22:57 INFO - Running setup.py install for blobuploader 14:22:57 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:22:57 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:22:57 INFO - Running setup.py install for requests 14:22:58 INFO - Running setup.py install for docopt 14:22:58 INFO - Successfully installed blobuploader requests docopt 14:22:58 INFO - Cleaning up... 14:22:58 INFO - Return code: 0 14:22:58 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:22:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:22:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:22:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:22:58 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:22:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:22:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:22:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:22:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:22:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:22:58 INFO - 'HOME': '/Users/cltbld', 14:22:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:22:58 INFO - 'LOGNAME': 'cltbld', 14:22:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:22:58 INFO - 'MOZ_NO_REMOTE': '1', 14:22:58 INFO - 'NO_EM_RESTART': '1', 14:22:58 INFO - 'PAGER': '/bin/cat', 14:22:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:22:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:22:58 INFO - 'PWD': '/builds/slave/test', 14:22:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:22:58 INFO - 'SHELL': '/bin/bash', 14:22:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:22:58 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:22:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:22:58 INFO - 'USER': 'cltbld', 14:22:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:22:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:22:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:22:58 INFO - 'XPC_FLAGS': '0x0', 14:22:58 INFO - 'XPC_SERVICE_NAME': '0', 14:22:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:22:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:22:58 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:22:58 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-TaXAVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Bfw9Fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-hpw4ag-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-25XoLz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0_1i5f-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-epxHEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-FROZFi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-TtAg2t-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-swiz_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-Ur4Y74-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:22:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:22:59 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-aKrbIi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-BAiFSx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wyDgQW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mlp4t9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-f5ZwZI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-2LGVCV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:00 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-_EqmKG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:23:00 INFO - Running setup.py install for manifestparser 14:23:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:23:00 INFO - Running setup.py install for mozcrash 14:23:00 INFO - Running setup.py install for mozdebug 14:23:01 INFO - Running setup.py install for mozdevice 14:23:01 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Running setup.py install for mozfile 14:23:01 INFO - Running setup.py install for mozhttpd 14:23:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Running setup.py install for mozinfo 14:23:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Running setup.py install for mozInstall 14:23:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:23:01 INFO - Running setup.py install for mozleak 14:23:01 INFO - Running setup.py install for mozlog 14:23:02 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Running setup.py install for moznetwork 14:23:02 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Running setup.py install for mozprocess 14:23:02 INFO - Running setup.py install for mozprofile 14:23:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Running setup.py install for mozrunner 14:23:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:23:02 INFO - Running setup.py install for mozscreenshot 14:23:02 INFO - Running setup.py install for moztest 14:23:03 INFO - Running setup.py install for mozversion 14:23:03 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:23:03 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:23:03 INFO - Cleaning up... 14:23:03 INFO - Return code: 0 14:23:03 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:03 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:03 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:03 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:03 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 14:23:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:23:03 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:23:03 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:03 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:03 INFO - 'HOME': '/Users/cltbld', 14:23:03 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:03 INFO - 'LOGNAME': 'cltbld', 14:23:03 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:03 INFO - 'MOZ_NO_REMOTE': '1', 14:23:03 INFO - 'NO_EM_RESTART': '1', 14:23:03 INFO - 'PAGER': '/bin/cat', 14:23:03 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:03 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:03 INFO - 'PWD': '/builds/slave/test', 14:23:03 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:03 INFO - 'SHELL': '/bin/bash', 14:23:03 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:23:03 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:23:03 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:03 INFO - 'USER': 'cltbld', 14:23:03 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:03 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:03 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:03 INFO - 'XPC_FLAGS': '0x0', 14:23:03 INFO - 'XPC_SERVICE_NAME': '0', 14:23:03 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:03 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:23:03 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-S7iJQO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 14:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:23:03 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-xJT9wu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:23:03 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-egZhqL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:23:03 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mdZpd2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:23:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.48 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:23:03 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-0Zezt4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-SbjFU1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-pW17uT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-yOQMsI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-1iG_kK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-cJLKPp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:23:04 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)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-lCQqQ3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-wnxEfq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.23 from file:///builds/slave/test/build/tests/mozbase/mozprocess in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 12)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-L6bXAh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:23:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 14:23:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:23:04 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-sdinPr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:05 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-shkKG6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 14:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:23:05 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-mw4Tgd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:23:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:23:05 INFO - Running setup.py (path:/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/pip-irS90J-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:05 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.48->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 14:23:05 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 14:23:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 14:23:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 14:23:08 INFO - Downloading blessings-1.6.tar.gz 14:23:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:23:08 INFO - Installing collected packages: blessings 14:23:08 INFO - Running setup.py install for blessings 14:23:08 INFO - Successfully installed blessings 14:23:08 INFO - Cleaning up... 14:23:08 INFO - Return code: 0 14:23:08 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:23:08 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:08 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:08 INFO - Reading from file tmpfile_stdout 14:23:08 INFO - Current package versions: 14:23:08 INFO - blessings == 1.6 14:23:08 INFO - blobuploader == 1.2.4 14:23:08 INFO - docopt == 0.6.1 14:23:08 INFO - functools32 == 3.2.3-2 14:23:08 INFO - jsonschema == 2.5.1 14:23:08 INFO - manifestparser == 1.1 14:23:08 INFO - mozInstall == 1.12 14:23:08 INFO - mozcrash == 0.17 14:23:08 INFO - mozdebug == 0.1 14:23:08 INFO - mozdevice == 0.48 14:23:08 INFO - mozfile == 1.2 14:23:08 INFO - mozhttpd == 0.7 14:23:08 INFO - mozinfo == 0.9 14:23:08 INFO - mozleak == 0.1 14:23:08 INFO - mozlog == 3.4 14:23:08 INFO - moznetwork == 0.27 14:23:08 INFO - mozprocess == 0.23 14:23:08 INFO - mozprofile == 0.28 14:23:08 INFO - mozrunner == 6.12 14:23:08 INFO - mozscreenshot == 0.1 14:23:08 INFO - mozsystemmonitor == 0.3 14:23:08 INFO - moztest == 0.7 14:23:08 INFO - mozversion == 1.4 14:23:08 INFO - psutil == 3.1.1 14:23:08 INFO - requests == 1.2.3 14:23:08 INFO - wsgiref == 0.1.2 14:23:08 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:23:08 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:08 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 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 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/talos/requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/talos 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/talos/requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 14:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:09 INFO - 'HOME': '/Users/cltbld', 14:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:09 INFO - 'LOGNAME': 'cltbld', 14:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:09 INFO - 'MOZ_NO_REMOTE': '1', 14:23:09 INFO - 'NO_EM_RESTART': '1', 14:23:09 INFO - 'PAGER': '/bin/cat', 14:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:09 INFO - 'PWD': '/builds/slave/test', 14:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:09 INFO - 'SHELL': '/bin/bash', 14:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:23:09 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:09 INFO - 'USER': 'cltbld', 14:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:09 INFO - 'XPC_FLAGS': '0x0', 14:23:09 INFO - 'XPC_SERVICE_NAME': '0', 14:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 14:23:09 INFO - Cleaning up... 14:23:09 INFO - Return code: 0 14:23:09 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:23: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')]} 14:23:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23: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')]} 14:23:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:23:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:23:09 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x109c6c490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109be2570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f8a52e54220>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, '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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', '__CF_USER_TEXT_ENCODING': '0x1C:0:0', 'PWD': '/builds/slave/test', 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 'MOZ_CRASHREPORTER_NO_REPORT': '1', 'PAGER': '/bin/cat'}}, attempt #1 14:23:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 14:23:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:23:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:09 INFO - 'HOME': '/Users/cltbld', 14:23:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:09 INFO - 'LOGNAME': 'cltbld', 14:23:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:09 INFO - 'MOZ_NO_REMOTE': '1', 14:23:09 INFO - 'NO_EM_RESTART': '1', 14:23:09 INFO - 'PAGER': '/bin/cat', 14:23:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:09 INFO - 'PWD': '/builds/slave/test', 14:23:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:09 INFO - 'SHELL': '/bin/bash', 14:23:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:23:09 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:23:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:09 INFO - 'USER': 'cltbld', 14:23:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:09 INFO - 'XPC_FLAGS': '0x0', 14:23:09 INFO - 'XPC_SERVICE_NAME': '0', 14:23:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:09 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:23:09 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:23:09 INFO - Cleaning up... 14:23:09 INFO - Return code: 0 14:23:09 INFO - Running post-action listener: _resource_record_post_action 14:23:09 INFO - Running post-action listener: _start_resource_monitoring 14:23:09 INFO - Starting resource monitoring. 14:23:09 INFO - [mozharness: 2016-10-31 21:23:09.725705Z] Finished create-virtualenv step (success) 14:23:09 INFO - [mozharness: 2016-10-31 21:23:09.726748Z] Running install step. 14:23:09 INFO - Running pre-action listener: _resource_record_pre_action 14:23:09 INFO - Running main action method: install 14:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:23:09 INFO - Reading from file tmpfile_stdout 14:23:09 INFO - Detecting whether we're running mozinstall >=1.0... 14:23:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:23:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:23:10 INFO - Reading from file tmpfile_stdout 14:23:10 INFO - Output received: 14:23:10 INFO - Usage: mozinstall [options] installer 14:23:10 INFO - Options: 14:23:10 INFO - -h, --help show this help message and exit 14:23:10 INFO - -d DEST, --destination=DEST 14:23:10 INFO - Directory to install application into. [default: 14:23:10 INFO - "/builds/slave/test"] 14:23:10 INFO - --app=APP Application being installed. [default: firefox] 14:23:10 INFO - mkdir: /builds/slave/test/build/application 14:23:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:23:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 14:23:44 INFO - Reading from file tmpfile_stdout 14:23:44 INFO - Output received: 14:23:44 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 14:23:44 INFO - Running post-action listener: _resource_record_post_action 14:23:44 INFO - [mozharness: 2016-10-31 21:23:44.437440Z] Finished install step (success) 14:23:44 INFO - [mozharness: 2016-10-31 21:23:44.437580Z] Running run-tests step. 14:23:44 INFO - Running pre-action listener: _resource_record_pre_action 14:23:44 INFO - Running main action method: run_tests 14:23:44 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:23:44 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:23:44 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:23:44 INFO - 2016-10-31 14:23:44.452 screenresolution[1662:7501] starting screenresolution argv=screenresolution get 14:23:44 INFO - 2016-10-31 14:23:44.454 screenresolution[1662:7501] Display 0: 1600x1200x32@60 14:23:44 INFO - 2016-10-31 14:23:44.460 screenresolution[1663:7503] starting screenresolution argv=screenresolution list 14:23:44 INFO - Available Modes on Display 0 14:23:44 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:23:44 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:23:44 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:23:44 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:23:44 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:23:44 INFO - Intel Iris: 14:23:44 INFO - Chipset Model: Intel Iris 14:23:44 INFO - Type: GPU 14:23:44 INFO - Bus: Built-In 14:23:44 INFO - VRAM (Dynamic, Max): 1536 MB 14:23:44 INFO - Vendor: Intel (0x8086) 14:23:44 INFO - Device ID: 0x0a2e 14:23:44 INFO - Revision ID: 0x0009 14:23:44 INFO - Displays: 14:23:44 INFO - Display: 14:23:44 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:23:44 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:23:44 INFO - Display Serial Number: 1600x1200 60 14:23:44 INFO - Main Display: Yes 14:23:44 INFO - Mirror: Off 14:23:44 INFO - Online: Yes 14:23:44 INFO - Rotation: Supported 14:23:44 INFO - Return code: 0 14:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:23:44 INFO - Python 2.7.3 14:23:44 INFO - Return code: 0 14:23:44 INFO - grabbing minidump binary from tooltool 14:23:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:23:44 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 0x109a80d30>, '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 0x1098a6db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x109a78b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:23:44 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 14:23:44 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 14:23:44 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 14:23:44 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:23:44 INFO - Return code: 0 14:23:44 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:23:44 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:23:44 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:23:44 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:23:44 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:23:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 14:23:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0314 --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 14:23:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0YiB4WvdBm/Render', 14:23:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:23:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:23:44 INFO - 'HOME': '/Users/cltbld', 14:23:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:23:44 INFO - 'LOGNAME': 'cltbld', 14:23:44 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:23:44 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:23:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:23:44 INFO - 'MOZ_NO_REMOTE': '1', 14:23:44 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:23:44 INFO - 'NO_EM_RESTART': '1', 14:23:44 INFO - 'PAGER': '/bin/cat', 14:23:44 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:23:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:23:44 INFO - 'PWD': '/builds/slave/test', 14:23:44 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:23:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:23:44 INFO - 'SHELL': '/bin/bash', 14:23:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QVGnH6R7YG/Listeners', 14:23:44 INFO - 'TMPDIR': '/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/', 14:23:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:23:44 INFO - 'USER': 'cltbld', 14:23:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:23:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:23:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:23:44 INFO - 'XPC_FLAGS': '0x0', 14:23:44 INFO - 'XPC_SERVICE_NAME': '0', 14:23:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:23:44 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0314', '--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 14:23:45 INFO - mozversion application_buildid: 20161031115704 14:23:45 INFO - mozversion application_changeset: 37ab1d54a08e7e1431660b22377428b74dcd090a 14:23:45 INFO - mozversion application_display_name: Nightly 14:23:45 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:23:45 INFO - mozversion application_name: Firefox 14:23:45 INFO - mozversion application_remotingname: firefox 14:23:45 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 14:23:45 INFO - mozversion application_vendor: Mozilla 14:23:45 INFO - mozversion application_version: 52.0a1 14:23:45 INFO - mozversion platform_buildid: 20161031115704 14:23:45 INFO - mozversion platform_changeset: 37ab1d54a08e7e1431660b22377428b74dcd090a 14:23:45 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 14:23:45 INFO - mozversion platform_version: 52.0a1 14:23:45 INFO - using testdate: 1477949025 14:23:45 INFO - actual date: 1477949025 14:23:45 INFO - starting webserver on 'localhost:49275' 14:23:45 INFO - SUITE-START | Running 2 tests 14:23:45 INFO - TEST-START | dromaeo_css 14:23:45 INFO - operating with platform_type : mac_ 14:23:45 INFO - Initialising browser for dromaeo_css test... 14:23:45 INFO - TEST-INFO | started process 1673 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpgMHKWE/profile http://localhost:49275/getInfo.html) 14:23:47 INFO - PROCESS | 1673 | 2016-10-31 14:23:47.868 plugin-container[1675:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f3b, name = 'com.apple.tsm.portname' 14:23:47 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:47 INFO - PROCESS | 1673 | 2016-10-31 14:23:47.872 plugin-container[1675:7666] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a27, name = 'com.apple.CFPasteboardClient' 14:23:47 INFO - PROCESS | 1673 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:47 INFO - PROCESS | 1673 | 2016-10-31 14:23:47.872 plugin-container[1675:7666] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:23:47 INFO - PROCESS | 1673 | __metrics Screen width/height:1600/1200 14:23:47 INFO - PROCESS | 1673 | colorDepth:24 14:23:47 INFO - PROCESS | 1673 | Browser inner width/height: 1024/689 14:23:47 INFO - PROCESS | 1673 | __metrics 14:23:48 ERROR - PROCESS | 1673 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 14:23:48 INFO - TEST-INFO | 1673: exit 0 14:23:48 INFO - Browser initialized. 14:23:48 INFO - Running cycle 1/1 for dromaeo_css test... 14:23:48 INFO - TEST-INFO | started process 1676 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmpgMHKWE/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:23:51 INFO - PROCESS | 1676 | 2016-10-31 14:23:51.154 plugin-container[1680:7835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 14:23:51 INFO - PROCESS | 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:51 INFO - PROCESS | 1676 | 2016-10-31 14:23:51.155 plugin-container[1680:7835] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4323, name = 'com.apple.CFPasteboardClient' 14:23:51 INFO - PROCESS | 1676 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:23:51 INFO - PROCESS | 1676 | 2016-10-31 14:23:51.155 plugin-container[1680:7835] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:24:00 INFO - PROCESS | 1676 | RSS: Main: 240029696 14:24:00 INFO - PROCESS | 1676 | 14:24:50 INFO - PROCESS | 1676 | 1477949090512 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 14:27:03 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49275/tests/dromaeo/cssquery-ext.html) 14:27:05 INFO - PROCESS | 1676 | RSS: Main: 254033920 14:27:05 INFO - PROCESS | 1676 | 14:30:07 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-ext.html (next: http://localhost:49275/tests/dromaeo/cssquery-jquery.html) 14:30:07 INFO - PROCESS | 1676 | RSS: Main: 264712192 14:30:07 INFO - PROCESS | 1676 | 14:33:09 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49275/tests/dromaeo/cssquery-mootools.html) 14:33:10 INFO - PROCESS | 1676 | RSS: Main: 269074432 14:33:10 INFO - PROCESS | 1676 | 14:36:12 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49275/tests/dromaeo/cssquery-prototype.html) 14:36:12 INFO - PROCESS | 1676 | RSS: Main: 267755520 14:36:12 INFO - PROCESS | 1676 | 14:39:15 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49275/tests/dromaeo/cssquery-yui.html) 14:39:16 INFO - PROCESS | 1676 | RSS: Main: 272060416 14:39:16 INFO - PROCESS | 1676 | 14:42:18 INFO - PROCESS | 1676 | Cycle 1(1): loaded http://localhost:49275/tests/dromaeo/cssquery-yui.html (next: http://localhost:49275/tests/dromaeo/cssquery-dojo.html) 14:42:19 INFO - PROCESS | 1676 | RSS: Main: 274210816 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | __start_tp_report 14:42:19 INFO - PROCESS | 1676 | _x_x_mozilla_page_load 14:42:19 INFO - PROCESS | 1676 | _x_x_mozilla_page_load_details 14:42:19 INFO - PROCESS | 1676 | |i|pagename|runs| 14:42:19 INFO - PROCESS | 1676 | |0;dojo.html;2766.681831589367;3372.22438839067;3378.864845406184;3412.8805491807793;3438.6733260340266;5627.184058311543;5676.545876329888;5679.716014199289;5704.201411802341;5711.228984087147;5530.751116199771;5688;5708.514776244014;5766.942330576693;5776.277965254343;4160.334346504553;4661.3707149534885;4903.068417000776;5239.449857764937;5241.580673546107;4469.865904022885;5166.380034395877;5230.6600070995455;5337.919931201035;5373.086575282192;5079.568236699885;5252.23842542833;5274.393444753864;5287.47125287472;5335.093034184198;5087.694738315713;5100.311457953203;5118.309028281209;5184.507471790174;5258.474152584749;5105.923411148836;5136.743162841843;5162.638615296931;5180.1452760294505;5210.244514545408;5586.24585680932;5699.002674531982;5701.232617888468;5724;5727;6093.512518998469;6112.205413296288;6145.955187618116;6158.907616385758;6180.81457556274;6258.123862659231;6272.780452684134;6280.214973128359;6294.559380843343;6308.810735677937;5797.333306669745;5814.331351894544;5829.8251052468495;5860;5897;5915.260592425947;5979.133025711334;6194.287656919422;6208.348123447064;6230.501559875199;6144.5084393248435;6194.473469755031;6213.850437668955;6218.129461875341;6219.440250377397;5236.528712415901;6238.407351301619;6270.9372906270355;6286.717097730613;6289.96855015722;5070.29015937769;6025.427584379477;6029.788957386558;6117.571769976148;6213.813585592439;6110.832160913912;6170.876582468325;6233.781817636361;6242.687865606701;6246;4111.506619205715;4914.311996320518;4920.704757714549;4928.457869634337;4987.675801072919;4349.06495103548;4395.890102747393;4422.933655995163;4425.247707889666;4451.421315229064;5754.0505816540635;5819.301683797971;5882.058870580772;5924.555658325643;5974.910376344358;6188.1027251048345;6215.782447614402;6250.653912263676;6297.527685423612;6307.180066591313;4764.904701906012;5643.125315576092;5787.421257874263;5881.529477641779;5928;5346.278252435949;5378.435264297271;5417.83746487606;5428.809991650273;5478.863028424242;6311.337309582428;6349.269833969152;6361.650109243851;6361.872762544724;6375.489960803217;6258.561900666909;6309;6314.526410519137;6319.957207060784;6324.304326523987;6215.06773983906;6216.627002379872;6218.19163508741;6228.657423841551;6258.718357673824;6207.255129384503;6241.001439769615;6246.6876656167915;6247.843803904939;6248.094026366242;6276;6287.339829317856;6293.181886354744;6298.496120310272;6323.683815809283;5894.764209431574;6018.909716354171;6068.362821903813;6099.024156134997;6184.8763024738355;5345.839624811262;5798.275215598051;6091.573589848846;6129.358790882775;6133.202683651097;5752.626079304831;5778.36437991829;5984.491318237999;5985.341612422543;5987.31145918225;5884.029135192816;6018.337982821974;6085.1480792689945;6130.141780150693;6144.938550614615;5257.658252213594;5889.970550147222;5894.764209431746;5996.730147143302;6090.878182436238 14:42:19 INFO - PROCESS | 1676 | |1;ext.html;454.6317482838901;484.14548322211306;496.5332587367873;507.6243579750984;537.2129829799343;40652.79673601632;42023.78988105059;42197.367039494355;42311;42327.57672423275;42497.36253956193;42968.57031429685;43014;43094.27622895088;43258;9414.152726254635;9466.62133514662;9585.137337639611;9597.752292202003;9604.567794449267;9468.147866691996;9556.23550115989;9575.994520575343;9600.759981000461;9603.2317414607;9587.904120958805;9590.856137157949;9624.951875240615;9635.18100961419;9638;7579.810504737398;8574.485530868136;9525.857112143358;9557.761055973588;9648.469334186617;9506.096920792514;9552.90447095534;9561.426314421156;9561.713148605553;9570.56932438035;40570.39144412836;40607;40986.79506602478;41166;41349.379759303636;3277.147941535218;3486.2853115111343;3720.325853365969;3888.2806680763865;3985.0834308109297;25789.484210315877;29605;30170.245743856358;30323;30883.845580772177;3163.4622114240633;3170.080676603782;3219.17910932713;3300.7689461737687;3352.8155951422664;410638.78722425684;466311;480089;481048.59475702746;481610;61342.69328653417;61393.07910381348;61564.38435615676;61599;61645;63413;63980;64058.35941640617;64075.03887441692;64122.35877641257;13737.725245495234;15736.213189340488;15804.36782528709;15818.841811581737;15879.52361429159;11917.880821191677;12086.274823510617;12141.757164856654;12144.90695837379;12166.635000949986;17275.654486910193;17356.65286694259;17608.823911760974;17620.383286584907;17765;3136.623605167349;3227.967720322814;5999.400059993965;6042.425969532887;6050.971334873082;852.1904191018433;888.2139306713605;939.9953000234955;969.95150242489;1003.4932359158578;26520.80656370468;27535.586966195522;30263.848680756455;30437.934672286363;30534.236644084074;906.859436787299;1061.6496556136456;1063.5426766490484;1081.2377274021796;1089.2810744908322;14962.925185374004;15080.849191508163;15487.612809679847;15842.920785396;15898.920505397631;632648;637219;637401.9428931361;638856;640793;58218;58622.7068864653;58679.11981320284;58686.11970820356;58742;1311.2329287366997;1343.9126456780277;1370.376478702188;1370.8149399830895;1371.6022353517567;29483.41033179367;30598.541021885136;30746.231344215677;31443.842780785948;32050.35899282048;56216.71891640516;57090;57095.85808283894;57257;57645.711771440874;613267;618857.9057104685;619045;619639.8396176507;622201;41861.79069104635;42725.786371067945;42782.57217427786;43580.56419435765;43747.78126109349;46902.76548617235;47922.76038619784;48224.517754822;48546.75726621344;48641;39089.022774430865;39226;39258.01854953649;39943.80028099841;40608.18783624252;39394.40908386319;39473.60526394699;40041.39937900992;40476.80437930127;41119.972000700225;38242.6175738239;38574.84275471741;38691.22617547577;38950.61049389583;39098;33280.833595831864;34539;34925.30149396947;35203.64796352004;35405.46891796677;21013.474663133544;25834.87082564575;25872.611910821728;26199.476010479302;27333.72666273312 14:42:19 INFO - PROCESS | 1676 | |2;jquery.html;324.9350129974005;329.17213208779924;374.33181770539585;383.79083399547244;399.9900251864043;19088.42734717959;20152.992350382494;20447;20805.27181548648;20829.895850520727;19241;19512.902435487802;19920.203191872344;19930.302439414623;20112.59774804503;5686.829395118132;5759.769609215627;5800.854978625526;5960.076188190816;6007.879842403171;5122.667026643275;5814.796482123126;5934.436228558302;5953.58324917256;5959.3146788119275;5754.367019627858;5860.326062502802;5878.265216847894;5879.647221166743;5908.586398952075;4950.801967921278;5843.328017278025;5914.852128696795;5915.556333274979;5924.37794031627;4931.7780699868235;5103.336566246401;5144.511271429208;5147.871303217412;5157.277981082651;22479.438014049778;23642;23680.526389472285;24262.878685606458;24772.628410573856;1731.7402389641654;1873.6627407066724;2173.4349069241953;2354.5290941811704;2573.240893936293;9915.603375864957;10126.493675316278;10189.949050254776;10200.591976321013;12916.806247906288;1714.2028956535146;1850.8056654051265;1855.2857149997212;1869.130854152812;1876.7654043244595;114604;114902.85097149006;117691;118327;118647.40676296563;9773.729415175982;11191.608293709682;11302.943485282685;11481.88518114825;11554.59558915434;13553.728925421436;14190.432382704783;14196.71606567863;14220.928895355457;14227.92886035584;8126.349892008625;8228.177182281725;8237.46456480327;8385.622646980779;8406.915930840736;7996.600169991477;8050.959745201237;8064.596770161468;8151.429399941947;8159.388045896581;3699.963000370016;4321.222180007629;4397.428334316583;4486.910261794746;4499.257622492318;1837.9081045947648;1901.1919934027983;1948.415475357387;1953.404211715412;1967.2131147540813;371.77879161898625;373.19948710017405;399.0701665120263;415.14065883620617;664.773976847874;9364;11059.502322395512;11176.664700059011;11733.4719937603;11835;409.8749881286236;416.3442577939757;431.3271296777038;433.48198902312066;436.1582146457304;5814.476697097197;6042.909356359658;6095.116208149793;6112.144299798032;6135.233095863017;584018;585691;587021;590329;593512;92567;129827.70172298157;131082;133799.33100334436;135994;416.7832726982013;426.91888541177696;428.52433798483656;429.47174974781194;429.7808117859851;9970.551325190529;10222.48887555634;10807.621733239614;10823.837642435527;11144.996950274513;56258.71870640621;57307.713461432424;57868.84262314814;58826;59684.99999999826;78393.60803195948;81100.18899811163;81944.59027704824;82626.17373826184;83154;7710.730124445618;7821;7986.201379861967;8043.477173983672;8124.268815806606;12144.453499592717;12310.507579696712;12608.49566017349;12624.810627840407;12661.87338126607;7748.070231572247;8544.102869198645;8615.009273933578;10053.396796192252;10907.509162087566;9724.854127187957;10601.25791194609;10641.946790266;10981.560737570728;11086.778264434828;9128.45229286245;10819.242653014213;10950.452477376259;11375.31748095117;11377.886221138015;6108.389161084034;7986.321162701235;8073.919260807317;8098.352131829557;8196.934398528154;8475.830483390175;10178.949105254427;10346.275760696679;10487.26589138753;10542.947285263524 14:42:19 INFO - PROCESS | 1676 | |3;mootools.html;1471.8380978092407;1519.954401367959;1608.7345587977984;1691.780068591083;1816.0193495512424;7474.551526908389;7719.382449404048;7951.36389088873;8426.620802063906;8434.957825210873;7873.724419645314;8148.511089334644;8152.38857085718;8261.380396470258;8281.83436331273;2267.5124848157643;2268.54629074185;2295.529416469628;2297.2993237062688;2301.4821665125296;1940.8932508712014;2293.220305096267;2337.1703045418885;2347.8708671023087;2355.7762012608773;2287.4624463251207;2287.5196208796087;2311.3874823171873;2318.3160967514623;2325.4070212095976;1932.4589115047809;2267.478479949604;2276.5674521840797;2296.8621882687094;2297.6783250344797;1690.2393922734818;1883.962320753591;2321.5124823787023;2321.9419514512274;2327.813774898021;8422.157784221528;8460.2385785279;8478.491290522588;8503.426866029804;8512.70205542803;4956.3804524434445;5125.410577783566;5171.534561889411;5190.091733946544;5200.323957885488;3354.496825476174;3356.7314614830757;3509.5262139611336;3558.982205088984;3579.033660911565;927.2350310993457;1038.4703801061437;1075.833245846895;1089.6622047165404;1093.2729734726397;10415.947920260425;10483.423411712352;10742.355458672504;10749.946250268777;10911.345319280868;4258.808353624095;4312.159128969821;4369.410129632459;4433.623142032899;4444.733251023443;7072.858542829217;7840.823876418583;8588.22705956467;8598.58123409643;8602.956985215034;5002.1496345621335;5028.748562571856;5028.823991160365;5031.943291908667;5032.748362581856;4189.2459357315975;4276.72201306914;4494.325851122292;4651.348811166439;4696.7651617419;3699.9815000924823;3753.962460375416;3824.598417166213;3866.845326186977;3898.122922342507;1238.5603110895684;1261.5332327039055;1263.9684007899875;1275.7306480052407;1281.8205451236834;719.6473727873305;751.7030772844696;805.2672068417712;880.6125304865839;908.6183802802839;4595.828063843698;5458.699771512525;5530;5594.888102237933;5720.77116915327;337.7433150805406;382.43017903323846;382.91958688675135;424.9065205654751;433.986641192812;3153.8423078845967;3829.349010668193;3916.2167566486814;3937.3897045957924;3954.209158168378;9688.70933871985;10054.69835904924;10066.043725846033;10070.295079344522;10100.242481813893;9561.856572151424;9790.020997900154;9887.604495820087;9911.553980070914;10036.949815250877;6548.6725663716625;7587.241275872479;7760.689572417153;7778.416618753616;7804.180561041123;5346.652467589594;5445.079542729229;5478.753456094565;5722.341930677859;5812.82561523155;8991.14584114508;9328.673496427593;9369.765755856159;9416.95291523538;9475.431474111576;9262.814743705223;9280.118388753057;9335.113164249386;9434.811303774024;9539.618415263312;7163.713451462092;7197.028401165985;7260.238671054314;7269.45479089081;7301.817454563678;7835.37317014626;7871;7880.763577092696;7902.604869756374;7911.881321780293;7025.789226323219;7157.1769246537315;7167.820804479929;7218.061651985208;7259.854802903807;6920.204176519765;7023.33278338557;7025.332593403823;7124.715011399693;7154.606496642735;5544.972275138599;5707.942920570741;6576.901346479711;6666.133402657818;6673.532852700265;4791.496892826342;4794.880127996828;4824.565789079;4830.758462076812;4906.509349065065;4707.741074240949;4768.904621907612;4822.590079843252;4897.2654101885;4922.237053256751 14:42:19 INFO - PROCESS | 1676 | |4;prototype.html;293.96619388770296;298.56707773728095;304.3426199409276;308.48175065889296;310.2058729652091;4836.782344794484;5321.733913304329;5362.4637536246355;5467.644603100801;5491.176323551469;4915.926261106087;5196.948030519694;5310.76101575429;5346.759395827187;5400.486953739389;3565.9286814263633;4152.5432202457705;4156.397322388252;4160.375943608453;4182.870624931266;3819.4652748615076;4057.5536690963972;4167.979160104195;4172.16556688662;4186.246475634384;4006.4991876015497;4028.5769994150633;4034.293998550242;4036.899077523071;4076.9592304077023;3210;3721.9069523261865;4046.0289530512614;4050.5139383274195;4059.4722686050773;3076.8615412306276;3228.7094161525574;3257.9022629321157;3271.525628783837;3539.1859872229275;4309.956900430987;4898.069366820293;5165.63840531163;5184.248283998799;5238.371395432534;3987.501562304712;4014.7189696721243;4475.5748203920575;4550.203714349976;4620.561046700592;4870.9269360959615;4974.90050198994;5182.585393168538;5343.064963631349;5634.549236061105;3031.5452682097643;3355.76509644325;3403.196305957846;3665.9266814663824;3819.1216020315483;4859.684120532154;4943.456219815817;5315.335583052119;5914.319853216892;5937.970310148422;3155.8954365971726;3418.4872269159823;3878.767273963515;3883.3204189266767;3904.863329783501;3306.8842590509216;3649.284836127016;3983.9203215935518;3986.541547722049;4003.419504171937;4367.253098760521;4548.0216105993995;4577.359169716243;4588.587027167571;4621.676482646182;5715.571332150105;5725.713714314351;5735.050082971809;5739.827805165852;5747.39652336507;1211.3943028485758;1270.7140893298933;1320.650027742649;1333.286691619998;1356.2472827580787;820.446198815797;846.6740304982626;879.3580686099056;899.5277479323407;914.7621618379269;4807.975960120177;5289.471052894757;5292.221583668605;5308.708021058802;5336.813211537577;5185.792568297225;5742.9562331964125;5789.8552536186935;5832.562557808182;5853.619514731528;4812.398450193726;4972.602191824645;5260.421353651095;5285.920711189336;5333.413324534298;2423.757624237562;2462.5717084091184;2462.9261122166363;2485.347244082701;2553.80846436518;4130.256553820341;4494.438195225597;4771.137317609407;4995.525425084611;5053.772580233898;3980.681545476355;4502.244124791334;4695.558309678948;4767.13971617027;5044.924326135111;4338.414314067623;4766.35373938937;5027.748612569357;5107.825200204048;5125.949620151969;3917.0990672144994;4729.196036673705;4832.511256702731;4910.607151427806;4922.556969872729;4036.273470775289;4527.456705195266;4817.710937343771;4820.276958456259;5336.546393556591;5808.273965754281;5953.613015154001;5994.100884867305;6070.544709146743;6108.5723999319625;4019.91960160801;4072.165206132795;4081.510218773766;4095.8156882940934;4108.281050816155;5692.573057020657;5754.108113242454;5812.883742325045;5889.970550147222;5933.258342707161;5326.256977656588;5709.942900570941;5743.770249189985;5791.247137872218;5798.246227990334;5246.737663116905;5285.656432331886;5700.144978253295;5733.799317023884;5738.48353648157;5393.271908292487;5519.834404967857;5592.580556458201;5643.068893632505;5670.319561652628;4323.654107671442;4693.5306469354155;4745.833895813768;5625.690587017753;5729.083346664514;4600.838970636011;4967.3045773591;5571.8885622288135;5626.803061892814;5658.217661436634 14:42:19 INFO - PROCESS | 1676 | |5;yui.html;588.4086493074459;616.9938300616994;678.7963610916721;683.6035099642206;799.0211990311868;14099.295035248235;15002.324895379707;15045.92477037616;15070.773938390932;15117.773233401507;14586;15352.616184595363;15369.69260614787;15381.077135371885;15586.298616562253;830.8712149616804;851.093585331623;856.4647095565272;857.8455877941968;859.7119964811795;663.7477758451781;680.3094858718405;811.6388207247777;833.154348336436;849.8357250566733;880.3969281042474;887.3300658003199;889.1419779912384;890.3945317184312;891.6299735609714;858.1375717404031;879.6525372477868;880.5949263338872;880.6125304865839;881.9823603527893;563.9915401268984;584.5557376393929;634.8571571396445;647.7311915555086;648.8896887529131;14384.424623015067;14564.344604492717;14704.779428308584;14851.183184924825;14876.256187190706;13417.932910335387;13434.261115638636;13475.86524134756;13547.390367433489;13871.237081960488;11460;11627.94186029073;11643.06855451562;11717.179797414185;11845.289282642983;3607.639236076371;3702.185519185775;3721.0511319613324;3796.0509872531866;3838.0021194489354;83994.16005839924;87164.5641771787;87898.56050719705;87944;89770;10469.738256543495;10578.841317380246;10642.680719578426;10860.45697715111;11150.776984460428;16471.91764041172;20488.79511204869;20854.687179692315;21075.68386474204;21293.36119916405;7817.5309481431295;7825;7976.52140871538;7988.400869934778;8160.836783264302;4342.326939324346;4393.846215382447;4426.004149066434;4439.200943830077;4531.229690952546;3491.9301613967577;3542.91142721434;3599.3521166190335;3607.4228123500116;3612.7290453216115;666.0907860770111;667.302668711198;671.5668393885916;676.0433985909981;685.1880521581942;418.2033226703135;539.3743257820909;574.2133277409976;590.670960656567;646.7445359083135;8818.206361427503;9075.863862042075;9112.908870911338;9219.308551858638;9242.168204861595;547.2857920413898;547.9753411096431;606.6663335165641;626.5645376463406;656.4256275758711;4177.352510360899;4440.089781594766;4482.305242687388;4513.119941611354;4545.272756358967;87272.81817954475;107335.46332268289;109217;112581;115312.42343788227;73281.6335918317;83077.58461207655;83492;99004;99972.50013749885;72205;75929;80737.19262807415;81923.7711434329;82120;9297.907020929704;9405.482698451651;9422.95288523553;9493.477858717837;9495.620175192915;49945;50355.74822125866;50681.746591266805;52388.47611523836;54150.72924635509;34420.82789586136;46929;62138;74315;74353;10343.741406464898;11972.527937058827;12098.576549820715;12103.818442723543;12110.818337724764;11893.226940248856;13565.525206617722;13862.098963567336;14157.292135393394;14467.782983255049;11719.945204931597;12224.266544007389;12556.937215313865;12781.04142189321;12908.935455322664;12402.13185077036;12481.188722732993;12772;13060.085793994329;13104.279264640536;12460.376981151088;12470;12755.80866287025;12768.297743624189;13081.411336490071;9852.655157069756;10303.175745940156;10432.374057556268;10434.06093451593;11086.113110951266;9191.494467804068;9879.308448408543;10024.699259022242;11017.83473247886;11113.611023614134 14:42:19 INFO - PROCESS | 1676 | __end_tp_report 14:42:19 INFO - PROCESS | 1676 | __start_cc_report 14:42:19 INFO - PROCESS | 1676 | _x_x_mozilla_cycle_collect,384 14:42:19 INFO - PROCESS | 1676 | __end_cc_report 14:42:19 INFO - PROCESS | 1676 | __startTimestamp1477950139190__endTimestamp 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | ------- Summary: start ------- 14:42:19 INFO - PROCESS | 1676 | Number of tests: 6 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#0] dojo.html Cycles:165 Average:5697.90 Median:5897.00 stddev:685.45 (11.6%) stddev-sans-first:647.84 14:42:19 INFO - PROCESS | 1676 | Values: 2766.7 3372.2 3378.9 3412.9 3438.7 5627.2 5676.5 5679.7 5704.2 5711.2 5530.8 5688.0 5708.5 5766.9 5776.3 4160.3 4661.4 4903.1 5239.4 5241.6 4469.9 5166.4 5230.7 5337.9 5373.1 5079.6 5252.2 5274.4 5287.5 5335.1 5087.7 5100.3 5118.3 5184.5 5258.5 5105.9 5136.7 5162.6 5180.1 5210.2 5586.2 5699.0 5701.2 5724.0 5727.0 6093.5 6112.2 6146.0 6158.9 6180.8 6258.1 6272.8 6280.2 6294.6 6308.8 5797.3 5814.3 5829.8 5860.0 5897.0 5915.3 5979.1 6194.3 6208.3 6230.5 6144.5 6194.5 6213.9 6218.1 6219.4 5236.5 6238.4 6270.9 6286.7 6290.0 5070.3 6025.4 6029.8 6117.6 6213.8 6110.8 6170.9 6233.8 6242.7 6246.0 4111.5 4914.3 4920.7 4928.5 4987.7 4349.1 4395.9 4422.9 4425.2 4451.4 5754.1 5819.3 5882.1 5924.6 5974.9 6188.1 6215.8 6250.7 6297.5 6307.2 4764.9 5643.1 5787.4 5881.5 5928.0 5346.3 5378.4 5417.8 5428.8 5478.9 6311.3 6349.3 6361.7 6361.9 6375.5 6258.6 6309.0 6314.5 6320.0 6324.3 6215.1 6216.6 6218.2 6228.7 6258.7 6207.3 6241.0 6246.7 6247.8 6248.1 6276.0 6287.3 6293.2 6298.5 6323.7 5894.8 6018.9 6068.4 6099.0 6184.9 5345.8 5798.3 6091.6 6129.4 6133.2 5752.6 5778.4 5984.5 5985.3 5987.3 5884.0 6018.3 6085.1 6130.1 6144.9 5257.7 5890.0 5894.8 5996.7 6090.9 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#1] ext.html Cycles:180 Average:71354.80 Median:42110.58 stddev:154652.32 (367.3%) stddev-sans-first:154994.54 14:42:19 INFO - PROCESS | 1676 | Values: 454.6 484.1 496.5 507.6 537.2 40652.8 42023.8 42197.4 42311.0 42327.6 42497.4 42968.6 43014.0 43094.3 43258.0 9414.2 9466.6 9585.1 9597.8 9604.6 9468.1 9556.2 9576.0 9600.8 9603.2 9587.9 9590.9 9625.0 9635.2 9638.0 7579.8 8574.5 9525.9 9557.8 9648.5 9506.1 9552.9 9561.4 9561.7 9570.6 40570.4 40607.0 40986.8 41166.0 41349.4 3277.1 3486.3 3720.3 3888.3 3985.1 25789.5 29605.0 30170.2 30323.0 30883.8 3163.5 3170.1 3219.2 3300.8 3352.8 410638.8 466311.0 480089.0 481048.6 481610.0 61342.7 61393.1 61564.4 61599.0 61645.0 63413.0 63980.0 64058.4 64075.0 64122.4 13737.7 15736.2 15804.4 15818.8 15879.5 11917.9 12086.3 12141.8 12144.9 12166.6 17275.7 17356.7 17608.8 17620.4 17765.0 3136.6 3228.0 5999.4 6042.4 6051.0 852.2 888.2 940.0 970.0 1003.5 26520.8 27535.6 30263.8 30437.9 30534.2 906.9 1061.6 1063.5 1081.2 1089.3 14962.9 15080.8 15487.6 15842.9 15898.9 632648.0 637219.0 637401.9 638856.0 640793.0 58218.0 58622.7 58679.1 58686.1 58742.0 1311.2 1343.9 1370.4 1370.8 1371.6 29483.4 30598.5 30746.2 31443.8 32050.4 56216.7 57090.0 57095.9 57257.0 57645.7 613267.0 618857.9 619045.0 619639.8 622201.0 41861.8 42725.8 42782.6 43580.6 43747.8 46902.8 47922.8 48224.5 48546.8 48641.0 39089.0 39226.0 39258.0 39943.8 40608.2 39394.4 39473.6 40041.4 40476.8 41120.0 38242.6 38574.8 38691.2 38950.6 39098.0 33280.8 34539.0 34925.3 35203.6 35405.5 21013.5 25834.9 25872.6 26199.5 27333.7 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#2] jquery.html Cycles:180 Average:33926.82 Median:416.56 stddev:98451.58 (23634.2%) stddev-sans-first:98695.43 14:42:19 INFO - PROCESS | 1676 | Values: 324.9 329.2 374.3 383.8 400.0 19088.4 20153.0 20447.0 20805.3 20829.9 19241.0 19512.9 19920.2 19930.3 20112.6 5686.8 5759.8 5800.9 5960.1 6007.9 5122.7 5814.8 5934.4 5953.6 5959.3 5754.4 5860.3 5878.3 5879.6 5908.6 4950.8 5843.3 5914.9 5915.6 5924.4 4931.8 5103.3 5144.5 5147.9 5157.3 22479.4 23642.0 23680.5 24262.9 24772.6 1731.7 1873.7 2173.4 2354.5 2573.2 9915.6 10126.5 10189.9 10200.6 12916.8 1714.2 1850.8 1855.3 1869.1 1876.8 114604.0 114902.9 117691.0 118327.0 118647.4 9773.7 11191.6 11302.9 11481.9 11554.6 13553.7 14190.4 14196.7 14220.9 14227.9 8126.3 8228.2 8237.5 8385.6 8406.9 7996.6 8051.0 8064.6 8151.4 8159.4 3700.0 4321.2 4397.4 4486.9 4499.3 1837.9 1901.2 1948.4 1953.4 1967.2 371.8 373.2 399.1 415.1 664.8 9364.0 11059.5 11176.7 11733.5 11835.0 409.9 416.3 431.3 433.5 436.2 5814.5 6042.9 6095.1 6112.1 6135.2 584018.0 585691.0 587021.0 590329.0 593512.0 92567.0 129827.7 131082.0 133799.3 135994.0 416.8 426.9 428.5 429.5 429.8 9970.6 10222.5 10807.6 10823.8 11145.0 56258.7 57307.7 57868.8 58826.0 59685.0 78393.6 81100.2 81944.6 82626.2 83154.0 7710.7 7821.0 7986.2 8043.5 8124.3 12144.5 12310.5 12608.5 12624.8 12661.9 7748.1 8544.1 8615.0 10053.4 10907.5 9724.9 10601.3 10641.9 10981.6 11086.8 9128.5 10819.2 10950.5 11375.3 11377.9 6108.4 7986.3 8073.9 8098.4 8196.9 8475.8 10178.9 10346.3 10487.3 10542.9 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#3] mootools.html Cycles:180 Average:5331.25 Median:4979.27 stddev:2948.38 (59.2%) stddev-sans-first:2942.39 14:42:19 INFO - PROCESS | 1676 | Values: 1471.8 1520.0 1608.7 1691.8 1816.0 7474.6 7719.4 7951.4 8426.6 8435.0 7873.7 8148.5 8152.4 8261.4 8281.8 2267.5 2268.5 2295.5 2297.3 2301.5 1940.9 2293.2 2337.2 2347.9 2355.8 2287.5 2287.5 2311.4 2318.3 2325.4 1932.5 2267.5 2276.6 2296.9 2297.7 1690.2 1884.0 2321.5 2321.9 2327.8 8422.2 8460.2 8478.5 8503.4 8512.7 4956.4 5125.4 5171.5 5190.1 5200.3 3354.5 3356.7 3509.5 3559.0 3579.0 927.2 1038.5 1075.8 1089.7 1093.3 10415.9 10483.4 10742.4 10749.9 10911.3 4258.8 4312.2 4369.4 4433.6 4444.7 7072.9 7840.8 8588.2 8598.6 8603.0 5002.1 5028.7 5028.8 5031.9 5032.7 4189.2 4276.7 4494.3 4651.3 4696.8 3700.0 3754.0 3824.6 3866.8 3898.1 1238.6 1261.5 1264.0 1275.7 1281.8 719.6 751.7 805.3 880.6 908.6 4595.8 5458.7 5530.0 5594.9 5720.8 337.7 382.4 382.9 424.9 434.0 3153.8 3829.3 3916.2 3937.4 3954.2 9688.7 10054.7 10066.0 10070.3 10100.2 9561.9 9790.0 9887.6 9911.6 10036.9 6548.7 7587.2 7760.7 7778.4 7804.2 5346.7 5445.1 5478.8 5722.3 5812.8 8991.1 9328.7 9369.8 9417.0 9475.4 9262.8 9280.1 9335.1 9434.8 9539.6 7163.7 7197.0 7260.2 7269.5 7301.8 7835.4 7871.0 7880.8 7902.6 7911.9 7025.8 7157.2 7167.8 7218.1 7259.9 6920.2 7023.3 7025.3 7124.7 7154.6 5545.0 5707.9 6576.9 6666.1 6673.5 4791.5 4794.9 4824.6 4830.8 4906.5 4707.7 4768.9 4822.6 4897.3 4922.2 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#4] prototype.html Cycles:180 Average:4397.21 Median:4818.99 stddev:1372.51 (28.5%) stddev-sans-first:1341.36 14:42:19 INFO - PROCESS | 1676 | Values: 294.0 298.6 304.3 308.5 310.2 4836.8 5321.7 5362.5 5467.6 5491.2 4915.9 5196.9 5310.8 5346.8 5400.5 3565.9 4152.5 4156.4 4160.4 4182.9 3819.5 4057.6 4168.0 4172.2 4186.2 4006.5 4028.6 4034.3 4036.9 4077.0 3210.0 3721.9 4046.0 4050.5 4059.5 3076.9 3228.7 3257.9 3271.5 3539.2 4310.0 4898.1 5165.6 5184.2 5238.4 3987.5 4014.7 4475.6 4550.2 4620.6 4870.9 4974.9 5182.6 5343.1 5634.5 3031.5 3355.8 3403.2 3665.9 3819.1 4859.7 4943.5 5315.3 5914.3 5938.0 3155.9 3418.5 3878.8 3883.3 3904.9 3306.9 3649.3 3983.9 3986.5 4003.4 4367.3 4548.0 4577.4 4588.6 4621.7 5715.6 5725.7 5735.1 5739.8 5747.4 1211.4 1270.7 1320.7 1333.3 1356.2 820.4 846.7 879.4 899.5 914.8 4808.0 5289.5 5292.2 5308.7 5336.8 5185.8 5743.0 5789.9 5832.6 5853.6 4812.4 4972.6 5260.4 5285.9 5333.4 2423.8 2462.6 2462.9 2485.3 2553.8 4130.3 4494.4 4771.1 4995.5 5053.8 3980.7 4502.2 4695.6 4767.1 5044.9 4338.4 4766.4 5027.7 5107.8 5125.9 3917.1 4729.2 4832.5 4910.6 4922.6 4036.3 4527.5 4817.7 4820.3 5336.5 5808.3 5953.6 5994.1 6070.5 6108.6 4019.9 4072.2 4081.5 4095.8 4108.3 5692.6 5754.1 5812.9 5890.0 5933.3 5326.3 5709.9 5743.8 5791.2 5798.2 5246.7 5285.7 5700.1 5733.8 5738.5 5393.3 5519.8 5592.6 5643.1 5670.3 4323.7 4693.5 4745.8 5625.7 5729.1 4600.8 4967.3 5571.9 5626.8 5658.2 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - PROCESS | 1676 | [#5] yui.html Cycles:180 Average:19549.62 Median:4522.17 stddev:28179.51 (623.1%) stddev-sans-first:28222.60 14:42:19 INFO - PROCESS | 1676 | Values: 588.4 617.0 678.8 683.6 799.0 14099.3 15002.3 15045.9 15070.8 15117.8 14586.0 15352.6 15369.7 15381.1 15586.3 830.9 851.1 856.5 857.8 859.7 663.7 680.3 811.6 833.2 849.8 880.4 887.3 889.1 890.4 891.6 858.1 879.7 880.6 880.6 882.0 564.0 584.6 634.9 647.7 648.9 14384.4 14564.3 14704.8 14851.2 14876.3 13417.9 13434.3 13475.9 13547.4 13871.2 11460.0 11627.9 11643.1 11717.2 11845.3 3607.6 3702.2 3721.1 3796.1 3838.0 83994.2 87164.6 87898.6 87944.0 89770.0 10469.7 10578.8 10642.7 10860.5 11150.8 16471.9 20488.8 20854.7 21075.7 21293.4 7817.5 7825.0 7976.5 7988.4 8160.8 4342.3 4393.8 4426.0 4439.2 4531.2 3491.9 3542.9 3599.4 3607.4 3612.7 666.1 667.3 671.6 676.0 685.2 418.2 539.4 574.2 590.7 646.7 8818.2 9075.9 9112.9 9219.3 9242.2 547.3 548.0 606.7 626.6 656.4 4177.4 4440.1 4482.3 4513.1 4545.3 87272.8 107335.5 109217.0 112581.0 115312.4 73281.6 83077.6 83492.0 99004.0 99972.5 72205.0 75929.0 80737.2 81923.8 82120.0 9297.9 9405.5 9423.0 9493.5 9495.6 49945.0 50355.7 50681.7 52388.5 54150.7 34420.8 46929.0 62138.0 74315.0 74353.0 10343.7 11972.5 12098.6 12103.8 12110.8 11893.2 13565.5 13862.1 14157.3 14467.8 11719.9 12224.3 12556.9 12781.0 12908.9 12402.1 12481.2 12772.0 13060.1 13104.3 12460.4 12470.0 12755.8 12768.3 13081.4 9852.7 10303.2 10432.4 10434.1 11086.1 9191.5 9879.3 10024.7 11017.8 11113.6 14:42:19 INFO - PROCESS | 1676 | -------- Summary: end -------- 14:42:19 INFO - PROCESS | 1676 | 14:42:19 INFO - TEST-INFO | 1676: exit 0 14:42:19 INFO - TEST-OK | dromaeo_css | took 1114889ms 14:42:19 INFO - TEST-START | kraken 14:42:19 INFO - operating with platform_type : mac_ 14:42:19 INFO - Initialising browser for kraken test... 14:42:19 INFO - TEST-INFO | started process 1799 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmp2oofD9/profile http://localhost:49275/getInfo.html) 14:42:22 INFO - PROCESS | 1799 | 2016-10-31 14:42:22.634 plugin-container[1800:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 14:42:22 INFO - PROCESS | 1799 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:22 INFO - PROCESS | 1799 | 2016-10-31 14:42:22.637 plugin-container[1800:10100] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b27, name = 'com.apple.CFPasteboardClient' 14:42:22 INFO - PROCESS | 1799 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:22 INFO - PROCESS | 1799 | 2016-10-31 14:42:22.638 plugin-container[1800:10100] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:42:22 INFO - PROCESS | 1799 | __metrics Screen width/height:1600/1200 14:42:22 INFO - PROCESS | 1799 | colorDepth:24 14:42:22 INFO - PROCESS | 1799 | Browser inner width/height: 1024/689 14:42:22 INFO - PROCESS | 1799 | __metrics 14:42:22 ERROR - PROCESS | 1799 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3131: TypeError: this.tabs is undefined 14:42:23 INFO - TEST-INFO | 1799: exit 0 14:42:23 INFO - Browser initialized. 14:42:23 INFO - Running cycle 1/1 for kraken test... 14:42:23 INFO - TEST-INFO | started process 1801 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/tmp2oofD9/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:42:26 INFO - PROCESS | 1801 | 2016-10-31 14:42:26.167 plugin-container[1802:10214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 14:42:26 INFO - PROCESS | 1801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:26 INFO - PROCESS | 1801 | 2016-10-31 14:42:26.167 plugin-container[1802:10214] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4423, name = 'com.apple.CFPasteboardClient' 14:42:26 INFO - PROCESS | 1801 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:42:26 INFO - PROCESS | 1801 | 2016-10-31 14:42:26.167 plugin-container[1802:10214] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:42:35 INFO - PROCESS | 1801 | RSS: Main: 240050176 14:42:35 INFO - PROCESS | 1801 | 14:42:39 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection) 14:42:40 INFO - PROCESS | 1801 | RSS: Main: 252526592 14:42:40 INFO - PROCESS | 1801 | 14:42:42 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-dft) 14:42:43 INFO - PROCESS | 1801 | RSS: Main: 260136960 14:42:43 INFO - PROCESS | 1801 | 14:42:46 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-fft) 14:42:47 INFO - PROCESS | 1801 | RSS: Main: 263184384 14:42:47 INFO - PROCESS | 1801 | 14:42:49 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator) 14:42:50 INFO - PROCESS | 1801 | RSS: Main: 264192000 14:42:50 INFO - PROCESS | 1801 | 14:42:52 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:42:52 INFO - PROCESS | 1801 | RSS: Main: 264503296 14:42:52 INFO - PROCESS | 1801 | 14:42:55 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom) 14:42:56 INFO - PROCESS | 1801 | RSS: Main: 265965568 14:42:56 INFO - PROCESS | 1801 | 14:42:59 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate) 14:42:59 INFO - PROCESS | 1801 | RSS: Main: 266264576 14:42:59 INFO - PROCESS | 1801 | 14:43:03 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial) 14:43:03 INFO - PROCESS | 1801 | RSS: Main: 266219520 14:43:03 INFO - PROCESS | 1801 | 14:43:05 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:43:05 INFO - PROCESS | 1801 | RSS: Main: 266158080 14:43:05 INFO - PROCESS | 1801 | 14:43:07 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:43:08 INFO - PROCESS | 1801 | RSS: Main: 266186752 14:43:08 INFO - PROCESS | 1801 | 14:43:10 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:43:11 INFO - PROCESS | 1801 | RSS: Main: 266313728 14:43:11 INFO - PROCESS | 1801 | 14:43:14 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:43:15 INFO - PROCESS | 1801 | RSS: Main: 266723328 14:43:15 INFO - PROCESS | 1801 | 14:43:18 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:43:18 INFO - PROCESS | 1801 | RSS: Main: 266452992 14:43:18 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | Cycle 1(1): loaded http://localhost:49275/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49275/tests/kraken/driver.html?testName=ai-astar) 14:43:20 INFO - PROCESS | 1801 | RSS: Main: 268042240 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | __start_tp_report 14:43:20 INFO - PROCESS | 1801 | _x_x_mozilla_page_load 14:43:20 INFO - PROCESS | 1801 | _x_x_mozilla_page_load_details 14:43:20 INFO - PROCESS | 1801 | |i|pagename|runs| 14:43:20 INFO - PROCESS | 1801 | |0;ai-astar;107;117;119;117;171;111;174;175;158;116 14:43:20 INFO - PROCESS | 1801 | |1;audio-beat-detection;154;147;112;192;149;146;117;157;142;120 14:43:20 INFO - PROCESS | 1801 | |2;audio-dft;276;238;202;198;198;199;196;198;199;197 14:43:20 INFO - PROCESS | 1801 | |3;audio-fft;82;58;58;84;60;63;87;60;79;50 14:43:20 INFO - PROCESS | 1801 | |4;audio-oscillator;90;70;90;74;74;72;88;70;72;91 14:43:20 INFO - PROCESS | 1801 | |5;imaging-gaussian-blur;91;89;89;87;90;86;92;94;90;87 14:43:20 INFO - PROCESS | 1801 | |6;imaging-darkroom;96;96;150;155;97;96;98;96;102;91 14:43:20 INFO - PROCESS | 1801 | |7;imaging-desaturate;99;144;135;89;130;136;115;140;117;141 14:43:20 INFO - PROCESS | 1801 | |8;json-parse-financial;60;60;77;57;56;55;57;58;46;50 14:43:20 INFO - PROCESS | 1801 | |9;json-stringify-tinderbox;69;71;86;78;72;67;60;78;108;62 14:43:20 INFO - PROCESS | 1801 | |10;stanford-crypto-aes;94;89;91;93;86;103;100;109;79;104 14:43:20 INFO - PROCESS | 1801 | |11;stanford-crypto-ccm;150;152;160;146;156;156;138;184;192;147 14:43:20 INFO - PROCESS | 1801 | |12;stanford-crypto-pbkdf2;177;178;174;189;176;176;177;175;176;175 14:43:20 INFO - PROCESS | 1801 | |13;stanford-crypto-sha256-iterative;62;91;92;94;92;66;71;67;67;69 14:43:20 INFO - PROCESS | 1801 | __end_tp_report 14:43:20 INFO - PROCESS | 1801 | __start_cc_report 14:43:20 INFO - PROCESS | 1801 | _x_x_mozilla_cycle_collect,979 14:43:20 INFO - PROCESS | 1801 | __end_cc_report 14:43:20 INFO - PROCESS | 1801 | __startTimestamp1477950200946__endTimestamp 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | ------- Summary: start ------- 14:43:20 INFO - PROCESS | 1801 | Number of tests: 14 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#0] ai-astar Cycles:10 Average:136.50 Median:138.50 stddev:28.96 (20.9%) stddev-sans-first:28.68 14:43:20 INFO - PROCESS | 1801 | Values: 107.0 117.0 119.0 117.0 171.0 111.0 174.0 175.0 158.0 116.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#1] audio-beat-detection Cycles:10 Average:143.60 Median:148.00 stddev:23.43 (15.8%) stddev-sans-first:24.55 14:43:20 INFO - PROCESS | 1801 | Values: 154.0 147.0 112.0 192.0 149.0 146.0 117.0 157.0 142.0 120.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#2] audio-dft Cycles:10 Average:210.10 Median:199.00 stddev:26.34 (13.2%) stddev-sans-first:13.31 14:43:20 INFO - PROCESS | 1801 | Values: 276.0 238.0 202.0 198.0 198.0 199.0 196.0 198.0 199.0 197.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#3] audio-fft Cycles:10 Average:68.10 Median:71.00 stddev:13.38 (18.8%) stddev-sans-first:13.21 14:43:20 INFO - PROCESS | 1801 | Values: 82.0 58.0 58.0 84.0 60.0 63.0 87.0 60.0 79.0 50.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#4] audio-oscillator Cycles:10 Average:79.10 Median:81.00 stddev:9.29 (11.5%) stddev-sans-first:8.98 14:43:20 INFO - PROCESS | 1801 | Values: 90.0 70.0 90.0 74.0 74.0 72.0 88.0 70.0 72.0 91.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#5] imaging-gaussian-blur Cycles:10 Average:89.50 Median:90.00 stddev:2.46 (2.7%) stddev-sans-first:2.55 14:43:20 INFO - PROCESS | 1801 | Values: 91.0 89.0 89.0 87.0 90.0 86.0 92.0 94.0 90.0 87.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#6] imaging-darkroom Cycles:10 Average:107.70 Median:96.00 stddev:23.79 (24.8%) stddev-sans-first:24.85 14:43:20 INFO - PROCESS | 1801 | Values: 96.0 96.0 150.0 155.0 97.0 96.0 98.0 96.0 102.0 91.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#7] imaging-desaturate Cycles:10 Average:124.60 Median:140.50 stddev:18.92 (13.5%) stddev-sans-first:17.66 14:43:20 INFO - PROCESS | 1801 | Values: 99.0 144.0 135.0 89.0 130.0 136.0 115.0 140.0 117.0 141.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#8] json-parse-financial Cycles:10 Average:57.60 Median:57.50 stddev:8.10 (14.1%) stddev-sans-first:8.54 14:43:20 INFO - PROCESS | 1801 | Values: 60.0 60.0 77.0 57.0 56.0 55.0 57.0 58.0 46.0 50.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:71.50 stddev:13.93 (19.5%) stddev-sans-first:14.60 14:43:20 INFO - PROCESS | 1801 | Values: 69.0 71.0 86.0 78.0 72.0 67.0 60.0 78.0 108.0 62.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#10] stanford-crypto-aes Cycles:10 Average:94.80 Median:87.50 stddev:9.19 (10.5%) stddev-sans-first:9.74 14:43:20 INFO - PROCESS | 1801 | Values: 94.0 89.0 91.0 93.0 86.0 103.0 100.0 109.0 79.0 104.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#11] stanford-crypto-ccm Cycles:10 Average:158.10 Median:156.00 stddev:17.03 (10.9%) stddev-sans-first:17.80 14:43:20 INFO - PROCESS | 1801 | Values: 150.0 152.0 160.0 146.0 156.0 156.0 138.0 184.0 192.0 147.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:177.30 Median:176.50 stddev:4.27 (2.4%) stddev-sans-first:4.53 14:43:20 INFO - PROCESS | 1801 | Values: 177.0 178.0 174.0 189.0 176.0 176.0 177.0 175.0 176.0 175.0 14:43:20 INFO - PROCESS | 1801 | 14:43:20 INFO - PROCESS | 1801 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:77.10 Median:81.00 stddev:13.25 (16.4%) stddev-sans-first:12.88 14:43:20 INFO - PROCESS | 1801 | Values: 62.0 91.0 92.0 94.0 92.0 66.0 71.0 67.0 67.0 69.0 14:43:20 INFO - PROCESS | 1801 | -------- Summary: end -------- 14:43:20 INFO - PROCESS | 1801 | 14:43:21 INFO - TEST-INFO | 1801: exit 0 14:43:21 INFO - TEST-OK | kraken | took 61871ms 14:43:21 INFO - SUITE-END | took 1176s 14:43:22 INFO - Completed test suite (00:19:37) 14:43:22 INFO - javascript benchmark 14:43:22 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6957.719544887193, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2766.681831589367, 3372.22438839067, 3378.864845406184, 3412.8805491807793, 3438.6733260340266, 5627.184058311543, 5676.545876329888, 5679.716014199289, 5704.201411802341, 5711.228984087147, 5530.751116199771, 5688.0, 5708.514776244014, 5766.942330576693, 5776.277965254343, 4160.334346504553, 4661.3707149534885, 4903.068417000776, 5239.449857764937, 5241.580673546107, 4469.865904022885, 5166.380034395877, 5230.6600070995455, 5337.919931201035, 5373.086575282192, 5079.568236699885, 5252.23842542833, 5274.393444753864, 5287.47125287472, 5335.093034184198, 5087.694738315713, 5100.311457953203, 5118.309028281209, 5184.507471790174, 5258.474152584749, 5105.923411148836, 5136.743162841843, 5162.638615296931, 5180.1452760294505, 5210.244514545408, 5586.24585680932, 5699.002674531982, 5701.232617888468, 5724.0, 5727.0, 6093.512518998469, 6112.205413296288, 6145.955187618116, 6158.907616385758, 6180.81457556274, 6258.123862659231, 6272.780452684134, 6280.214973128359, 6294.559380843343, 6308.810735677937, 5797.333306669745, 5814.331351894544, 5829.8251052468495, 5860.0, 5897.0, 5915.260592425947, 5979.133025711334, 6194.287656919422, 6208.348123447064, 6230.501559875199, 6144.5084393248435, 6194.473469755031, 6213.850437668955, 6218.129461875341, 6219.440250377397, 5236.528712415901, 6238.407351301619, 6270.9372906270355, 6286.717097730613, 6289.96855015722, 5070.29015937769, 6025.427584379477, 6029.788957386558, 6117.571769976148, 6213.813585592439, 6110.832160913912, 6170.876582468325, 6233.781817636361, 6242.687865606701, 6246.0, 4111.506619205715, 4914.311996320518, 4920.704757714549, 4928.457869634337, 4987.675801072919, 4349.06495103548, 4395.890102747393, 4422.933655995163, 4425.247707889666, 4451.421315229064, 5754.0505816540635, 5819.301683797971, 5882.058870580772, 5924.555658325643, 5974.910376344358, 6188.1027251048345, 6215.782447614402, 6250.653912263676, 6297.527685423612, 6307.180066591313, 4764.904701906012, 5643.125315576092, 5787.421257874263, 5881.529477641779, 5928.0, 5346.278252435949, 5378.435264297271, 5417.83746487606, 5428.809991650273, 5478.863028424242, 6311.337309582428, 6349.269833969152, 6361.650109243851, 6361.872762544724, 6375.489960803217, 6258.561900666909, 6309.0, 6314.526410519137, 6319.957207060784, 6324.304326523987, 6215.06773983906, 6216.627002379872, 6218.19163508741, 6228.657423841551, 6258.718357673824, 6207.255129384503, 6241.001439769615, 6246.6876656167915, 6247.843803904939, 6248.094026366242, 6276.0, 6287.339829317856, 6293.181886354744, 6298.496120310272, 6323.683815809283, 5894.764209431574, 6018.909716354171, 6068.362821903813, 6099.024156134997, 6184.8763024738355, 5345.839624811262, 5798.275215598051, 6091.573589848846, 6129.358790882775, 6133.202683651097, 5752.626079304831, 5778.36437991829, 5984.491318237999, 5985.341612422543, 5987.31145918225, 5884.029135192816, 6018.337982821974, 6085.1480792689945, 6130.141780150693, 6144.938550614615, 5257.658252213594, 5889.970550147222, 5894.764209431746, 5996.730147143302, 6090.878182436238], "value": 5652.883009376262, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [454.6317482838901, 484.14548322211306, 496.5332587367873, 507.6243579750984, 537.2129829799343, 40652.79673601632, 42023.78988105059, 42197.367039494355, 42311.0, 42327.57672423275, 42497.36253956193, 42968.57031429685, 43014.0, 43094.27622895088, 43258.0, 9414.152726254635, 9466.62133514662, 9585.137337639611, 9597.752292202003, 9604.567794449267, 9468.147866691996, 9556.23550115989, 9575.994520575343, 9600.759981000461, 9603.2317414607, 9587.904120958805, 9590.856137157949, 9624.951875240615, 9635.18100961419, 9638.0, 7579.810504737398, 8574.485530868136, 9525.857112143358, 9557.761055973588, 9648.469334186617, 9506.096920792514, 9552.90447095534, 9561.426314421156, 9561.713148605553, 9570.56932438035, 40570.39144412836, 40607.0, 40986.79506602478, 41166.0, 41349.379759303636, 3277.147941535218, 3486.2853115111343, 3720.325853365969, 3888.2806680763865, 3985.0834308109297, 25789.484210315877, 29605.0, 30170.245743856358, 30323.0, 30883.845580772177, 3163.4622114240633, 3170.080676603782, 3219.17910932713, 3300.7689461737687, 3352.8155951422664, 410638.78722425684, 466311.0, 480089.0, 481048.59475702746, 481610.0, 61342.69328653417, 61393.07910381348, 61564.38435615676, 61599.0, 61645.0, 63413.0, 63980.0, 64058.35941640617, 64075.03887441692, 64122.35877641257, 13737.725245495234, 15736.213189340488, 15804.36782528709, 15818.841811581737, 15879.52361429159, 11917.880821191677, 12086.274823510617, 12141.757164856654, 12144.90695837379, 12166.635000949986, 17275.654486910193, 17356.65286694259, 17608.823911760974, 17620.383286584907, 17765.0, 3136.623605167349, 3227.967720322814, 5999.400059993965, 6042.425969532887, 6050.971334873082, 852.1904191018433, 888.2139306713605, 939.9953000234955, 969.95150242489, 1003.4932359158578, 26520.80656370468, 27535.586966195522, 30263.848680756455, 30437.934672286363, 30534.236644084074, 906.859436787299, 1061.6496556136456, 1063.5426766490484, 1081.2377274021796, 1089.2810744908322, 14962.925185374004, 15080.849191508163, 15487.612809679847, 15842.920785396, 15898.920505397631, 632648.0, 637219.0, 637401.9428931361, 638856.0, 640793.0, 58218.0, 58622.7068864653, 58679.11981320284, 58686.11970820356, 58742.0, 1311.2329287366997, 1343.9126456780277, 1370.376478702188, 1370.8149399830895, 1371.6022353517567, 29483.41033179367, 30598.541021885136, 30746.231344215677, 31443.842780785948, 32050.35899282048, 56216.71891640516, 57090.0, 57095.85808283894, 57257.0, 57645.711771440874, 613267.0, 618857.9057104685, 619045.0, 619639.8396176507, 622201.0, 41861.79069104635, 42725.786371067945, 42782.57217427786, 43580.56419435765, 43747.78126109349, 46902.76548617235, 47922.76038619784, 48224.517754822, 48546.75726621344, 48641.0, 39089.022774430865, 39226.0, 39258.01854953649, 39943.80028099841, 40608.18783624252, 39394.40908386319, 39473.60526394699, 40041.39937900992, 40476.80437930127, 41119.972000700225, 38242.6175738239, 38574.84275471741, 38691.22617547577, 38950.61049389583, 39098.0, 33280.833595831864, 34539.0, 34925.30149396947, 35203.64796352004, 35405.46891796677, 21013.474663133544, 25834.87082564575, 25872.611910821728, 26199.476010479302, 27333.72666273312], "value": 20046.54867340897, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [324.9350129974005, 329.17213208779924, 374.33181770539585, 383.79083399547244, 399.9900251864043, 19088.42734717959, 20152.992350382494, 20447.0, 20805.27181548648, 20829.895850520727, 19241.0, 19512.902435487802, 19920.203191872344, 19930.302439414623, 20112.59774804503, 5686.829395118132, 5759.769609215627, 5800.854978625526, 5960.076188190816, 6007.879842403171, 5122.667026643275, 5814.796482123126, 5934.436228558302, 5953.58324917256, 5959.3146788119275, 5754.367019627858, 5860.326062502802, 5878.265216847894, 5879.647221166743, 5908.586398952075, 4950.801967921278, 5843.328017278025, 5914.852128696795, 5915.556333274979, 5924.37794031627, 4931.7780699868235, 5103.336566246401, 5144.511271429208, 5147.871303217412, 5157.277981082651, 22479.438014049778, 23642.0, 23680.526389472285, 24262.878685606458, 24772.628410573856, 1731.7402389641654, 1873.6627407066724, 2173.4349069241953, 2354.5290941811704, 2573.240893936293, 9915.603375864957, 10126.493675316278, 10189.949050254776, 10200.591976321013, 12916.806247906288, 1714.2028956535146, 1850.8056654051265, 1855.2857149997212, 1869.130854152812, 1876.7654043244595, 114604.0, 114902.85097149006, 117691.0, 118327.0, 118647.40676296563, 9773.729415175982, 11191.608293709682, 11302.943485282685, 11481.88518114825, 11554.59558915434, 13553.728925421436, 14190.432382704783, 14196.71606567863, 14220.928895355457, 14227.92886035584, 8126.349892008625, 8228.177182281725, 8237.46456480327, 8385.622646980779, 8406.915930840736, 7996.600169991477, 8050.959745201237, 8064.596770161468, 8151.429399941947, 8159.388045896581, 3699.963000370016, 4321.222180007629, 4397.428334316583, 4486.910261794746, 4499.257622492318, 1837.9081045947648, 1901.1919934027983, 1948.415475357387, 1953.404211715412, 1967.2131147540813, 371.77879161898625, 373.19948710017405, 399.0701665120263, 415.14065883620617, 664.773976847874, 9364.0, 11059.502322395512, 11176.664700059011, 11733.4719937603, 11835.0, 409.8749881286236, 416.3442577939757, 431.3271296777038, 433.48198902312066, 436.1582146457304, 5814.476697097197, 6042.909356359658, 6095.116208149793, 6112.144299798032, 6135.233095863017, 584018.0, 585691.0, 587021.0, 590329.0, 593512.0, 92567.0, 129827.70172298157, 131082.0, 133799.33100334436, 135994.0, 416.7832726982013, 426.91888541177696, 428.52433798483656, 429.47174974781194, 429.7808117859851, 9970.551325190529, 10222.48887555634, 10807.621733239614, 10823.837642435527, 11144.996950274513, 56258.71870640621, 57307.713461432424, 57868.84262314814, 58826.0, 59684.99999999826, 78393.60803195948, 81100.18899811163, 81944.59027704824, 82626.17373826184, 83154.0, 7710.730124445618, 7821.0, 7986.201379861967, 8043.477173983672, 8124.268815806606, 12144.453499592717, 12310.507579696712, 12608.49566017349, 12624.810627840407, 12661.87338126607, 7748.070231572247, 8544.102869198645, 8615.009273933578, 10053.396796192252, 10907.509162087566, 9724.854127187957, 10601.25791194609, 10641.946790266, 10981.560737570728, 11086.778264434828, 9128.45229286245, 10819.242653014213, 10950.452477376259, 11375.31748095117, 11377.886221138015, 6108.389161084034, 7986.321162701235, 8073.919260807317, 8098.352131829557, 8196.934398528154, 8475.830483390175, 10178.949105254427, 10346.275760696679, 10487.26589138753, 10542.947285263524], "value": 8316.708881934199, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1471.8380978092407, 1519.954401367959, 1608.7345587977984, 1691.780068591083, 1816.0193495512424, 7474.551526908389, 7719.382449404048, 7951.36389088873, 8426.620802063906, 8434.957825210873, 7873.724419645314, 8148.511089334644, 8152.38857085718, 8261.380396470258, 8281.83436331273, 2267.5124848157643, 2268.54629074185, 2295.529416469628, 2297.2993237062688, 2301.4821665125296, 1940.8932508712014, 2293.220305096267, 2337.1703045418885, 2347.8708671023087, 2355.7762012608773, 2287.4624463251207, 2287.5196208796087, 2311.3874823171873, 2318.3160967514623, 2325.4070212095976, 1932.4589115047809, 2267.478479949604, 2276.5674521840797, 2296.8621882687094, 2297.6783250344797, 1690.2393922734818, 1883.962320753591, 2321.5124823787023, 2321.9419514512274, 2327.813774898021, 8422.157784221528, 8460.2385785279, 8478.491290522588, 8503.426866029804, 8512.70205542803, 4956.3804524434445, 5125.410577783566, 5171.534561889411, 5190.091733946544, 5200.323957885488, 3354.496825476174, 3356.7314614830757, 3509.5262139611336, 3558.982205088984, 3579.033660911565, 927.2350310993457, 1038.4703801061437, 1075.833245846895, 1089.6622047165404, 1093.2729734726397, 10415.947920260425, 10483.423411712352, 10742.355458672504, 10749.946250268777, 10911.345319280868, 4258.808353624095, 4312.159128969821, 4369.410129632459, 4433.623142032899, 4444.733251023443, 7072.858542829217, 7840.823876418583, 8588.22705956467, 8598.58123409643, 8602.956985215034, 5002.1496345621335, 5028.748562571856, 5028.823991160365, 5031.943291908667, 5032.748362581856, 4189.2459357315975, 4276.72201306914, 4494.325851122292, 4651.348811166439, 4696.7651617419, 3699.9815000924823, 3753.962460375416, 3824.598417166213, 3866.845326186977, 3898.122922342507, 1238.5603110895684, 1261.5332327039055, 1263.9684007899875, 1275.7306480052407, 1281.8205451236834, 719.6473727873305, 751.7030772844696, 805.2672068417712, 880.6125304865839, 908.6183802802839, 4595.828063843698, 5458.699771512525, 5530.0, 5594.888102237933, 5720.77116915327, 337.7433150805406, 382.43017903323846, 382.91958688675135, 424.9065205654751, 433.986641192812, 3153.8423078845967, 3829.349010668193, 3916.2167566486814, 3937.3897045957924, 3954.209158168378, 9688.70933871985, 10054.69835904924, 10066.043725846033, 10070.295079344522, 10100.242481813893, 9561.856572151424, 9790.020997900154, 9887.604495820087, 9911.553980070914, 10036.949815250877, 6548.6725663716625, 7587.241275872479, 7760.689572417153, 7778.416618753616, 7804.180561041123, 5346.652467589594, 5445.079542729229, 5478.753456094565, 5722.341930677859, 5812.82561523155, 8991.14584114508, 9328.673496427593, 9369.765755856159, 9416.95291523538, 9475.431474111576, 9262.814743705223, 9280.118388753057, 9335.113164249386, 9434.811303774024, 9539.618415263312, 7163.713451462092, 7197.028401165985, 7260.238671054314, 7269.45479089081, 7301.817454563678, 7835.37317014626, 7871.0, 7880.763577092696, 7902.604869756374, 7911.881321780293, 7025.789226323219, 7157.1769246537315, 7167.820804479929, 7218.061651985208, 7259.854802903807, 6920.204176519765, 7023.33278338557, 7025.332593403823, 7124.715011399693, 7154.606496642735, 5544.972275138599, 5707.942920570741, 6576.901346479711, 6666.133402657818, 6673.532852700265, 4791.496892826342, 4794.880127996828, 4824.565789079, 4830.758462076812, 4906.509349065065, 4707.741074240949, 4768.904621907612, 4822.590079843252, 4897.2654101885, 4922.237053256751], "value": 4234.417343638213, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [293.96619388770296, 298.56707773728095, 304.3426199409276, 308.48175065889296, 310.2058729652091, 4836.782344794484, 5321.733913304329, 5362.4637536246355, 5467.644603100801, 5491.176323551469, 4915.926261106087, 5196.948030519694, 5310.76101575429, 5346.759395827187, 5400.486953739389, 3565.9286814263633, 4152.5432202457705, 4156.397322388252, 4160.375943608453, 4182.870624931266, 3819.4652748615076, 4057.5536690963972, 4167.979160104195, 4172.16556688662, 4186.246475634384, 4006.4991876015497, 4028.5769994150633, 4034.293998550242, 4036.899077523071, 4076.9592304077023, 3210.0, 3721.9069523261865, 4046.0289530512614, 4050.5139383274195, 4059.4722686050773, 3076.8615412306276, 3228.7094161525574, 3257.9022629321157, 3271.525628783837, 3539.1859872229275, 4309.956900430987, 4898.069366820293, 5165.63840531163, 5184.248283998799, 5238.371395432534, 3987.501562304712, 4014.7189696721243, 4475.5748203920575, 4550.203714349976, 4620.561046700592, 4870.9269360959615, 4974.90050198994, 5182.585393168538, 5343.064963631349, 5634.549236061105, 3031.5452682097643, 3355.76509644325, 3403.196305957846, 3665.9266814663824, 3819.1216020315483, 4859.684120532154, 4943.456219815817, 5315.335583052119, 5914.319853216892, 5937.970310148422, 3155.8954365971726, 3418.4872269159823, 3878.767273963515, 3883.3204189266767, 3904.863329783501, 3306.8842590509216, 3649.284836127016, 3983.9203215935518, 3986.541547722049, 4003.419504171937, 4367.253098760521, 4548.0216105993995, 4577.359169716243, 4588.587027167571, 4621.676482646182, 5715.571332150105, 5725.713714314351, 5735.050082971809, 5739.827805165852, 5747.39652336507, 1211.3943028485758, 1270.7140893298933, 1320.650027742649, 1333.286691619998, 1356.2472827580787, 820.446198815797, 846.6740304982626, 879.3580686099056, 899.5277479323407, 914.7621618379269, 4807.975960120177, 5289.471052894757, 5292.221583668605, 5308.708021058802, 5336.813211537577, 5185.792568297225, 5742.9562331964125, 5789.8552536186935, 5832.562557808182, 5853.619514731528, 4812.398450193726, 4972.602191824645, 5260.421353651095, 5285.920711189336, 5333.413324534298, 2423.757624237562, 2462.5717084091184, 2462.9261122166363, 2485.347244082701, 2553.80846436518, 4130.256553820341, 4494.438195225597, 4771.137317609407, 4995.525425084611, 5053.772580233898, 3980.681545476355, 4502.244124791334, 4695.558309678948, 4767.13971617027, 5044.924326135111, 4338.414314067623, 4766.35373938937, 5027.748612569357, 5107.825200204048, 5125.949620151969, 3917.0990672144994, 4729.196036673705, 4832.511256702731, 4910.607151427806, 4922.556969872729, 4036.273470775289, 4527.456705195266, 4817.710937343771, 4820.276958456259, 5336.546393556591, 5808.273965754281, 5953.613015154001, 5994.100884867305, 6070.544709146743, 6108.5723999319625, 4019.91960160801, 4072.165206132795, 4081.510218773766, 4095.8156882940934, 4108.281050816155, 5692.573057020657, 5754.108113242454, 5812.883742325045, 5889.970550147222, 5933.258342707161, 5326.256977656588, 5709.942900570941, 5743.770249189985, 5791.247137872218, 5798.246227990334, 5246.737663116905, 5285.656432331886, 5700.144978253295, 5733.799317023884, 5738.48353648157, 5393.271908292487, 5519.834404967857, 5592.580556458201, 5643.068893632505, 5670.319561652628, 4323.654107671442, 4693.5306469354155, 4745.833895813768, 5625.690587017753, 5729.083346664514, 4600.838970636011, 4967.3045773591, 5571.8885622288135, 5626.803061892814, 5658.217661436634], "value": 3963.7369927516834, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [588.4086493074459, 616.9938300616994, 678.7963610916721, 683.6035099642206, 799.0211990311868, 14099.295035248235, 15002.324895379707, 15045.92477037616, 15070.773938390932, 15117.773233401507, 14586.0, 15352.616184595363, 15369.69260614787, 15381.077135371885, 15586.298616562253, 830.8712149616804, 851.093585331623, 856.4647095565272, 857.8455877941968, 859.7119964811795, 663.7477758451781, 680.3094858718405, 811.6388207247777, 833.154348336436, 849.8357250566733, 880.3969281042474, 887.3300658003199, 889.1419779912384, 890.3945317184312, 891.6299735609714, 858.1375717404031, 879.6525372477868, 880.5949263338872, 880.6125304865839, 881.9823603527893, 563.9915401268984, 584.5557376393929, 634.8571571396445, 647.7311915555086, 648.8896887529131, 14384.424623015067, 14564.344604492717, 14704.779428308584, 14851.183184924825, 14876.256187190706, 13417.932910335387, 13434.261115638636, 13475.86524134756, 13547.390367433489, 13871.237081960488, 11460.0, 11627.94186029073, 11643.06855451562, 11717.179797414185, 11845.289282642983, 3607.639236076371, 3702.185519185775, 3721.0511319613324, 3796.0509872531866, 3838.0021194489354, 83994.16005839924, 87164.5641771787, 87898.56050719705, 87944.0, 89770.0, 10469.738256543495, 10578.841317380246, 10642.680719578426, 10860.45697715111, 11150.776984460428, 16471.91764041172, 20488.79511204869, 20854.687179692315, 21075.68386474204, 21293.36119916405, 7817.5309481431295, 7825.0, 7976.52140871538, 7988.400869934778, 8160.836783264302, 4342.326939324346, 4393.846215382447, 4426.004149066434, 4439.200943830077, 4531.229690952546, 3491.9301613967577, 3542.91142721434, 3599.3521166190335, 3607.4228123500116, 3612.7290453216115, 666.0907860770111, 667.302668711198, 671.5668393885916, 676.0433985909981, 685.1880521581942, 418.2033226703135, 539.3743257820909, 574.2133277409976, 590.670960656567, 646.7445359083135, 8818.206361427503, 9075.863862042075, 9112.908870911338, 9219.308551858638, 9242.168204861595, 547.2857920413898, 547.9753411096431, 606.6663335165641, 626.5645376463406, 656.4256275758711, 4177.352510360899, 4440.089781594766, 4482.305242687388, 4513.119941611354, 4545.272756358967, 87272.81817954475, 107335.46332268289, 109217.0, 112581.0, 115312.42343788227, 73281.6335918317, 83077.58461207655, 83492.0, 99004.0, 99972.50013749885, 72205.0, 75929.0, 80737.19262807415, 81923.7711434329, 82120.0, 9297.907020929704, 9405.482698451651, 9422.95288523553, 9493.477858717837, 9495.620175192915, 49945.0, 50355.74822125866, 50681.746591266805, 52388.47611523836, 54150.72924635509, 34420.82789586136, 46929.0, 62138.0, 74315.0, 74353.0, 10343.741406464898, 11972.527937058827, 12098.576549820715, 12103.818442723543, 12110.818337724764, 11893.226940248856, 13565.525206617722, 13862.098963567336, 14157.292135393394, 14467.782983255049, 11719.945204931597, 12224.266544007389, 12556.937215313865, 12781.04142189321, 12908.935455322664, 12402.13185077036, 12481.188722732993, 12772.0, 13060.085793994329, 13104.279264640536, 12460.376981151088, 12470.0, 12755.80866287025, 12768.297743624189, 13081.411336490071, 9852.655157069756, 10303.175745940156, 10432.374057556268, 10434.06093451593, 11086.113110951266, 9191.494467804068, 9879.308448408543, 10024.699259022242, 11017.83473247886, 11113.611023614134], "value": 7171.2199169372, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1599.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [107.0, 117.0, 119.0, 117.0, 171.0, 111.0, 174.0, 175.0, 158.0, 116.0], "value": 136.5, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [154.0, 147.0, 112.0, 192.0, 149.0, 146.0, 117.0, 157.0, 142.0, 120.0], "value": 143.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [276.0, 238.0, 202.0, 198.0, 198.0, 199.0, 196.0, 198.0, 199.0, 197.0], "value": 210.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 58.0, 84.0, 60.0, 63.0, 87.0, 60.0, 79.0, 50.0], "value": 68.1, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [90.0, 70.0, 90.0, 74.0, 74.0, 72.0, 88.0, 70.0, 72.0, 91.0], "value": 79.1, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 89.0, 89.0, 87.0, 90.0, 86.0, 92.0, 94.0, 90.0, 87.0], "value": 89.5, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 150.0, 155.0, 97.0, 96.0, 98.0, 96.0, 102.0, 91.0], "value": 107.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [99.0, 144.0, 135.0, 89.0, 130.0, 136.0, 115.0, 140.0, 117.0, 141.0], "value": 124.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 60.0, 77.0, 57.0, 56.0, 55.0, 57.0, 58.0, 46.0, 50.0], "value": 57.6, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 86.0, 78.0, 72.0, 67.0, 60.0, 78.0, 108.0, 62.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 89.0, 91.0, 93.0, 86.0, 103.0, 100.0, 109.0, 79.0, 104.0], "value": 94.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [150.0, 152.0, 160.0, 146.0, 156.0, 156.0, 138.0, 184.0, 192.0, 147.0], "value": 158.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 178.0, 174.0, 189.0, 176.0, 176.0, 177.0, 175.0, 176.0, 175.0], "value": 177.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 91.0, 92.0, 94.0, 92.0, 66.0, 71.0, 67.0, 67.0, 69.0], "value": 77.1, "unit": "score"}]}]} 14:43:22 INFO - Return code: 0 14:43:22 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:43:22 ERROR - # TBPL SUCCESS # 14:43:22 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:43:22 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:43:22 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:43:22 INFO - 2016-10-31 14:43:22.262 screenresolution[1812:10595] starting screenresolution argv=screenresolution get 14:43:22 INFO - 2016-10-31 14:43:22.265 screenresolution[1812:10595] Display 0: 1600x1200x32@60 14:43:22 INFO - 2016-10-31 14:43:22.271 screenresolution[1813:10598] starting screenresolution argv=screenresolution list 14:43:22 INFO - Available Modes on Display 0 14:43:22 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:43:22 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:43:22 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:43:22 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:43:22 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:43:22 INFO - Intel Iris: 14:43:22 INFO - Chipset Model: Intel Iris 14:43:22 INFO - Type: GPU 14:43:22 INFO - Bus: Built-In 14:43:22 INFO - VRAM (Dynamic, Max): 1536 MB 14:43:22 INFO - Vendor: Intel (0x8086) 14:43:22 INFO - Device ID: 0x0a2e 14:43:22 INFO - Revision ID: 0x0009 14:43:22 INFO - Displays: 14:43:22 INFO - Display: 14:43:22 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:43:22 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:43:22 INFO - Display Serial Number: 1600x1200 60 14:43:22 INFO - Main Display: Yes 14:43:22 INFO - Mirror: Off 14:43:22 INFO - Online: Yes 14:43:22 INFO - Rotation: Supported 14:43:22 INFO - Return code: 0 14:43:22 INFO - Running post-action listener: _resource_record_post_action 14:43:22 INFO - [mozharness: 2016-10-31 21:43:22.509576Z] Finished run-tests step (success) 14:43:22 INFO - Running post-run listener: _resource_record_post_run 14:43:22 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 38807040; Write bytes: 714298368; Read time: 263; Write time: 2094 14:43:22 INFO - TinderboxPrint: CPU usage
54.6% 14:43:22 INFO - TinderboxPrint: I/O read bytes / time
38,807,040 / 263 14:43:22 INFO - TinderboxPrint: I/O write bytes / time
714,298,368 / 2,094 14:43:22 INFO - TinderboxPrint: CPU idle
2,201.2 (45.4%) 14:43:22 INFO - TinderboxPrint: CPU system
982.1 (20.3%) 14:43:22 INFO - TinderboxPrint: CPU user
1,666.2 (34.4%) 14:43:22 INFO - TinderboxPrint: Swap in / out
446,181,376 / 0 14:43:22 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 396198400; Write bytes: 390940160; Read time: 29500; Write time: 826 14:43:22 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 37815808; Write bytes: 303738368; Read time: 245; Write time: 1217 14:43:22 INFO - Running post-run listener: _upload_blobber_files 14:43:22 INFO - Blob upload gear active. 14:43:22 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:43:22 INFO - Files from /builds/slave/test/build/blobber_upload_dir are to be uploaded with branch at the following location(s): https://blobupload.elasticbeanstalk.com 14:43:22 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:43:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:43:23 INFO - (blobuploader) - INFO - Open directory for files ... 14:43:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:43:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:43:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:24 INFO - (blobuploader) - INFO - Done attempting. 14:43:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:43:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:43:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:26 INFO - (blobuploader) - INFO - Done attempting. 14:43:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:43:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:43:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:43:28 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:43:28 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:43:28 INFO - (blobuploader) - INFO - Done attempting. 14:43:28 INFO - (blobuploader) - INFO - Iteration through files over. 14:43:28 INFO - Return code: 0 14:43:28 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:43:28 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:43:28 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1d1e0de5508a20698f536d6d8a1e11fb0a9901faac7e0a9b36ce43d527771a92235520bdcec217aec2dcc7ffddc8ab77b4d84a5eec66b47cc1dff4b3934adb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39312607929c2f5f321f4eecd56b869175244ec9096c001f43c4e261a6136b253191889dab5674341cdd3e480ae91614f076a5db64c78615dca5089666b98dc8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9930da3b0319289abff904a6894b0d0853330fee225978c5c27e9fc5aa2d63fb93418e77f383e91094afcdab087ba368991df38b1ac4d5b92290e8b32cfa189"} 14:43:28 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:43:28 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:43:28 INFO - Contents: 14:43:28 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1d1e0de5508a20698f536d6d8a1e11fb0a9901faac7e0a9b36ce43d527771a92235520bdcec217aec2dcc7ffddc8ab77b4d84a5eec66b47cc1dff4b3934adb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39312607929c2f5f321f4eecd56b869175244ec9096c001f43c4e261a6136b253191889dab5674341cdd3e480ae91614f076a5db64c78615dca5089666b98dc8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9930da3b0319289abff904a6894b0d0853330fee225978c5c27e9fc5aa2d63fb93418e77f383e91094afcdab087ba368991df38b1ac4d5b92290e8b32cfa189"} 14:43:28 INFO - Running post-run listener: copy_logs_to_upload_dir 14:43:28 INFO - Copying logs to upload dir... 14:43:28 INFO - mkdir: /builds/slave/test/build/upload/logs 14:43:28 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.856048 ========= master_lag: 0.05 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-31 14:43:29.030559) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:29.038059) ========= 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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1d1e0de5508a20698f536d6d8a1e11fb0a9901faac7e0a9b36ce43d527771a92235520bdcec217aec2dcc7ffddc8ab77b4d84a5eec66b47cc1dff4b3934adb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39312607929c2f5f321f4eecd56b869175244ec9096c001f43c4e261a6136b253191889dab5674341cdd3e480ae91614f076a5db64c78615dca5089666b98dc8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9930da3b0319289abff904a6894b0d0853330fee225978c5c27e9fc5aa2d63fb93418e77f383e91094afcdab087ba368991df38b1ac4d5b92290e8b32cfa189"} build_url:https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011300 build_url: 'https://queue.taskcluster.net/v1/task/B7Zp9wGLSOWhKfhazbk3XQ/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/c1d1e0de5508a20698f536d6d8a1e11fb0a9901faac7e0a9b36ce43d527771a92235520bdcec217aec2dcc7ffddc8ab77b4d84a5eec66b47cc1dff4b3934adb7", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/39312607929c2f5f321f4eecd56b869175244ec9096c001f43c4e261a6136b253191889dab5674341cdd3e480ae91614f076a5db64c78615dca5089666b98dc8", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/b9930da3b0319289abff904a6894b0d0853330fee225978c5c27e9fc5aa2d63fb93418e77f383e91094afcdab087ba368991df38b1ac4d5b92290e8b32cfa189"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:29.074918) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:29.075266) ========= 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.0YiB4WvdBm/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.QVGnH6R7YG/Listeners TMPDIR=/var/folders/gt/gzllwlfx58vgtmvns94gtby000000w/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.005167 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-31 14:43:29.123594) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 14:43:29.126925) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-31 14:43:29.127242) ========= ========= Total master_lag: 0.19 =========