builder: mozilla-aurora_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0120 starttime: 1483653672.04 results: success (0) buildid: 20170105122013 builduid: e19f4f90c178470ca462574bc9f76c8e revision: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.037573) ========= master: http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.038020) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.038309) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.065890) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.066297) ========= 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.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-05 14:01:12-- 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% 13.5M=0.001s 2017-01-05 14:01:12 (13.5 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.490193 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.580767) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.581073) ========= 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.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.029960 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:12.634152) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 14:01:12.634553) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-aurora --rev dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-05 14:01:12,737 truncating revision to first 12 chars 2017-01-05 14:01:12,737 Setting DEBUG logging. 2017-01-05 14:01:12,737 attempt 1/10 2017-01-05 14:01:12,737 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-aurora/dbbbc4ab2f1b?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-05 14:01:14,043 unpacking tar archive at: mozilla-aurora-dbbbc4ab2f1b/testing/mozharness/ program finished with exit code 0 elapsedTime=1.572227 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-05 14:01:14.244192) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.244467) ========= script_repo_revision: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.244832) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.245090) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-05 14:01:14.266075) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-05 14:01:14.266346) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Aurora', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Aurora'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:14 INFO - MultiFileLogger online at 20170105 14:01:14 in /builds/slave/test 14:01:14 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Aurora --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Aurora 14:01:14 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 14:01:14 INFO - {'append_to_log': False, 14:01:14 INFO - 'base_work_dir': '/builds/slave/test', 14:01:14 INFO - 'blob_upload_branch': 'Mozilla-Aurora', 14:01:14 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 14:01:14 INFO - 'branch': 'Mozilla-Aurora', 14:01:14 INFO - 'buildbot_json_path': 'buildprops.json', 14:01:14 INFO - 'config_files': ('talos/mac_config.py',), 14:01:14 INFO - 'default_actions': ('clobber', 14:01:14 INFO - 'read-buildbot-config', 14:01:14 INFO - 'download-and-extract', 14:01:14 INFO - 'populate-webroot', 14:01:14 INFO - 'create-virtualenv', 14:01:14 INFO - 'install', 14:01:14 INFO - 'run-tests'), 14:01:14 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 14:01:14 INFO - 'download_minidump_stackwalk': True, 14:01:14 INFO - 'download_symbols': 'ondemand', 14:01:14 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 14:01:14 INFO - 'tooltool.py': '/tools/tooltool.py', 14:01:14 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 14:01:14 INFO - '/tools/misc-python/virtualenv.py')}, 14:01:14 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 14:01:14 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 14:01:14 INFO - 'installer_path': 'installer.exe', 14:01:14 INFO - 'log_level': 'info', 14:01:14 INFO - 'log_name': 'talos', 14:01:14 INFO - 'log_to_console': True, 14:01:14 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 14:01:14 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 14:01:14 INFO - 'opt_config_files': (), 14:01:14 INFO - 'pip_index': False, 14:01:14 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:01:14 INFO - 'cmd': ('bash', 14:01:14 INFO - '-c', 14:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:01:14 INFO - 'enabled': True, 14:01:14 INFO - 'halt_on_failure': False, 14:01:14 INFO - 'name': 'check_screen_resolution'},), 14:01:14 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 14:01:14 INFO - 'cmd': ('bash', 14:01:14 INFO - '-c', 14:01:14 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 14:01:14 INFO - 'enabled': True, 14:01:14 INFO - 'halt_on_failure': False, 14:01:14 INFO - 'name': 'check_screen_resolution'},), 14:01:14 INFO - 'run_cmd_checks_enabled': True, 14:01:14 INFO - 'sps_profile': False, 14:01:14 INFO - 'sps_profile_interval': 0, 14:01:14 INFO - 'suite': 'dromaeojs-e10s', 14:01:14 INFO - 'system_bits': '32', 14:01:14 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 14:01:14 INFO - 'title': 't-yosemite-r7-0120', 14:01:14 INFO - 'tooltool_cache': '/builds/tooltool_cache', 14:01:14 INFO - 'use_talos_json': True, 14:01:14 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 14:01:14 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 14:01:14 INFO - 'work_dir': 'build'} 14:01:14 INFO - [mozharness: 2017-01-05 22:01:14.467911Z] Running clobber step. 14:01:14 INFO - Running pre-action listener: _resource_record_pre_action 14:01:14 INFO - Running main action method: clobber 14:01:14 INFO - rmtree: /builds/slave/test/build 14:01:14 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 14:01:16 INFO - Running post-action listener: _resource_record_post_action 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.861938Z] Finished clobber step (success) 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.862228Z] Running read-buildbot-config step. 14:01:16 INFO - Running pre-action listener: _resource_record_pre_action 14:01:16 INFO - Running main action method: read_buildbot_config 14:01:16 INFO - Using buildbot properties: 14:01:16 INFO - { 14:01:16 INFO - "project": "", 14:01:16 INFO - "product": "firefox", 14:01:16 INFO - "script_repo_revision": "production", 14:01:16 INFO - "builddir": "mozilla-aurora_yosemite_r7_test-dromaeojs-e10s", 14:01:16 INFO - "repository": "", 14:01:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-aurora talos dromaeojs-e10s", 14:01:16 INFO - "buildid": "20170105122013", 14:01:16 INFO - "pgo_build": "False", 14:01:16 INFO - "basedir": "/builds/slave/test", 14:01:16 INFO - "buildnumber": 69, 14:01:16 INFO - "slavename": "t-yosemite-r7-0120", 14:01:16 INFO - "master": "http://buildbot-master135.bb.releng.scl3.mozilla.com:8201/", 14:01:16 INFO - "platform": "yosemite_r7", 14:01:16 INFO - "scheduler": "tests-mozilla-aurora-macosx64-talos", 14:01:16 INFO - "branch": "mozilla-aurora", 14:01:16 INFO - "repo_path": "releases/mozilla-aurora", 14:01:16 INFO - "revision": "dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0", 14:01:16 INFO - "stage_platform": "macosx64", 14:01:16 INFO - "builduid": "e19f4f90c178470ca462574bc9f76c8e", 14:01:16 INFO - "slavebuilddir": "test" 14:01:16 INFO - } 14:01:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg. 14:01:16 INFO - Running post-action listener: _resource_record_post_action 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.867746Z] Finished read-buildbot-config step (success) 14:01:16 INFO - [mozharness: 2017-01-05 22:01:16.867962Z] Running download-and-extract step. 14:01:16 INFO - Running pre-action listener: _resource_record_pre_action 14:01:16 INFO - Running main action method: download_and_extract 14:01:16 INFO - mkdir: /builds/slave/test/build/tests 14:01: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:01:16 INFO - trying https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json 14:01:16 INFO - Downloading https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:01:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json'}, attempt #1 14:01:17 INFO - Downloaded 1199 bytes. 14:01:17 INFO - Reading from file /builds/slave/test/build/firefox-52.0a2.en-US.mac.test_packages.json 14:01:17 INFO - Using the following test package requirements: 14:01:17 INFO - {u'common': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:01:17 INFO - u'cppunittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.cppunittest.tests.zip'], 14:01:17 INFO - u'gtest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.gtest.tests.zip'], 14:01:17 INFO - u'jittest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'jsshell-mac.zip'], 14:01:17 INFO - u'mochitest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.mochitest.tests.zip'], 14:01:17 INFO - u'mozbase': [u'firefox-52.0a2.en-US.mac.common.tests.zip'], 14:01:17 INFO - u'reftest': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.reftest.tests.zip'], 14:01:17 INFO - u'talos': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.talos.tests.zip'], 14:01:17 INFO - u'web-platform': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.web-platform.tests.zip'], 14:01:17 INFO - u'xpcshell': [u'firefox-52.0a2.en-US.mac.common.tests.zip', 14:01:17 INFO - u'firefox-52.0a2.en-US.mac.xpcshell.tests.zip']} 14:01:17 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip'] for test suite category: common 14:01:17 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:01:17 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:01:17 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:01:18 INFO - Http code: 200 14:01:18 INFO - via: 1.1 24b0e5a3429d07ef12381da50e07f70f.cloudfront.net (CloudFront) 14:01:18 INFO - x-amz-cf-id: Cb6GiuN-TjKHIl_WzVzG42EO-ZeevDKX20owfNzdwGv08lun0Tfuqg== 14:01:18 INFO - x-amz-version-id: DdnnonK8yj1lGKCEXAi2cs375rvYM7GP 14:01:18 INFO - Expected file size: 16269400 14:01:18 INFO - Obtained file size: 16269400 14:01:25 INFO - Downloading packages: [u'firefox-52.0a2.en-US.mac.common.tests.zip', u'firefox-52.0a2.en-US.mac.talos.tests.zip'] for test suite category: talos 14:01:25 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip 14:01:25 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip'}, attempt #1 14:01:25 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.common.tests.zip into memory 14:01:25 INFO - Http code: 200 14:01:25 INFO - via: 1.1 20f1c35f343f4b271ae8dcacfd7ea0e9.cloudfront.net (CloudFront) 14:01:25 INFO - x-amz-cf-id: 9p2GISmBWInRgbVN--oGE_DVpH4gLqt_n_7YSJMGcfDXCdRwwV7Zhg== 14:01:25 INFO - x-amz-version-id: DdnnonK8yj1lGKCEXAi2cs375rvYM7GP 14:01:26 INFO - Expected file size: 16269400 14:01:26 INFO - Obtained file size: 16269400 14:01:32 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip 14:01:32 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip'}, attempt #1 14:01:32 INFO - Fetch https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.talos.tests.zip into memory 14:01:33 INFO - Http code: 200 14:01:33 INFO - via: 1.1 423912f2b1c5569e2fa5017167c61fa0.cloudfront.net (CloudFront) 14:01:33 INFO - x-amz-cf-id: hBG1hKGrvCw6h-Q86M3SXUxuv4auoU2rj43dEp8KSy_rNsvszI4SQw== 14:01:33 INFO - x-amz-version-id: 6sN6murE149SKWb_aBkJhkzy6gsa9VjG 14:01:33 INFO - Expected file size: 14409038 14:01:33 INFO - Obtained file size: 14409038 14:01: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:01:34 INFO - trying https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:34 INFO - Downloading https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg 14:01:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg'}, attempt #1 14:01:36 INFO - Downloaded 109850829 bytes. 14:01:36 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:36 INFO - mkdir: /builds/slave/test/properties 14:01:36 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 14:01:36 INFO - Writing to file /builds/slave/test/properties/build_url 14:01:36 INFO - Contents: 14:01:36 INFO - build_url:https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg 14:01:37 INFO - Running post-action listener: _resource_record_post_action 14:01:37 INFO - Running post-action listener: set_extra_try_arguments 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.525842Z] Finished download-and-extract step (success) 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.525992Z] Running populate-webroot step. 14:01:37 INFO - Running pre-action listener: _resource_record_pre_action 14:01:37 INFO - Running main action method: populate_webroot 14:01:37 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 14:01:37 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 14:01:37 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 14:01:37 INFO - '--tppagecycles', 14:01:37 INFO - '7'], 14:01:37 INFO - 'tests': ['tsvgm']}}, 14:01:37 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tresize', 'tcanvasmark']}, 14:01:37 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 14:01:37 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['dromaeo_css', 'kraken']}, 14:01:37 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 14:01:37 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:37 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 14:01:37 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['damp', 'tps']}, 14:01:37 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['damp', 'tps']}, 14:01:37 INFO - 'g3': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['dromaeo_dom']}, 14:01:37 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 14:01:37 INFO - 'g4': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['basic_compositor_video']}, 14:01:37 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 14:01:37 INFO - 'other': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s_l64': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['a11yr', 14:01:37 INFO - 'ts_paint', 14:01:37 INFO - 'tpaint', 14:01:37 INFO - 'sessionrestore', 14:01:37 INFO - 'sessionrestore_no_auto_restore', 14:01:37 INFO - 'tabpaint']}, 14:01:37 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tsvgx', 14:01:37 INFO - 'tsvgr_opacity', 14:01:37 INFO - 'tart', 14:01:37 INFO - 'tscrollx', 14:01:37 INFO - 'cart']}, 14:01:37 INFO - 'svgr-e10s': {'tests': ['tsvgx', 14:01:37 INFO - 'tsvgr_opacity', 14:01:37 INFO - 'tart', 14:01:37 INFO - 'tscrollx', 14:01:37 INFO - 'cart']}, 14:01:37 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s'], 14:01:37 INFO - 'tests': ['tp5o']}, 14:01:37 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'tests': ['tp5o']}, 14:01:37 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--disable-e10s', 14:01:37 INFO - '--xperf_path', 14:01:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:37 INFO - 'tests': ['tp5n']}, 14:01:37 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 14:01:37 INFO - 'talos_options': ['--xperf_path', 14:01:37 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 14:01:37 INFO - 'tests': ['tp5n']}}, 14:01:37 INFO - 'talos.zip': {'path': '', 14:01:37 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 14:01:37 INFO - Running post-action listener: _resource_record_post_action 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.544572Z] Finished populate-webroot step (success) 14:01:37 INFO - [mozharness: 2017-01-05 22:01:37.544703Z] Running create-virtualenv step. 14:01:37 INFO - Running pre-action listener: _resource_record_pre_action 14:01:37 INFO - Running main action method: create_virtualenv 14:01:37 INFO - Creating virtualenv /builds/slave/test/build/venv 14:01: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:01:37 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 14:01:37 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 14:01:37 INFO - Using real prefix '/tools/python27' 14:01:37 INFO - New python executable in /builds/slave/test/build/venv/bin/python 14:01:38 INFO - Installing distribute.............................................................................................................................................................................................done. 14:01:41 INFO - Installing pip.................done. 14:01:41 INFO - Return code: 0 14:01:41 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 14:01:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 14:01:41 INFO - Reading from file tmpfile_stdout 14:01:41 INFO - Output received: 14:01: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:01:41 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 14:01: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:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01: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:01: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:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:41 INFO - 'HOME': '/Users/cltbld', 14:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:41 INFO - 'LOGNAME': 'cltbld', 14:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:41 INFO - 'MOZ_NO_REMOTE': '1', 14:01:41 INFO - 'NO_EM_RESTART': '1', 14:01:41 INFO - 'PAGER': '/bin/cat', 14:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:41 INFO - 'PWD': '/builds/slave/test', 14:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:41 INFO - 'SHELL': '/bin/bash', 14:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:41 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:41 INFO - 'USER': 'cltbld', 14:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:41 INFO - 'XPC_FLAGS': '0x0', 14:01:41 INFO - 'XPC_SERVICE_NAME': '0', 14:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01: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:01:41 INFO - Cleaning up... 14:01:41 INFO - Return code: 0 14:01:41 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 14:01: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:01:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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:01:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01: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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01: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:01: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:01:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:41 INFO - 'HOME': '/Users/cltbld', 14:01:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:41 INFO - 'LOGNAME': 'cltbld', 14:01:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:41 INFO - 'MOZ_NO_REMOTE': '1', 14:01:41 INFO - 'NO_EM_RESTART': '1', 14:01:41 INFO - 'PAGER': '/bin/cat', 14:01:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:41 INFO - 'PWD': '/builds/slave/test', 14:01:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:41 INFO - 'SHELL': '/bin/bash', 14:01:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:41 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:41 INFO - 'USER': 'cltbld', 14:01:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:41 INFO - 'XPC_FLAGS': '0x0', 14:01:41 INFO - 'XPC_SERVICE_NAME': '0', 14:01:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:42 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:42 INFO - Downloading/unpacking psutil>=3.1.1 14:01: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:01: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:01: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:01: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:01: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:01: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:01:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 14:01:45 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:45 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:45 INFO - Installing collected packages: psutil 14:01:45 INFO - Running setup.py install for psutil 14:01:46 INFO - building 'psutil._psutil_osx' extension 14:01: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_osx.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o 14:01: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:01: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:01: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:01:46 INFO - building 'psutil._psutil_posix' extension 14:01: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:01:46 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 14:01:46 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 14:01:46 INFO - ^ 14:01:46 INFO - 1 warning generated. 14:01: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:01:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 14:01:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 14:01:47 INFO - Successfully installed psutil 14:01:47 INFO - Cleaning up... 14:01:47 INFO - Return code: 0 14:01:47 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 14:01:47 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:01:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:47 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:01:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:47 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:47 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:01:47 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:01:47 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:47 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:47 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:47 INFO - 'HOME': '/Users/cltbld', 14:01:47 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:47 INFO - 'LOGNAME': 'cltbld', 14:01:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:47 INFO - 'MOZ_NO_REMOTE': '1', 14:01:47 INFO - 'NO_EM_RESTART': '1', 14:01:47 INFO - 'PAGER': '/bin/cat', 14:01:47 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:47 INFO - 'PWD': '/builds/slave/test', 14:01:47 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:47 INFO - 'SHELL': '/bin/bash', 14:01:47 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:47 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:47 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:47 INFO - 'USER': 'cltbld', 14:01:47 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:47 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:47 INFO - 'XPC_FLAGS': '0x0', 14:01:47 INFO - 'XPC_SERVICE_NAME': '0', 14:01:47 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:47 INFO - Downloading/unpacking mozsystemmonitor==0.3 14:01: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:01: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:01: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:01: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:01: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:01: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:01:50 INFO - Downloading mozsystemmonitor-0.3.tar.gz 14:01:50 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 14:01:50 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 14:01:50 INFO - Installing collected packages: mozsystemmonitor 14:01:50 INFO - Running setup.py install for mozsystemmonitor 14:01:50 INFO - Successfully installed mozsystemmonitor 14:01:50 INFO - Cleaning up... 14:01:50 INFO - Return code: 0 14:01:50 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 14:01:50 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:01:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:50 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:01:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:50 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:50 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:01:50 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:01:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:50 INFO - 'HOME': '/Users/cltbld', 14:01:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:50 INFO - 'LOGNAME': 'cltbld', 14:01:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:50 INFO - 'MOZ_NO_REMOTE': '1', 14:01:50 INFO - 'NO_EM_RESTART': '1', 14:01:50 INFO - 'PAGER': '/bin/cat', 14:01:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:50 INFO - 'PWD': '/builds/slave/test', 14:01:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:50 INFO - 'SHELL': '/bin/bash', 14:01:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:50 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:50 INFO - 'USER': 'cltbld', 14:01:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:50 INFO - 'XPC_FLAGS': '0x0', 14:01:50 INFO - 'XPC_SERVICE_NAME': '0', 14:01:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:50 INFO - Downloading/unpacking jsonschema==2.5.1 14:01: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:01: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:01: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:01: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:01: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:01: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:01:53 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 14:01:53 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 14:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:53 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:01:53 INFO - Downloading functools32-3.2.3-2.tar.gz 14:01:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 14:01:53 INFO - warning: no files found matching '*.txt' 14:01:53 INFO - no previously-included directories found matching 'build' 14:01:53 INFO - no previously-included directories found matching 'dist' 14:01:53 INFO - no previously-included directories found matching '.git*' 14:01:53 INFO - Installing collected packages: jsonschema, functools32 14:01:54 INFO - Running setup.py install for functools32 14:01:54 INFO - warning: no files found matching '*.txt' 14:01:54 INFO - no previously-included directories found matching 'build' 14:01:54 INFO - no previously-included directories found matching 'dist' 14:01:54 INFO - no previously-included directories found matching '.git*' 14:01:54 INFO - Successfully installed jsonschema functools32 14:01:54 INFO - Cleaning up... 14:01:54 INFO - Return code: 0 14:01:54 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 14:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:54 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:54 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:01:54 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:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:54 INFO - 'HOME': '/Users/cltbld', 14:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:54 INFO - 'LOGNAME': 'cltbld', 14:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:54 INFO - 'MOZ_NO_REMOTE': '1', 14:01:54 INFO - 'NO_EM_RESTART': '1', 14:01:54 INFO - 'PAGER': '/bin/cat', 14:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:54 INFO - 'PWD': '/builds/slave/test', 14:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:54 INFO - 'SHELL': '/bin/bash', 14:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:54 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:54 INFO - 'USER': 'cltbld', 14:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:54 INFO - 'XPC_FLAGS': '0x0', 14:01:54 INFO - 'XPC_SERVICE_NAME': '0', 14:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:54 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 14:01:54 INFO - Cleaning up... 14:01:54 INFO - Return code: 0 14:01:54 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 14:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:54 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:54 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:54 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:54 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:54 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:54 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:01:54 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:01:54 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:54 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:54 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:54 INFO - 'HOME': '/Users/cltbld', 14:01:54 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:54 INFO - 'LOGNAME': 'cltbld', 14:01:54 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:54 INFO - 'MOZ_NO_REMOTE': '1', 14:01:54 INFO - 'NO_EM_RESTART': '1', 14:01:54 INFO - 'PAGER': '/bin/cat', 14:01:54 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:54 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:54 INFO - 'PWD': '/builds/slave/test', 14:01:54 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:54 INFO - 'SHELL': '/bin/bash', 14:01:54 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:54 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:54 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:54 INFO - 'USER': 'cltbld', 14:01:54 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:54 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:54 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:54 INFO - 'XPC_FLAGS': '0x0', 14:01:54 INFO - 'XPC_SERVICE_NAME': '0', 14:01:54 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:54 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:54 INFO - Downloading/unpacking blobuploader==1.2.4 14:01: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:01: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:01: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:01: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:01: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:01: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:01:57 INFO - Downloading blobuploader-1.2.4.tar.gz 14:01:57 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 14:01:57 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 14:01: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:01: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:01: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:01: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:01: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:01: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:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 14:01:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 14:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 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:01:58 INFO - Downloading docopt-0.6.1.tar.gz 14:01:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 14:01:58 INFO - Installing collected packages: blobuploader, requests, docopt 14:01:58 INFO - Running setup.py install for blobuploader 14:01:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 14:01:59 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 14:01:59 INFO - Running setup.py install for requests 14:01:59 INFO - Running setup.py install for docopt 14:01:59 INFO - Successfully installed blobuploader requests docopt 14:01:59 INFO - Cleaning up... 14:01:59 INFO - Return code: 0 14:01:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 14:01:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:01:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:01:59 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:01:59 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:01:59 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:01:59 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:01:59 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:01:59 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:01:59 INFO - 'HOME': '/Users/cltbld', 14:01:59 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:01:59 INFO - 'LOGNAME': 'cltbld', 14:01:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:01:59 INFO - 'MOZ_NO_REMOTE': '1', 14:01:59 INFO - 'NO_EM_RESTART': '1', 14:01:59 INFO - 'PAGER': '/bin/cat', 14:01:59 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:01:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:01:59 INFO - 'PWD': '/builds/slave/test', 14:01:59 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:01:59 INFO - 'SHELL': '/bin/bash', 14:01:59 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:01:59 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:01:59 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:01:59 INFO - 'USER': 'cltbld', 14:01:59 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:01:59 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:01:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:01:59 INFO - 'XPC_FLAGS': '0x0', 14:01:59 INFO - 'XPC_SERVICE_NAME': '0', 14:01:59 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:01:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:01:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-RNLgHM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-c5HplT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-kaOUt1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-DNK0p9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-30TtKO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-j3wWBS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-_4nd3D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-nfwu3L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-fLAn59-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:02:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:02:00 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-LAze1D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Bt2k8P-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-I6ARA8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-1NOFY_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-GgTdQ0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-y6oNod-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vLAvFn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:02:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:02:01 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Ey5LQA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:02:01 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 14:02:01 INFO - Running setup.py install for manifestparser 14:02:01 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 14:02:01 INFO - Running setup.py install for mozcrash 14:02:02 INFO - Running setup.py install for mozdebug 14:02:02 INFO - Running setup.py install for mozdevice 14:02:02 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Installing dm script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozfile 14:02:02 INFO - Running setup.py install for mozhttpd 14:02:02 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozinfo 14:02:02 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozInstall 14:02:02 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 14:02:02 INFO - Running setup.py install for mozleak 14:02:03 INFO - Running setup.py install for mozlog 14:02:03 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for moznetwork 14:02:03 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozprocess 14:02:03 INFO - Running setup.py install for mozprofile 14:02:03 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozrunner 14:02:03 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 14:02:03 INFO - Running setup.py install for mozscreenshot 14:02:04 INFO - Running setup.py install for moztest 14:02:04 INFO - Running setup.py install for mozversion 14:02:04 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 14:02:04 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 14:02:04 INFO - Cleaning up... 14:02:04 INFO - Return code: 0 14:02:04 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:02:04 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:02:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:04 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:02:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:04 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:02:04 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:02:04 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:02:04 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:02:04 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:04 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:04 INFO - 'HOME': '/Users/cltbld', 14:02:04 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:04 INFO - 'LOGNAME': 'cltbld', 14:02:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:04 INFO - 'MOZ_NO_REMOTE': '1', 14:02:04 INFO - 'NO_EM_RESTART': '1', 14:02:04 INFO - 'PAGER': '/bin/cat', 14:02:04 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:04 INFO - 'PWD': '/builds/slave/test', 14:02:04 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:04 INFO - 'SHELL': '/bin/bash', 14:02:04 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:02:04 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:02:04 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:04 INFO - 'USER': 'cltbld', 14:02:04 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:04 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:04 INFO - 'XPC_FLAGS': '0x0', 14:02:04 INFO - 'XPC_SERVICE_NAME': '0', 14:02:04 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 14:02:04 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-M861gN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 14:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 14:02:04 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-4u2nxb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 14:02:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 14:02:04 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-SBendS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 14:02:04 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:02:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 14:02:04 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-U47aAD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-WN9SXT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vqjDkB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-dxnURP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-PrnuWA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-vlUdpc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-E8Gtzp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-M8EEej-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-Sur7fy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 14:02:05 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:02:05 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 14:02:05 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-_ielkb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 14:02:06 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:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 14:02:06 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-8pEpzj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 14:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:06 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-cpy7d9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 14:02:06 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:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 14:02:06 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-eIn6qN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 14:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 14:02:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 14:02:06 INFO - Running setup.py (path:/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/pip-ee28ar-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 14:02:06 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:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:02:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 14:02:06 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:02:06 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:02:06 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:02:06 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 14:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:06 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:02:09 INFO - Downloading blessings-1.6.tar.gz 14:02:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 14:02:09 INFO - Installing collected packages: blessings 14:02:09 INFO - Running setup.py install for blessings 14:02:09 INFO - Successfully installed blessings 14:02:09 INFO - Cleaning up... 14:02:09 INFO - Return code: 0 14:02:09 INFO - Done creating virtualenv /builds/slave/test/build/venv. 14:02:09 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:02:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:02:09 INFO - Reading from file tmpfile_stdout 14:02:09 INFO - Current package versions: 14:02:09 INFO - blessings == 1.6 14:02:09 INFO - blobuploader == 1.2.4 14:02:09 INFO - docopt == 0.6.1 14:02:09 INFO - functools32 == 3.2.3-2 14:02:09 INFO - jsonschema == 2.5.1 14:02:09 INFO - manifestparser == 1.1 14:02:09 INFO - mozInstall == 1.12 14:02:09 INFO - mozcrash == 1.0 14:02:09 INFO - mozdebug == 0.1 14:02:09 INFO - mozdevice == 0.48 14:02:09 INFO - mozfile == 1.2 14:02:09 INFO - mozhttpd == 0.7 14:02:09 INFO - mozinfo == 0.9 14:02:09 INFO - mozleak == 0.1 14:02:09 INFO - mozlog == 3.4 14:02:09 INFO - moznetwork == 0.27 14:02:09 INFO - mozprocess == 0.23 14:02:09 INFO - mozprofile == 0.28 14:02:09 INFO - mozrunner == 6.13 14:02:09 INFO - mozscreenshot == 0.1 14:02:09 INFO - mozsystemmonitor == 0.3 14:02:09 INFO - moztest == 0.8 14:02:09 INFO - mozversion == 1.4 14:02:09 INFO - psutil == 3.1.1 14:02:09 INFO - requests == 1.2.3 14:02:09 INFO - wsgiref == 0.1.2 14:02:09 INFO - Installing None into virtualenv /builds/slave/test/build/venv 14:02: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:02:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02: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:02:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02: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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:02: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:02: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:02:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:02:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:09 INFO - 'HOME': '/Users/cltbld', 14:02:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:09 INFO - 'LOGNAME': 'cltbld', 14:02:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:09 INFO - 'MOZ_NO_REMOTE': '1', 14:02:09 INFO - 'NO_EM_RESTART': '1', 14:02:09 INFO - 'PAGER': '/bin/cat', 14:02:09 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:09 INFO - 'PWD': '/builds/slave/test', 14:02:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:09 INFO - 'SHELL': '/bin/bash', 14:02:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:02:09 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:02:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:09 INFO - 'USER': 'cltbld', 14:02:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:09 INFO - 'XPC_FLAGS': '0x0', 14:02:09 INFO - 'XPC_SERVICE_NAME': '0', 14:02:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 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:02:10 INFO - Cleaning up... 14:02:10 INFO - Return code: 0 14:02:10 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 14:02:10 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:02:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 14:02:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:10 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:02:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 14:02:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 14:02:10 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 0x10dd98490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dd0e570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7f9200cba320>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, '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.03eqGfd3Gt/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.XkeoO2THfR/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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:02:10 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:02:10 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:02:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:02:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:10 INFO - 'HOME': '/Users/cltbld', 14:02:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:10 INFO - 'LOGNAME': 'cltbld', 14:02:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:10 INFO - 'MOZ_NO_REMOTE': '1', 14:02:10 INFO - 'NO_EM_RESTART': '1', 14:02:10 INFO - 'PAGER': '/bin/cat', 14:02:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:10 INFO - 'PWD': '/builds/slave/test', 14:02:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:10 INFO - 'SHELL': '/bin/bash', 14:02:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:02:10 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:02:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:10 INFO - 'USER': 'cltbld', 14:02:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:10 INFO - 'XPC_FLAGS': '0x0', 14:02:10 INFO - 'XPC_SERVICE_NAME': '0', 14:02:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 14:02:10 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 14:02:10 INFO - Cleaning up... 14:02:10 INFO - Return code: 0 14:02:10 INFO - Running post-action listener: _resource_record_post_action 14:02:10 INFO - Running post-action listener: _start_resource_monitoring 14:02:10 INFO - Starting resource monitoring. 14:02:10 INFO - [mozharness: 2017-01-05 22:02:10.320439Z] Finished create-virtualenv step (success) 14:02:10 INFO - [mozharness: 2017-01-05 22:02:10.321451Z] Running install step. 14:02:10 INFO - Running pre-action listener: _resource_record_pre_action 14:02:10 INFO - Running main action method: install 14:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 14:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 14:02:10 INFO - Reading from file tmpfile_stdout 14:02:10 INFO - Detecting whether we're running mozinstall >=1.0... 14:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 14:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 14:02:10 INFO - Reading from file tmpfile_stdout 14:02:10 INFO - Output received: 14:02:10 INFO - Usage: mozinstall [options] installer 14:02:10 INFO - Options: 14:02:10 INFO - -h, --help show this help message and exit 14:02:10 INFO - -d DEST, --destination=DEST 14:02:10 INFO - Directory to install application into. [default: 14:02:10 INFO - "/builds/slave/test"] 14:02:10 INFO - --app=APP Application being installed. [default: firefox] 14:02:10 INFO - mkdir: /builds/slave/test/build/application 14:02:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 14:02:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a2.en-US.mac.dmg --destination /builds/slave/test/build/application 14:02:41 INFO - Reading from file tmpfile_stdout 14:02:41 INFO - Output received: 14:02:41 INFO - /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox 14:02:41 INFO - Running post-action listener: _resource_record_post_action 14:02:41 INFO - [mozharness: 2017-01-05 22:02:41.589935Z] Finished install step (success) 14:02:41 INFO - [mozharness: 2017-01-05 22:02:41.590074Z] Running run-tests step. 14:02:41 INFO - Running pre-action listener: _resource_record_pre_action 14:02:41 INFO - Running main action method: run_tests 14:02:41 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:02:41 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:02:41 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:02:41 INFO - 2017-01-05 14:02:41.604 screenresolution[1624:7813] starting screenresolution argv=screenresolution get 14:02:41 INFO - 2017-01-05 14:02:41.607 screenresolution[1624:7813] Display 0: 1600x1200x32@60 14:02:41 INFO - 2017-01-05 14:02:41.612 screenresolution[1625:7815] starting screenresolution argv=screenresolution list 14:02:41 INFO - Available Modes on Display 0 14:02:41 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:02:41 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:02:41 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:02:41 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:02:41 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:02:41 INFO - Intel Iris: 14:02:41 INFO - Chipset Model: Intel Iris 14:02:41 INFO - Type: GPU 14:02:41 INFO - Bus: Built-In 14:02:41 INFO - VRAM (Dynamic, Max): 1536 MB 14:02:41 INFO - Vendor: Intel (0x8086) 14:02:41 INFO - Device ID: 0x0a2e 14:02:41 INFO - Revision ID: 0x0009 14:02:41 INFO - Displays: 14:02:41 INFO - Display: 14:02:41 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:02:41 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:02:41 INFO - Display Serial Number: 1600x1200 60 14:02:41 INFO - Main Display: Yes 14:02:41 INFO - Mirror: Off 14:02:41 INFO - Online: Yes 14:02:41 INFO - Rotation: Supported 14:02:41 INFO - Return code: 0 14:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 14:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 14:02:41 INFO - Python 2.7.3 14:02:41 INFO - Return code: 0 14:02:41 INFO - grabbing minidump binary from tooltool 14:02: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:02:41 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 0x10dbacd30>, '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 0x10da12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10dba4b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 14:02:41 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:02:41 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:02:41 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:02:41 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 14:02:41 INFO - Return code: 0 14:02:41 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 14:02:41 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 14:02:41 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 14:02:41 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 14:02:41 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 14:02:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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:02:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Aurora --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0120 --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:02:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.03eqGfd3Gt/Render', 14:02:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 14:02:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 14:02:41 INFO - 'HOME': '/Users/cltbld', 14:02:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 14:02:41 INFO - 'LOGNAME': 'cltbld', 14:02:41 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 14:02:41 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 14:02:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 14:02:41 INFO - 'MOZ_NO_REMOTE': '1', 14:02:41 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 14:02:41 INFO - 'NO_EM_RESTART': '1', 14:02:41 INFO - 'PAGER': '/bin/cat', 14:02:41 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 14:02:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 14:02:41 INFO - 'PWD': '/builds/slave/test', 14:02:41 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 14:02:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 14:02:41 INFO - 'SHELL': '/bin/bash', 14:02:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.XkeoO2THfR/Listeners', 14:02:41 INFO - 'TMPDIR': '/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/', 14:02:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 14:02:41 INFO - 'USER': 'cltbld', 14:02:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 14:02:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 14:02:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 14:02:41 INFO - 'XPC_FLAGS': '0x0', 14:02:41 INFO - 'XPC_SERVICE_NAME': '0', 14:02:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 14:02:41 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Aurora', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0120', '--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:02:42 INFO - mozversion application_buildid: 20170105122013 14:02:42 INFO - mozversion application_changeset: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 14:02:42 INFO - mozversion application_display_name: FirefoxDeveloperEdition 14:02:42 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 14:02:42 INFO - mozversion application_name: Firefox 14:02:42 INFO - mozversion application_remotingname: firefox-dev 14:02:42 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:42 INFO - mozversion application_vendor: Mozilla 14:02:42 INFO - mozversion application_version: 52.0a2 14:02:42 INFO - mozversion platform_buildid: 20170105122013 14:02:42 INFO - mozversion platform_changeset: dbbbc4ab2f1bd1e37ebbc45cdedf291074717da0 14:02:42 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-aurora 14:02:42 INFO - mozversion platform_version: 52.0a2 14:02:42 INFO - using testdate: 1483653762 14:02:42 INFO - actual date: 1483653762 14:02:42 INFO - starting webserver on 'localhost:49248' 14:02:42 INFO - SUITE-START | Running 2 tests 14:02:42 INFO - TEST-START | dromaeo_css 14:02:42 INFO - operating with platform_type : mac_ 14:02:42 INFO - Initialising browser for dromaeo_css test... 14:02:42 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpXQc4eN/profile http://localhost:49248/getInfo.html) 14:02:45 INFO - PROCESS | 1635 | 2017-01-05 14:02:45.389 plugin-container[1637:7993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d4b, name = 'com.apple.tsm.portname' 14:02:45 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:02:45 INFO - PROCESS | 1635 | 2017-01-05 14:02:45.391 plugin-container[1637:7993] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920f, name = 'com.apple.CFPasteboardClient' 14:02:45 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:02:45 INFO - PROCESS | 1635 | 2017-01-05 14:02:45.391 plugin-container[1637:7993] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:02:45 INFO - PROCESS | 1635 | __metrics Screen width/height:1600/1200 14:02:45 INFO - PROCESS | 1635 | colorDepth:24 14:02:45 INFO - PROCESS | 1635 | Browser inner width/height: 1024/689 14:02:45 INFO - PROCESS | 1635 | __metrics 14:02:45 ERROR - PROCESS | 1635 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:02:46 INFO - TEST-INFO | 1635: exit 0 14:02:46 INFO - Browser initialized. 14:02:46 INFO - Running cycle 1/1 for dromaeo_css test... 14:02:46 INFO - TEST-INFO | started process 1638 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmpXQc4eN/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:02:49 INFO - PROCESS | 1638 | 2017-01-05 14:02:49.043 plugin-container[1639:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 14:02:49 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:02:49 INFO - PROCESS | 1638 | 2017-01-05 14:02:49.045 plugin-container[1639:8123] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4507, name = 'com.apple.CFPasteboardClient' 14:02:49 INFO - PROCESS | 1638 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:02:49 INFO - PROCESS | 1638 | 2017-01-05 14:02:49.045 plugin-container[1639:8123] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:02:58 INFO - PROCESS | 1638 | RSS: Main: 229744640 14:02:58 INFO - PROCESS | 1638 | 14:06:02 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49248/tests/dromaeo/cssquery-ext.html) 14:06:04 INFO - PROCESS | 1638 | RSS: Main: 234340352 14:06:04 INFO - PROCESS | 1638 | 14:09:06 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-ext.html (next: http://localhost:49248/tests/dromaeo/cssquery-jquery.html) 14:09:07 INFO - PROCESS | 1638 | RSS: Main: 243761152 14:09:07 INFO - PROCESS | 1638 | 14:12:09 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49248/tests/dromaeo/cssquery-mootools.html) 14:12:09 INFO - PROCESS | 1638 | RSS: Main: 246423552 14:12:09 INFO - PROCESS | 1638 | 14:15:11 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49248/tests/dromaeo/cssquery-prototype.html) 14:15:12 INFO - PROCESS | 1638 | RSS: Main: 246124544 14:15:12 INFO - PROCESS | 1638 | 14:18:15 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49248/tests/dromaeo/cssquery-yui.html) 14:18:15 INFO - PROCESS | 1638 | RSS: Main: 249081856 14:18:15 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | Cycle 1(1): loaded http://localhost:49248/tests/dromaeo/cssquery-yui.html (next: http://localhost:49248/tests/dromaeo/cssquery-dojo.html) 14:21:18 INFO - PROCESS | 1638 | RSS: Main: 250331136 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | __start_tp_report 14:21:18 INFO - PROCESS | 1638 | _x_x_mozilla_page_load 14:21:18 INFO - PROCESS | 1638 | _x_x_mozilla_page_load_details 14:21:18 INFO - PROCESS | 1638 | |i|pagename|runs| 14:21:18 INFO - PROCESS | 1638 | |0;dojo.html;2989.4320079184954;3409.3181363727244;3418.2479854432017;3457.39495588272;3463.2554000889804;5819.9709001454985;5879.647221166733;5896.88206235875;5902.409759024095;5902.996490596609;5906.88186236275;5951.88096238075;5986.982213023785;6007.6996150192535;6026.849328766783;5256.369235691713;5305.602079844008;5376.51611354978;5397.46025397461;5443.510084092431;5401.621886467949;5410.269613602172;5413;5420.024395608789;5425.430329815372;5254.527092561669;5267.631265811395;5395.136778115502;5408.134698448249;5435.293411856473;5290.100682883881;5299.973500132494;5320.893582128375;5339.119045357511;5353.223782551547;5260.316158899319;5264.2893209416625;5293.682379057231;5366.329208848894;5402.945970540323;5687.374388817227;5988.371221021817;6009.489193418564;6023.668698221596;6036.698165091771;6190;6191.380861913818;6209.441150296495;6212.565120441571;6230.96884515579;6261.092141639482;6263.937360626426;6346.238451385817;6347.301796802348;6414.102025716404;5095.490450954875;5885.911711324334;6058.909116363214;6064.120702498113;6082.635041897412;6230.7819226326865;6271.749130034838;6302.306746257908;6309.242890853127;6410.839729006719;6247.094171345129;6252.781152659635;6264.404881536247;6265.686715664198;6272.6236425814595;6182.350853160354;6271.874562508724;6285.622862628165;6292.370762923671;6306.747730090745;6172.351903050115;6197.876042479125;6208.2239720034995;6212.378762123752;6254.21822272216;6144.846378840565;6208.844778880474;6215.12988181655;6215.223097112861;6245.156903817927;5011.373578302712;5063.417706963673;5063.974680126649;5073.086844367976;5078.263651770472;4406.361077643724;4427;4467.218236808546;4487.663425243055;4513.255312873404;5337.492938170868;5373.408925018245;5809.593328466966;6042.516598672096;6106.053561697944;5892.558058145656;6198.194234749454;6258.561900666909;6274.466670332981;6283.37166283368;5994.6403215807195;6006.819795406145;6073.514118870568;6086.330503644595;6098.878022439526;5523.861903452445;5559.054960656698;5570.415106413769;5583;5640.5487560995025;6415.230172379344;6497.74009039652;6498.5126115541525;6500.187476565429;6539.182602174633;6163.506919446523;6214.9689251553455;6253.124562561154;6290.370962903673;6310.116583678197;6327.746890124344;6474.546781725234;6475.190601174853;6479.222493300834;6480.44916182111;6265.091561723433;6267.655278959701;6278.529110316653;6286.151369565233;6293.685315734104;6134.78528251509;6159.599626024294;6172.19761431029;6249.031400132987;6274.623522588659;6177.382261773966;6197.845053873689;6212.751489940351;6235.906461402992;6246.156768836149;6159.106929495288;6205.6586887721605;6241.750329986749;6242.531810114168;6266.248050234001;5125.79496820123;5351.1705685617235;5603.131514615241;6066.4540191382985;6086.756529738938;6131.9272270139245;6159.476444502267;6161.291451482957;6189.566730328834;6267.9373206267355;6039.728212230548;6115.388461153849;6139.938600613937;6163.722632481638;6178.938210617836 14:21:18 INFO - PROCESS | 1638 | |1;ext.html;485.3617492996709;489.3711580618905;500.6020213929925;521.2494008627576;530.204692960559;38109.61890381095;40337.19325613493;40385;40414;40491.79754101229;41326;41730.791346043305;41860.372094418606;41861.162776744444;41955.58044419555;9358.62565497381;9564.426134431922;9568.282378821581;9586.90413095867;9591.952040239788;9478.431294122374;9501.80996380072;9567.66513172039;9568.33021688482;9591.424514529115;9515.429074255531;9589.993050729678;9590.136887680108;9604.615815367377;9611.086946740048;7900.960495197516;8684.175003374668;9565.330426870121;9566.426014439156;9574.138327550554;9468.90531094694;9522.904770952271;9549.188318992894;9580.281478889041;9603.855942160873;39892.40161397582;40083.198336033405;40224;40234.792956211364;40437;3345.414552453311;3441.328940856534;3481.547398838135;3531.2584357285;3673.797941113238;28823.135305940854;29327;29563.26091847721;29884;30292.84853575718;3199.744020478356;3203.2792621660174;3208.197950512372;3234.272288735039;3291.4240007998505;509525;512230;515118;515316.4234178843;517670;49313;60078.69960650169;60365.69817150974;60460.79078418407;60655.393446064976;59295.40704592899;61539;62826;63234.36765632377;63267;16009.199540022952;16116.35534578627;16248;16262.674746505003;16264.268107935171;12449.315287659265;12526;12604.936975315064;12621.495140194473;12637.684057898443;15657.217139142997;16485.83514164867;16572.419965301156;16595.419160329573;16620.418285359956;3178.8092714437207;3275.5741753571883;6821.317868213139;6848.897266541006;6867.07294515244;817.5585184000691;819.6803246733779;1014.102519270451;1022.2486472442748;1023.9504507879483;29789.553156702666;29851.85074074616;29959.700402996128;30074.54888176679;30202.09393718192;1000.4847503535685;1034.7775228325945;1055.7466208109997;1057.9047885690325;1136.8561592993492;12765.361731913366;15124.319405626773;15391.384344626313;15542.057242844645;15753.212339382984;680755;684851;685327;688648;689687;49450.76373090629;51322.97354052898;53818.46181538134;57738.42261577414;58417.707911461024;1139.3562637110006;1167.6231941420872;1343.402186027228;1377.4008306386754;1379.6275005748591;23270.76729232686;28395.574066388606;29428.85285573644;29967.70032299649;30052.398952021274;49829.75085124551;49989.25016124834;50342.748286258335;50383.49616503788;50498.74750626223;604855;611747;612345.1358404869;612359;614017;23270.65094023557;24652.38369040788;26033.479330413666;26383.944642214097;26545.867270663523;44646.55353446424;45494.545054550355;45970.31044534402;46183.30725039195;46424.767876160404;30152.84923575368;37653.81173094227;38160.42759358556;38507;38535.22929541337;37366.62633373631;37854.62145378511;38660.420093698056;38778.80610597042;38955;37550.80818130489;38491.6150838488;38853.611463885;39499.40750888796;40103.59896400999;28776.568351474325;33985.3202935935;34514.30971380508;34589.13527161841;34899.4765078519;22771.747553884696;23281.650775238046;23362.299131026095;23493.006188947576;25838.870805645853 14:21:18 INFO - PROCESS | 1638 | |2;jquery.html;314.7576366198026;316.5504982924249;365.251234968315;374.16747736286754;392.89195471245404;18467.907660461697;19227.80772192276;19285.903570482147;19461.805381946175;19517;16937.407190748294;18941.810581894177;18983.145758440867;20010.599788004267;20101;5019.422766381858;5605.607607467468;5879.11813228017;5879.265091863517;5898.7640494380385;5458.5087342139195;5750;5775.797847075353;5782.392848750884;5796.913046304308;5712.200291959129;5721.256236689224;5727.6563406195555;5740.56945729072;5744.597878148531;5576.163575463673;5903.763849446017;5939.495142912836;5993.310769261503;5996.340402555716;5569.972150139264;5746.13807928814;5752.309722833245;5776.075827867521;5787.987102257088;12816.871831281662;12902.03234757397;13033.543825966046;13095.541656042087;13177.077604567685;2197.2419515267206;2269.3872654383226;2328.080408238753;2352.3766201956323;2837.2764944939113;8518.318534517224;9557.378770379972;9741.366811157252;9801.31390802637;10276.845847312296;1781.3676144968608;1836.8897866128075;1843.1060935446299;1867.7128254272577;1979.0698371765247;73087.63456182685;74311.62844185799;111331;113866.43066784613;125998.37000815029;8787.472751634923;8928.419652722552;8968.955155224183;9239.353245272767;10171.186305095574;10167.949160254151;12304.692382690504;12336.75326493483;12360.938195308965;12442.937785311016;7880;8142.389320800845;8142.430029897969;8148.714794982266;8182.263596276363;8325.625346859406;8463.915360846313;8521.786955326166;8561.0582835888;8652.97417959679;3103.8292893890602;3849.345611246095;3898.805059747058;3956.4460975463458;3968.345223038224;1929.4308179087202;1934.8839069655867;1941.9126139323764;1995.7305763722002;1998.3805020443708;782.5265714242915;836.4270474724833;920.3235621817959;925.6991477769752;1022.7903279827766;19554.413367598994;21033.474163146042;21072.840993745493;21198.576028479343;21345.46636334073;925.6112432778249;926.9062506242699;938.333783014054;951.7810903492235;992.2409356842022;10592.788144237073;11141.442927853575;11151.498182581803;11164.888351116548;11184.832227516594;561246;563306;566844;569745;577232;99815;123276;126964;127366;130698;778.6768491076253;946.6355453150586;957.8132264208413;959.491469521155;963.4749061761216;18368.448946531626;18878.622427551647;19011;19066.904665477137;19185;52414.68963275949;52658.68353291045;52739.472605273455;53812.578433969786;53838.46161538334;52498;74680.50638987082;77689.61155194188;78453.60773196098;79527.60236198783;7843.176466471166;7847.332976697045;7878.369730421668;7924.6037698116015;7989.600519974094;10147.949260253652;11937.641870743892;12075.51697932108;12139.210951288138;12182.02543796512;10168.796624067329;10248.128909042814;10411.323263987817;10588.84116738265;10611.787764244518;9094.909050909406;10054.547545360623;10328.948355258175;10373.740656483647;10659.147268218368;10329.74175645615;10456.529456174643;10615.469226538797;10631.255812093079;10939.28894621847;5465.289512363367;5787;6810.625415601991;7240.275972402717;7599.822027585733;6476.287608362982;10601.893981060091;10724.624638137628;10765.3540787553;10967.903209678969 14:21:18 INFO - PROCESS | 1638 | |3;mootools.html;1405.7610206264933;1451.0785651111541;1620.3842539834861;1760.3486709917333;1795.3357257814603;6172.722227499762;7069.681864316105;7805.843883122334;8021.719239826607;8528.232459078681;6552.868942621157;8189.426740128193;8223.62993665285;8314.91685083149;8327.333813294937;1926.2969016309087;2313.248194336839;2335.532893421314;2338.6608941703444;2353.62342025276;2320.071971211512;2350.165691179632;2364.361622361961;2368.5973384524673;2368.7039120109985;2315.571619250444;2321.512482378694;2343.5547246023284;2345.57779599672;2352.529494101178;2295.540891821626;2301.0450662974845;2313.838031337807;2324.8140148788057;2326.638916234005;2322.5354929014097;2342.7774361435636;2354.587947109266;2357.846739961897;2368.1237941961413;8246.299064579518;8383.664653413856;8437.325013998927;8460.2385785279;8482.575871206414;4290.485141783006;4960.578350840183;5090.032893750175;5131.281620573122;5146.562542183881;2958.9408211835644;3259.6577359377397;3423.7089847363004;3526.612072672004;3570.0182449826248;1050.4642632257528;1057.7778666479971;1095.1348434736549;1101.3336931156614;1103.1230172013268;10890.237683362167;10902.836457453144;10922.344659320466;10953.671389858317;11016.724581885437;4502.099580083996;4584.839530616412;4664.673472856935;4688.859334219979;4735.881602959885;7932.444728868923;9344.90655093454;9403.90596094044;9417;9422.717318480456;5126.154184559506;5147.742612869341;5183.637145399785;5193.584513238932;5210.53105220532;4055.5538890721996;4690.273007683814;4701.177293973541;4744.312074749142;4769.952300476951;3305.3389322135667;3795.867144649924;3800.847966081326;3862.1696335288048;3890.2219556088894;1085.9619913302997;1262.6464589914838;1270.3521204185931;1273.7579859826603;1279.4754150798128;877.2280393254023;877.4998250996871;894.6063731957918;901.427593478133;907.3784457646533;5533.889322213533;5715.657060576295;5900.970495147497;5907.586468947218;5968.074948383008;765.9310662040443;836.2599099796723;843.8396704626101;892.5849479991705;935.704049890906;3897.162110146332;3909.276783795007;3918.314294998421;3922.0391004204153;3923.8234279457492;9666.661666841626;9992.950035249778;10161.644342447978;10170.745731356626;10186.083252507162;9158.358914875895;9518.333716639769;9833.950830245803;10085.041921017491;10106.343087699275;6659.700313485905;6818.624975626289;7165.6417179140835;7834;7865.331446826969;4723.669343145947;5429.864253393696;5448.673079615236;5817.389174136824;5882.5293976482635;7631.694732210872;9022.368434209542;9133.725988220363;9233.261339092722;9297;7307.5980821055355;9316.580753865957;9370.10983956523;9392.013838546854;9571.947085820693;6787.762428314985;7645.1972542882195;7712.5758083305955;7725.845483090195;7870.134285228506;7856.214378562327;7899.802504937423;7971.760847174594;7972.402069844668;7973.880391794011;7001.929980700128;7140.964295178491;7182.102237220348;7192.992980982562;7202.459815513962;5521.503064724194;6943.201531823905;7000.404965577983;7115.075040244735;7129.679164437509;6125.142480052886;6619.933800662125;6642.468602511691;6689.26418094019;6707.396334329934;4795.640326975421;4807.6394270429155;4831.396075490564;4861.270809378621;4951.628627852853;4144.191882582867;4777.402824646919;4801.95198048029;4889.071076495455;5067.594592432522 14:21:18 INFO - PROCESS | 1638 | |4;prototype.html;288.71705728386183;303.81467304943993;304.19236925961553;306.7316098413888;315.4243505602275;4762.833300834472;5272.6836389816635;5447.346318441792;5471.701885208212;5588.888222235553;4464.5758652928;4959.23131914553;5199.324087868572;5284.841454756353;5422.864428389292;4144.564820693821;4149.336106223005;4161.0845613964875;4165.312723400635;4182.414461975326;3800.6959443244473;4072.3891416287497;4164.812583433738;4170.812313445902;4186.37204419338;3231.6588615724445;3326.6839650233187;4009.7594144351287;4011.1376054148354;4045.59544045596;4006.934694958359;4029.657479114279;4036.4752582164424;4050.9670034141104;4090.7750073745933;3521.6830485256196;3523;3527.9823600882087;3542.8405721742583;3544.007677850206;3999.580044095357;4959.677620954626;5265.341832270966;5328.200769884548;5344.91982620261;3892.922141557153;4654.301854721785;4658.813647454098;4662.976685116552;4666.4633567139535;5056.84829455117;5559.527440167591;5694.715264236772;5754.625949313281;5758.827235182951;3617.981910090459;3860.864869729546;3867.864624738149;3873.845046198149;3896.5129358830145;5299.198272587378;5920.644761314335;5938.6436813791315;5955.642661440284;5992.412010817137;3563.0937448464947;3638.5633723952765;3906.2382835346834;3909.237698648793;3918.8236529356245;3242.691944265291;3837.808109594509;3882.611738826095;3894.786800889278;3915.784631845219;3904.763331784988;4057.14799892026;4203.495580530356;4559.726416415026;4615.838445654386;5097.541221290102;5464.562834973272;5710.3433105193435;5739.885202295931;5796.362400135981;1047.664747280863;1240.3240234072452;1268.4398190226073;1273.840769903762;1277.5145444730942;752.8255920763627;756.5233902641301;827.2761333832897;870.9085546017703;872.9301655867515;4283.6144746972295;4668.206404911173;4678.60231880294;5038.5969122470115;5046.798128074909;5456.854060647309;5538.529225015919;5569.359523654751;5576.860578485489;5643.746031428595;4336.9783151084675;4754.833580824724;5017.774200161001;5065.936153407825;5088.600634825379;2894.6250530998027;2907.7496676428914;2910.4179164167253;2913.21343237329;2916.2855100500515;4861.611071114373;5552.5280351169795;5711.742971566289;5769.480746732815;5779.33537643168;4726.952730472652;5228.947710522846;5579.856129493444;5589.916151257653;5689.971550142222;5161.174212126042;5226.63413561047;5255.421903590525;5510.972445137749;5742.54059675225;5069.308512763265;5248.448912864094;5529.502344788988;5541.806036788693;5545.417731138173;5146.150885103916;5379.704116273642;5389.434109418455;5436.429174936733;5453.618246722692;4880.633952453652;5166.689998600096;5685.545156387397;5753.8561535961935;5782.855428614318;3466.3067386522293;3479.5476588043393;3543.2559162575117;3714.0157858167067;3725.9254814904093;4988.4263309719845;5469.72651367438;5520.641158324696;5926.880817831738;5955.553333499918;5499.725013749377;5650.802221922213;5693;5753.654780713171;5828.766849326149;4619.445666520039;4799.28010798383;5724.883647688661;5774.653520788766;5812.389699081705;5374.247605335335;5389.784408623611;5642.294713160855;5643.573263496736;5696.487316141402;4727.912580106664;5386.946130538645;5799.536037117105;5807.390224026416;5812;5669.31968163823;5696.784379559179;5700.686462244616;5722.512146841935;5783.768649253982 14:21:18 INFO - PROCESS | 1638 | |5;yui.html;667.5927684112692;688.7761477519804;769.1846642558892;770.5838847022608;833.5415521463194;14372.928135359321;14997.17515536645;15018.849811501881;15034.94755367122;15060.24698765063;12649.114561980641;12664.240145591271;12737.872621273784;12738.80891786624;12742.17175883567;782.3076577229136;815.6370415165254;840.6343240690319;841.3353450773882;844.7423535821572;679.8164495586188;814.7474282972267;823.6005537314397;825.63671984327;830.995845020774;804.569555287921;855.0978717453085;867.7093173786789;885.1635204731531;890.9153630405101;833.6915341323719;856.5075081827974;871.1854416120947;872.7032808844991;876.4916348517876;619.2074145094286;650.9479241660656;660.2934859700123;663.7013343995221;665.0024962556166;12395.256284622952;12429.751404971941;12444.440000200013;12480.81278780819;12487.438065287084;10182.9490852546;10589.417582032986;11600.187986840925;11863.28820270778;11994.760104797855;10174.51513934944;10216.846747298796;10337.89662103377;10525.210609204263;10576.206784491194;3493.755437119403;3515.6308587598446;3548.077499850032;3661.2677464506937;3665.358562251622;87742;89006.10993890042;91296.63055054245;92207.07792922053;93300.53349733276;7498.250174982458;8693.695720649746;8783.121687831166;8870.73387798367;9185.954070229605;18783.90608046951;18784.342548010754;18798.812011879978;18866;18940.147693353556;7433.442491813135;7633.809154771176;7719.498232614862;7903.762887113281;7904.762857114296;4214.072903961123;4236.661067114624;4259.361095835649;4341.283688191413;4392.450943632046;2745.32739478825;2845.9857700711777;3399.8810041648426;3456.827158642058;3484.6341134180548;581.6539159200269;609.3053918532876;620.3238470067648;643.6556442303345;660.1088530483809;513.7662363624542;518.3753576939779;534.8367301120048;575.9280089988752;582.6766144789682;7607;8878.467291962503;8944;8981.595828187617;9234.584443700049;541.9647722898;632.7880160146402;638.6551262318296;649.7238673563717;690.6822861483673;3663.8717644882836;4256.45804008647;4398.978005109954;4501.707389019703;4578;72490.27509724941;94579.10841783263;99328.50335748275;110927;113143.86856131499;82966;84906;91589.62615560772;99015;100576.4971175154;81187;83349;84765.72851407234;85327.5733621328;85613.57193213994;8338.833223335621;8673.783155421164;8676.08901065379;8699.695510657097;8707.3020760951;41071.589284106776;47554.76222618865;52697.73651131873;56513.02204422825;56681.71659141678;59954.40045599488;63495.6825215871;66483.33516664771;68262;75549;12081.335526545772;12191.634250972485;12332.753344932871;12363.876361236273;12391.132620716824;10668.89331106679;11846.940765296464;12146.724593917865;13637.181769093886;13878.722425551634;10865.293755906157;11061.336319820837;11631.243969141979;13180.538681146114;13311.33443327849;11979.460924258256;12703.618891433272;12769;13041.804372934603;13233.007524435514;12559.246445213317;12585.24488530691;12586.811197832223;12745.681357966125;13396.93301533486;10517.368957862553;10779.622713205;10806.297590656583;10811.405372704578;11092.833607495733;9558.378705384128;10268.794624107326;11181.273217240854;11439.370834603844;11490.712732181762 14:21:18 INFO - PROCESS | 1638 | __end_tp_report 14:21:18 INFO - PROCESS | 1638 | __start_cc_report 14:21:18 INFO - PROCESS | 1638 | _x_x_mozilla_cycle_collect,375 14:21:18 INFO - PROCESS | 1638 | __end_cc_report 14:21:18 INFO - PROCESS | 1638 | __startTimestamp1483654878371__endTimestamp 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | ------- Summary: start ------- 14:21:18 INFO - PROCESS | 1638 | Number of tests: 6 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#0] dojo.html Cycles:165 Average:5837.79 Median:6115.39 stddev:647.07 (10.6%) stddev-sans-first:609.26 14:21:18 INFO - PROCESS | 1638 | Values: 2989.4 3409.3 3418.2 3457.4 3463.3 5820.0 5879.6 5896.9 5902.4 5903.0 5906.9 5951.9 5987.0 6007.7 6026.8 5256.4 5305.6 5376.5 5397.5 5443.5 5401.6 5410.3 5413.0 5420.0 5425.4 5254.5 5267.6 5395.1 5408.1 5435.3 5290.1 5300.0 5320.9 5339.1 5353.2 5260.3 5264.3 5293.7 5366.3 5402.9 5687.4 5988.4 6009.5 6023.7 6036.7 6190.0 6191.4 6209.4 6212.6 6231.0 6261.1 6263.9 6346.2 6347.3 6414.1 5095.5 5885.9 6058.9 6064.1 6082.6 6230.8 6271.7 6302.3 6309.2 6410.8 6247.1 6252.8 6264.4 6265.7 6272.6 6182.4 6271.9 6285.6 6292.4 6306.7 6172.4 6197.9 6208.2 6212.4 6254.2 6144.8 6208.8 6215.1 6215.2 6245.2 5011.4 5063.4 5064.0 5073.1 5078.3 4406.4 4427.0 4467.2 4487.7 4513.3 5337.5 5373.4 5809.6 6042.5 6106.1 5892.6 6198.2 6258.6 6274.5 6283.4 5994.6 6006.8 6073.5 6086.3 6098.9 5523.9 5559.1 5570.4 5583.0 5640.5 6415.2 6497.7 6498.5 6500.2 6539.2 6163.5 6215.0 6253.1 6290.4 6310.1 6327.7 6474.5 6475.2 6479.2 6480.4 6265.1 6267.7 6278.5 6286.2 6293.7 6134.8 6159.6 6172.2 6249.0 6274.6 6177.4 6197.8 6212.8 6235.9 6246.2 6159.1 6205.7 6241.8 6242.5 6266.2 5125.8 5351.2 5603.1 6066.5 6086.8 6131.9 6159.5 6161.3 6189.6 6267.9 6039.7 6115.4 6139.9 6163.7 6178.9 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#1] ext.html Cycles:180 Average:72510.10 Median:39987.80 stddev:162825.55 (407.2%) stddev-sans-first:163192.52 14:21:18 INFO - PROCESS | 1638 | Values: 485.4 489.4 500.6 521.2 530.2 38109.6 40337.2 40385.0 40414.0 40491.8 41326.0 41730.8 41860.4 41861.2 41955.6 9358.6 9564.4 9568.3 9586.9 9592.0 9478.4 9501.8 9567.7 9568.3 9591.4 9515.4 9590.0 9590.1 9604.6 9611.1 7901.0 8684.2 9565.3 9566.4 9574.1 9468.9 9522.9 9549.2 9580.3 9603.9 39892.4 40083.2 40224.0 40234.8 40437.0 3345.4 3441.3 3481.5 3531.3 3673.8 28823.1 29327.0 29563.3 29884.0 30292.8 3199.7 3203.3 3208.2 3234.3 3291.4 509525.0 512230.0 515118.0 515316.4 517670.0 49313.0 60078.7 60365.7 60460.8 60655.4 59295.4 61539.0 62826.0 63234.4 63267.0 16009.2 16116.4 16248.0 16262.7 16264.3 12449.3 12526.0 12604.9 12621.5 12637.7 15657.2 16485.8 16572.4 16595.4 16620.4 3178.8 3275.6 6821.3 6848.9 6867.1 817.6 819.7 1014.1 1022.2 1024.0 29789.6 29851.9 29959.7 30074.5 30202.1 1000.5 1034.8 1055.7 1057.9 1136.9 12765.4 15124.3 15391.4 15542.1 15753.2 680755.0 684851.0 685327.0 688648.0 689687.0 49450.8 51323.0 53818.5 57738.4 58417.7 1139.4 1167.6 1343.4 1377.4 1379.6 23270.8 28395.6 29428.9 29967.7 30052.4 49829.8 49989.3 50342.7 50383.5 50498.7 604855.0 611747.0 612345.1 612359.0 614017.0 23270.7 24652.4 26033.5 26383.9 26545.9 44646.6 45494.5 45970.3 46183.3 46424.8 30152.8 37653.8 38160.4 38507.0 38535.2 37366.6 37854.6 38660.4 38778.8 38955.0 37550.8 38491.6 38853.6 39499.4 40103.6 28776.6 33985.3 34514.3 34589.1 34899.5 22771.7 23281.7 23362.3 23493.0 25838.9 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#2] jquery.html Cycles:180 Average:32582.49 Median:2174.67 stddev:94651.40 (4352.5%) stddev-sans-first:94885.91 14:21:18 INFO - PROCESS | 1638 | Values: 314.8 316.6 365.3 374.2 392.9 18467.9 19227.8 19285.9 19461.8 19517.0 16937.4 18941.8 18983.1 20010.6 20101.0 5019.4 5605.6 5879.1 5879.3 5898.8 5458.5 5750.0 5775.8 5782.4 5796.9 5712.2 5721.3 5727.7 5740.6 5744.6 5576.2 5903.8 5939.5 5993.3 5996.3 5570.0 5746.1 5752.3 5776.1 5788.0 12816.9 12902.0 13033.5 13095.5 13177.1 2197.2 2269.4 2328.1 2352.4 2837.3 8518.3 9557.4 9741.4 9801.3 10276.8 1781.4 1836.9 1843.1 1867.7 1979.1 73087.6 74311.6 111331.0 113866.4 125998.4 8787.5 8928.4 8969.0 9239.4 10171.2 10167.9 12304.7 12336.8 12360.9 12442.9 7880.0 8142.4 8142.4 8148.7 8182.3 8325.6 8463.9 8521.8 8561.1 8653.0 3103.8 3849.3 3898.8 3956.4 3968.3 1929.4 1934.9 1941.9 1995.7 1998.4 782.5 836.4 920.3 925.7 1022.8 19554.4 21033.5 21072.8 21198.6 21345.5 925.6 926.9 938.3 951.8 992.2 10592.8 11141.4 11151.5 11164.9 11184.8 561246.0 563306.0 566844.0 569745.0 577232.0 99815.0 123276.0 126964.0 127366.0 130698.0 778.7 946.6 957.8 959.5 963.5 18368.4 18878.6 19011.0 19066.9 19185.0 52414.7 52658.7 52739.5 53812.6 53838.5 52498.0 74680.5 77689.6 78453.6 79527.6 7843.2 7847.3 7878.4 7924.6 7989.6 10147.9 11937.6 12075.5 12139.2 12182.0 10168.8 10248.1 10411.3 10588.8 10611.8 9094.9 10054.5 10328.9 10373.7 10659.1 10329.7 10456.5 10615.5 10631.3 10939.3 5465.3 5787.0 6810.6 7240.3 7599.8 6476.3 10601.9 10724.6 10765.4 10967.9 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#3] mootools.html Cycles:180 Average:5365.35 Median:5138.92 stddev:2917.87 (56.8%) stddev-sans-first:2910.88 14:21:18 INFO - PROCESS | 1638 | Values: 1405.8 1451.1 1620.4 1760.3 1795.3 6172.7 7069.7 7805.8 8021.7 8528.2 6552.9 8189.4 8223.6 8314.9 8327.3 1926.3 2313.2 2335.5 2338.7 2353.6 2320.1 2350.2 2364.4 2368.6 2368.7 2315.6 2321.5 2343.6 2345.6 2352.5 2295.5 2301.0 2313.8 2324.8 2326.6 2322.5 2342.8 2354.6 2357.8 2368.1 8246.3 8383.7 8437.3 8460.2 8482.6 4290.5 4960.6 5090.0 5131.3 5146.6 2958.9 3259.7 3423.7 3526.6 3570.0 1050.5 1057.8 1095.1 1101.3 1103.1 10890.2 10902.8 10922.3 10953.7 11016.7 4502.1 4584.8 4664.7 4688.9 4735.9 7932.4 9344.9 9403.9 9417.0 9422.7 5126.2 5147.7 5183.6 5193.6 5210.5 4055.6 4690.3 4701.2 4744.3 4770.0 3305.3 3795.9 3800.8 3862.2 3890.2 1086.0 1262.6 1270.4 1273.8 1279.5 877.2 877.5 894.6 901.4 907.4 5533.9 5715.7 5901.0 5907.6 5968.1 765.9 836.3 843.8 892.6 935.7 3897.2 3909.3 3918.3 3922.0 3923.8 9666.7 9993.0 10161.6 10170.7 10186.1 9158.4 9518.3 9834.0 10085.0 10106.3 6659.7 6818.6 7165.6 7834.0 7865.3 4723.7 5429.9 5448.7 5817.4 5882.5 7631.7 9022.4 9133.7 9233.3 9297.0 7307.6 9316.6 9370.1 9392.0 9571.9 6787.8 7645.2 7712.6 7725.8 7870.1 7856.2 7899.8 7971.8 7972.4 7973.9 7001.9 7141.0 7182.1 7193.0 7202.5 5521.5 6943.2 7000.4 7115.1 7129.7 6125.1 6619.9 6642.5 6689.3 6707.4 4795.6 4807.6 4831.4 4861.3 4951.6 4144.2 4777.4 4802.0 4889.1 5067.6 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#4] prototype.html Cycles:180 Average:4495.13 Median:5061.39 stddev:1395.07 (27.6%) stddev-sans-first:1362.78 14:21:18 INFO - PROCESS | 1638 | Values: 288.7 303.8 304.2 306.7 315.4 4762.8 5272.7 5447.3 5471.7 5588.9 4464.6 4959.2 5199.3 5284.8 5422.9 4144.6 4149.3 4161.1 4165.3 4182.4 3800.7 4072.4 4164.8 4170.8 4186.4 3231.7 3326.7 4009.8 4011.1 4045.6 4006.9 4029.7 4036.5 4051.0 4090.8 3521.7 3523.0 3528.0 3542.8 3544.0 3999.6 4959.7 5265.3 5328.2 5344.9 3892.9 4654.3 4658.8 4663.0 4666.5 5056.8 5559.5 5694.7 5754.6 5758.8 3618.0 3860.9 3867.9 3873.8 3896.5 5299.2 5920.6 5938.6 5955.6 5992.4 3563.1 3638.6 3906.2 3909.2 3918.8 3242.7 3837.8 3882.6 3894.8 3915.8 3904.8 4057.1 4203.5 4559.7 4615.8 5097.5 5464.6 5710.3 5739.9 5796.4 1047.7 1240.3 1268.4 1273.8 1277.5 752.8 756.5 827.3 870.9 872.9 4283.6 4668.2 4678.6 5038.6 5046.8 5456.9 5538.5 5569.4 5576.9 5643.7 4337.0 4754.8 5017.8 5065.9 5088.6 2894.6 2907.7 2910.4 2913.2 2916.3 4861.6 5552.5 5711.7 5769.5 5779.3 4727.0 5228.9 5579.9 5589.9 5690.0 5161.2 5226.6 5255.4 5511.0 5742.5 5069.3 5248.4 5529.5 5541.8 5545.4 5146.2 5379.7 5389.4 5436.4 5453.6 4880.6 5166.7 5685.5 5753.9 5782.9 3466.3 3479.5 3543.3 3714.0 3725.9 4988.4 5469.7 5520.6 5926.9 5955.6 5499.7 5650.8 5693.0 5753.7 5828.8 4619.4 4799.3 5724.9 5774.7 5812.4 5374.2 5389.8 5642.3 5643.6 5696.5 4727.9 5386.9 5799.5 5807.4 5812.0 5669.3 5696.8 5700.7 5722.5 5783.8 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - PROCESS | 1638 | [#5] yui.html Cycles:180 Average:19505.16 Median:26608.06 stddev:28713.43 (107.9%) stddev-sans-first:28759.14 14:21:18 INFO - PROCESS | 1638 | Values: 667.6 688.8 769.2 770.6 833.5 14372.9 14997.2 15018.8 15034.9 15060.2 12649.1 12664.2 12737.9 12738.8 12742.2 782.3 815.6 840.6 841.3 844.7 679.8 814.7 823.6 825.6 831.0 804.6 855.1 867.7 885.2 890.9 833.7 856.5 871.2 872.7 876.5 619.2 650.9 660.3 663.7 665.0 12395.3 12429.8 12444.4 12480.8 12487.4 10182.9 10589.4 11600.2 11863.3 11994.8 10174.5 10216.8 10337.9 10525.2 10576.2 3493.8 3515.6 3548.1 3661.3 3665.4 87742.0 89006.1 91296.6 92207.1 93300.5 7498.3 8693.7 8783.1 8870.7 9186.0 18783.9 18784.3 18798.8 18866.0 18940.1 7433.4 7633.8 7719.5 7903.8 7904.8 4214.1 4236.7 4259.4 4341.3 4392.5 2745.3 2846.0 3399.9 3456.8 3484.6 581.7 609.3 620.3 643.7 660.1 513.8 518.4 534.8 575.9 582.7 7607.0 8878.5 8944.0 8981.6 9234.6 542.0 632.8 638.7 649.7 690.7 3663.9 4256.5 4399.0 4501.7 4578.0 72490.3 94579.1 99328.5 110927.0 113143.9 82966.0 84906.0 91589.6 99015.0 100576.5 81187.0 83349.0 84765.7 85327.6 85613.6 8338.8 8673.8 8676.1 8699.7 8707.3 41071.6 47554.8 52697.7 56513.0 56681.7 59954.4 63495.7 66483.3 68262.0 75549.0 12081.3 12191.6 12332.8 12363.9 12391.1 10668.9 11846.9 12146.7 13637.2 13878.7 10865.3 11061.3 11631.2 13180.5 13311.3 11979.5 12703.6 12769.0 13041.8 13233.0 12559.2 12585.2 12586.8 12745.7 13396.9 10517.4 10779.6 10806.3 10811.4 11092.8 9558.4 10268.8 11181.3 11439.4 11490.7 14:21:18 INFO - PROCESS | 1638 | -------- Summary: end -------- 14:21:18 INFO - PROCESS | 1638 | 14:21:18 INFO - TEST-INFO | 1638: exit 0 14:21:18 INFO - TEST-OK | dromaeo_css | took 1116797ms 14:21:18 INFO - TEST-START | kraken 14:21:18 INFO - operating with platform_type : mac_ 14:21:18 INFO - Initialising browser for kraken test... 14:21:19 INFO - TEST-INFO | started process 1649 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp87logk/profile http://localhost:49248/getInfo.html) 14:21:21 INFO - PROCESS | 1649 | 2017-01-05 14:21:21.980 plugin-container[1650:10614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863b, name = 'com.apple.tsm.portname' 14:21:21 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:21:21 INFO - PROCESS | 1649 | 2017-01-05 14:21:21.983 plugin-container[1650:10614] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x451b, name = 'com.apple.CFPasteboardClient' 14:21:21 INFO - PROCESS | 1649 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:21:21 INFO - PROCESS | 1649 | 2017-01-05 14:21:21.983 plugin-container[1650:10614] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:21:22 INFO - PROCESS | 1649 | __metrics Screen width/height:1600/1200 14:21:22 INFO - PROCESS | 1649 | colorDepth:24 14:21:22 INFO - PROCESS | 1649 | Browser inner width/height: 1024/689 14:21:22 INFO - PROCESS | 1649 | __metrics 14:21:22 ERROR - PROCESS | 1649 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3175: TypeError: this.tabs is undefined 14:21:23 INFO - TEST-INFO | 1649: exit 0 14:21:23 INFO - Browser initialized. 14:21:23 INFO - Running cycle 1/1 for kraken test... 14:21:23 INFO - TEST-INFO | started process 1651 (/builds/slave/test/build/application/FirefoxDeveloperEdition.app/Contents/MacOS/firefox -foreground -profile /var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/tmp87logk/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:21:25 INFO - PROCESS | 1651 | 2017-01-05 14:21:25.970 plugin-container[1652:10735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 14:21:25 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:21:25 INFO - PROCESS | 1651 | 2017-01-05 14:21:25.970 plugin-container[1652:10735] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x431f, name = 'com.apple.CFPasteboardClient' 14:21:25 INFO - PROCESS | 1651 | See /usr/include/servers/bootstrap_defs.h for the error codes. 14:21:25 INFO - PROCESS | 1651 | 2017-01-05 14:21:25.970 plugin-container[1652:10735] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 14:21:35 INFO - PROCESS | 1651 | RSS: Main: 230010880 14:21:35 INFO - PROCESS | 1651 | 14:21:38 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection) 14:21:39 INFO - PROCESS | 1651 | RSS: Main: 241717248 14:21:39 INFO - PROCESS | 1651 | 14:21:42 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-dft) 14:21:42 INFO - PROCESS | 1651 | RSS: Main: 249352192 14:21:42 INFO - PROCESS | 1651 | 14:21:45 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-fft) 14:21:46 INFO - PROCESS | 1651 | RSS: Main: 253059072 14:21:46 INFO - PROCESS | 1651 | 14:21:48 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator) 14:21:49 INFO - PROCESS | 1651 | RSS: Main: 253587456 14:21:49 INFO - PROCESS | 1651 | 14:21:51 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:21:51 INFO - PROCESS | 1651 | RSS: Main: 254791680 14:21:51 INFO - PROCESS | 1651 | 14:21:54 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom) 14:21:55 INFO - PROCESS | 1651 | RSS: Main: 259330048 14:21:55 INFO - PROCESS | 1651 | 14:21:58 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate) 14:21:58 INFO - PROCESS | 1651 | RSS: Main: 259051520 14:21:58 INFO - PROCESS | 1651 | 14:22:01 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial) 14:22:02 INFO - PROCESS | 1651 | RSS: Main: 258588672 14:22:02 INFO - PROCESS | 1651 | 14:22:04 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:22:05 INFO - PROCESS | 1651 | RSS: Main: 257490944 14:22:05 INFO - PROCESS | 1651 | 14:22:06 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:22:07 INFO - PROCESS | 1651 | RSS: Main: 257634304 14:22:07 INFO - PROCESS | 1651 | 14:22:09 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:22:10 INFO - PROCESS | 1651 | RSS: Main: 257609728 14:22:10 INFO - PROCESS | 1651 | 14:22:13 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:22:14 INFO - PROCESS | 1651 | RSS: Main: 257843200 14:22:14 INFO - PROCESS | 1651 | 14:22:16 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:22:17 INFO - PROCESS | 1651 | RSS: Main: 257888256 14:22:17 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | Cycle 1(1): loaded http://localhost:49248/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49248/tests/kraken/driver.html?testName=ai-astar) 14:22:19 INFO - PROCESS | 1651 | RSS: Main: 259477504 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | __start_tp_report 14:22:19 INFO - PROCESS | 1651 | _x_x_mozilla_page_load 14:22:19 INFO - PROCESS | 1651 | _x_x_mozilla_page_load_details 14:22:19 INFO - PROCESS | 1651 | |i|pagename|runs| 14:22:19 INFO - PROCESS | 1651 | |0;ai-astar;116;119;117;119;126;162;117;118;117;151 14:22:19 INFO - PROCESS | 1651 | |1;audio-beat-detection;176;149;147;138;145;150;142;115;141;117 14:22:19 INFO - PROCESS | 1651 | |2;audio-dft;194;202;196;195;200;219;271;194;194;196 14:22:19 INFO - PROCESS | 1651 | |3;audio-fft;83;66;86;111;67;61;60;61;98;82 14:22:19 INFO - PROCESS | 1651 | |4;audio-oscillator;86;71;91;72;73;104;105;70;69;88 14:22:19 INFO - PROCESS | 1651 | |5;imaging-gaussian-blur;96;116;89;99;121;97;94;86;89;87 14:22:19 INFO - PROCESS | 1651 | |6;imaging-darkroom;97;157;122;96;95;96;96;95;95;96 14:22:19 INFO - PROCESS | 1651 | |7;imaging-desaturate;80;139;127;117;90;92;89;86;86;138 14:22:19 INFO - PROCESS | 1651 | |8;json-parse-financial;58;57;53;61;66;89;96;94;95;91 14:22:19 INFO - PROCESS | 1651 | |9;json-stringify-tinderbox;66;70;73;77;77;79;88;61;90;108 14:22:19 INFO - PROCESS | 1651 | |10;stanford-crypto-aes;88;86;85;86;82;74;101;77;98;104 14:22:19 INFO - PROCESS | 1651 | |11;stanford-crypto-ccm;157;166;157;138;151;144;147;175;201;168 14:22:19 INFO - PROCESS | 1651 | |12;stanford-crypto-pbkdf2;166;167;166;163;167;166;163;167;162;159 14:22:19 INFO - PROCESS | 1651 | |13;stanford-crypto-sha256-iterative;69;66;67;79;93;92;65;67;68;68 14:22:19 INFO - PROCESS | 1651 | __end_tp_report 14:22:19 INFO - PROCESS | 1651 | __start_cc_report 14:22:19 INFO - PROCESS | 1651 | _x_x_mozilla_cycle_collect,970 14:22:19 INFO - PROCESS | 1651 | __end_cc_report 14:22:19 INFO - PROCESS | 1651 | __startTimestamp1483654939632__endTimestamp 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | ------- Summary: start ------- 14:22:19 INFO - PROCESS | 1651 | Number of tests: 14 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#0] ai-astar Cycles:10 Average:126.20 Median:119.00 stddev:16.42 (13.8%) stddev-sans-first:16.99 14:22:19 INFO - PROCESS | 1651 | Values: 116.0 119.0 117.0 119.0 126.0 162.0 117.0 118.0 117.0 151.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#1] audio-beat-detection Cycles:10 Average:142.00 Median:146.00 stddev:17.24 (11.8%) stddev-sans-first:13.18 14:22:19 INFO - PROCESS | 1651 | Values: 176.0 149.0 147.0 138.0 145.0 150.0 142.0 115.0 141.0 117.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#2] audio-dft Cycles:10 Average:206.10 Median:198.00 stddev:24.03 (12.1%) stddev-sans-first:25.09 14:22:19 INFO - PROCESS | 1651 | Values: 194.0 202.0 196.0 195.0 200.0 219.0 271.0 194.0 194.0 196.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#3] audio-fft Cycles:10 Average:77.50 Median:74.50 stddev:17.51 (23.5%) stddev-sans-first:18.46 14:22:19 INFO - PROCESS | 1651 | Values: 83.0 66.0 86.0 111.0 67.0 61.0 60.0 61.0 98.0 82.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#4] audio-oscillator Cycles:10 Average:82.90 Median:72.50 stddev:13.96 (19.2%) stddev-sans-first:14.76 14:22:19 INFO - PROCESS | 1651 | Values: 86.0 71.0 91.0 72.0 73.0 104.0 105.0 70.0 69.0 88.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#5] imaging-gaussian-blur Cycles:10 Average:97.40 Median:91.50 stddev:12.01 (13.1%) stddev-sans-first:12.73 14:22:19 INFO - PROCESS | 1651 | Values: 96.0 116.0 89.0 99.0 121.0 97.0 94.0 86.0 89.0 87.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#6] imaging-darkroom Cycles:10 Average:104.50 Median:96.00 stddev:20.22 (21.1%) stddev-sans-first:21.26 14:22:19 INFO - PROCESS | 1651 | Values: 97.0 157.0 122.0 96.0 95.0 96.0 96.0 95.0 95.0 96.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#7] imaging-desaturate Cycles:10 Average:104.40 Median:86.00 stddev:23.25 (27.0%) stddev-sans-first:22.93 14:22:19 INFO - PROCESS | 1651 | Values: 80.0 139.0 127.0 117.0 90.0 92.0 89.0 86.0 86.0 138.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#8] json-parse-financial Cycles:10 Average:76.00 Median:90.00 stddev:18.31 (20.3%) stddev-sans-first:18.23 14:22:19 INFO - PROCESS | 1651 | Values: 58.0 57.0 53.0 61.0 66.0 89.0 96.0 94.0 95.0 91.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#9] json-stringify-tinderbox Cycles:10 Average:78.90 Median:77.00 stddev:13.58 (17.6%) stddev-sans-first:13.58 14:22:19 INFO - PROCESS | 1651 | Values: 66.0 70.0 73.0 77.0 77.0 79.0 88.0 61.0 90.0 108.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#10] stanford-crypto-aes Cycles:10 Average:88.10 Median:85.50 stddev:9.97 (11.7%) stddev-sans-first:10.58 14:22:19 INFO - PROCESS | 1651 | Values: 88.0 86.0 85.0 86.0 82.0 74.0 101.0 77.0 98.0 104.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#11] stanford-crypto-ccm Cycles:10 Average:160.40 Median:161.50 stddev:18.30 (11.3%) stddev-sans-first:19.36 14:22:19 INFO - PROCESS | 1651 | Values: 157.0 166.0 157.0 138.0 151.0 144.0 147.0 175.0 201.0 168.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:164.60 Median:166.00 stddev:2.72 (1.6%) stddev-sans-first:2.83 14:22:19 INFO - PROCESS | 1651 | Values: 166.0 167.0 166.0 163.0 167.0 166.0 163.0 167.0 162.0 159.0 14:22:19 INFO - PROCESS | 1651 | 14:22:19 INFO - PROCESS | 1651 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.40 Median:68.50 stddev:10.78 (15.7%) stddev-sans-first:11.32 14:22:19 INFO - PROCESS | 1651 | Values: 69.0 66.0 67.0 79.0 93.0 92.0 65.0 67.0 68.0 68.0 14:22:19 INFO - PROCESS | 1651 | -------- Summary: end -------- 14:22:19 INFO - PROCESS | 1651 | 14:22:20 INFO - TEST-INFO | 1651: exit 0 14:22:20 INFO - TEST-OK | kraken | took 61694ms 14:22:20 INFO - SUITE-END | took 1178s 14:22:20 INFO - Completed test suite (00:19:38) 14:22:20 INFO - javascript benchmark 14:22:20 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7058.155022814419, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2989.4320079184954, 3409.3181363727244, 3418.2479854432017, 3457.39495588272, 3463.2554000889804, 5819.9709001454985, 5879.647221166733, 5896.88206235875, 5902.409759024095, 5902.996490596609, 5906.88186236275, 5951.88096238075, 5986.982213023785, 6007.6996150192535, 6026.849328766783, 5256.369235691713, 5305.602079844008, 5376.51611354978, 5397.46025397461, 5443.510084092431, 5401.621886467949, 5410.269613602172, 5413.0, 5420.024395608789, 5425.430329815372, 5254.527092561669, 5267.631265811395, 5395.136778115502, 5408.134698448249, 5435.293411856473, 5290.100682883881, 5299.973500132494, 5320.893582128375, 5339.119045357511, 5353.223782551547, 5260.316158899319, 5264.2893209416625, 5293.682379057231, 5366.329208848894, 5402.945970540323, 5687.374388817227, 5988.371221021817, 6009.489193418564, 6023.668698221596, 6036.698165091771, 6190.0, 6191.380861913818, 6209.441150296495, 6212.565120441571, 6230.96884515579, 6261.092141639482, 6263.937360626426, 6346.238451385817, 6347.301796802348, 6414.102025716404, 5095.490450954875, 5885.911711324334, 6058.909116363214, 6064.120702498113, 6082.635041897412, 6230.7819226326865, 6271.749130034838, 6302.306746257908, 6309.242890853127, 6410.839729006719, 6247.094171345129, 6252.781152659635, 6264.404881536247, 6265.686715664198, 6272.6236425814595, 6182.350853160354, 6271.874562508724, 6285.622862628165, 6292.370762923671, 6306.747730090745, 6172.351903050115, 6197.876042479125, 6208.2239720034995, 6212.378762123752, 6254.21822272216, 6144.846378840565, 6208.844778880474, 6215.12988181655, 6215.223097112861, 6245.156903817927, 5011.373578302712, 5063.417706963673, 5063.974680126649, 5073.086844367976, 5078.263651770472, 4406.361077643724, 4427.0, 4467.218236808546, 4487.663425243055, 4513.255312873404, 5337.492938170868, 5373.408925018245, 5809.593328466966, 6042.516598672096, 6106.053561697944, 5892.558058145656, 6198.194234749454, 6258.561900666909, 6274.466670332981, 6283.37166283368, 5994.6403215807195, 6006.819795406145, 6073.514118870568, 6086.330503644595, 6098.878022439526, 5523.861903452445, 5559.054960656698, 5570.415106413769, 5583.0, 5640.5487560995025, 6415.230172379344, 6497.74009039652, 6498.5126115541525, 6500.187476565429, 6539.182602174633, 6163.506919446523, 6214.9689251553455, 6253.124562561154, 6290.370962903673, 6310.116583678197, 6327.746890124344, 6474.546781725234, 6475.190601174853, 6479.222493300834, 6480.44916182111, 6265.091561723433, 6267.655278959701, 6278.529110316653, 6286.151369565233, 6293.685315734104, 6134.78528251509, 6159.599626024294, 6172.19761431029, 6249.031400132987, 6274.623522588659, 6177.382261773966, 6197.845053873689, 6212.751489940351, 6235.906461402992, 6246.156768836149, 6159.106929495288, 6205.6586887721605, 6241.750329986749, 6242.531810114168, 6266.248050234001, 5125.79496820123, 5351.1705685617235, 5603.131514615241, 6066.4540191382985, 6086.756529738938, 6131.9272270139245, 6159.476444502267, 6161.291451482957, 6189.566730328834, 6267.9373206267355, 6039.728212230548, 6115.388461153849, 6139.938600613937, 6163.722632481638, 6178.938210617836], "value": 5795.933807036797, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [485.3617492996709, 489.3711580618905, 500.6020213929925, 521.2494008627576, 530.204692960559, 38109.61890381095, 40337.19325613493, 40385.0, 40414.0, 40491.79754101229, 41326.0, 41730.791346043305, 41860.372094418606, 41861.162776744444, 41955.58044419555, 9358.62565497381, 9564.426134431922, 9568.282378821581, 9586.90413095867, 9591.952040239788, 9478.431294122374, 9501.80996380072, 9567.66513172039, 9568.33021688482, 9591.424514529115, 9515.429074255531, 9589.993050729678, 9590.136887680108, 9604.615815367377, 9611.086946740048, 7900.960495197516, 8684.175003374668, 9565.330426870121, 9566.426014439156, 9574.138327550554, 9468.90531094694, 9522.904770952271, 9549.188318992894, 9580.281478889041, 9603.855942160873, 39892.40161397582, 40083.198336033405, 40224.0, 40234.792956211364, 40437.0, 3345.414552453311, 3441.328940856534, 3481.547398838135, 3531.2584357285, 3673.797941113238, 28823.135305940854, 29327.0, 29563.26091847721, 29884.0, 30292.84853575718, 3199.744020478356, 3203.2792621660174, 3208.197950512372, 3234.272288735039, 3291.4240007998505, 509525.0, 512230.0, 515118.0, 515316.4234178843, 517670.0, 49313.0, 60078.69960650169, 60365.69817150974, 60460.79078418407, 60655.393446064976, 59295.40704592899, 61539.0, 62826.0, 63234.36765632377, 63267.0, 16009.199540022952, 16116.35534578627, 16248.0, 16262.674746505003, 16264.268107935171, 12449.315287659265, 12526.0, 12604.936975315064, 12621.495140194473, 12637.684057898443, 15657.217139142997, 16485.83514164867, 16572.419965301156, 16595.419160329573, 16620.418285359956, 3178.8092714437207, 3275.5741753571883, 6821.317868213139, 6848.897266541006, 6867.07294515244, 817.5585184000691, 819.6803246733779, 1014.102519270451, 1022.2486472442748, 1023.9504507879483, 29789.553156702666, 29851.85074074616, 29959.700402996128, 30074.54888176679, 30202.09393718192, 1000.4847503535685, 1034.7775228325945, 1055.7466208109997, 1057.9047885690325, 1136.8561592993492, 12765.361731913366, 15124.319405626773, 15391.384344626313, 15542.057242844645, 15753.212339382984, 680755.0, 684851.0, 685327.0, 688648.0, 689687.0, 49450.76373090629, 51322.97354052898, 53818.46181538134, 57738.42261577414, 58417.707911461024, 1139.3562637110006, 1167.6231941420872, 1343.402186027228, 1377.4008306386754, 1379.6275005748591, 23270.76729232686, 28395.574066388606, 29428.85285573644, 29967.70032299649, 30052.398952021274, 49829.75085124551, 49989.25016124834, 50342.748286258335, 50383.49616503788, 50498.74750626223, 604855.0, 611747.0, 612345.1358404869, 612359.0, 614017.0, 23270.65094023557, 24652.38369040788, 26033.479330413666, 26383.944642214097, 26545.867270663523, 44646.55353446424, 45494.545054550355, 45970.31044534402, 46183.30725039195, 46424.767876160404, 30152.84923575368, 37653.81173094227, 38160.42759358556, 38507.0, 38535.22929541337, 37366.62633373631, 37854.62145378511, 38660.420093698056, 38778.80610597042, 38955.0, 37550.80818130489, 38491.6150838488, 38853.611463885, 39499.40750888796, 40103.59896400999, 28776.568351474325, 33985.3202935935, 34514.30971380508, 34589.13527161841, 34899.4765078519, 22771.747553884696, 23281.650775238046, 23362.299131026095, 23493.006188947576, 25838.870805645853], "value": 19471.55700627427, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [314.7576366198026, 316.5504982924249, 365.251234968315, 374.16747736286754, 392.89195471245404, 18467.907660461697, 19227.80772192276, 19285.903570482147, 19461.805381946175, 19517.0, 16937.407190748294, 18941.810581894177, 18983.145758440867, 20010.599788004267, 20101.0, 5019.422766381858, 5605.607607467468, 5879.11813228017, 5879.265091863517, 5898.7640494380385, 5458.5087342139195, 5750.0, 5775.797847075353, 5782.392848750884, 5796.913046304308, 5712.200291959129, 5721.256236689224, 5727.6563406195555, 5740.56945729072, 5744.597878148531, 5576.163575463673, 5903.763849446017, 5939.495142912836, 5993.310769261503, 5996.340402555716, 5569.972150139264, 5746.13807928814, 5752.309722833245, 5776.075827867521, 5787.987102257088, 12816.871831281662, 12902.03234757397, 13033.543825966046, 13095.541656042087, 13177.077604567685, 2197.2419515267206, 2269.3872654383226, 2328.080408238753, 2352.3766201956323, 2837.2764944939113, 8518.318534517224, 9557.378770379972, 9741.366811157252, 9801.31390802637, 10276.845847312296, 1781.3676144968608, 1836.8897866128075, 1843.1060935446299, 1867.7128254272577, 1979.0698371765247, 73087.63456182685, 74311.62844185799, 111331.0, 113866.43066784613, 125998.37000815029, 8787.472751634923, 8928.419652722552, 8968.955155224183, 9239.353245272767, 10171.186305095574, 10167.949160254151, 12304.692382690504, 12336.75326493483, 12360.938195308965, 12442.937785311016, 7880.0, 8142.389320800845, 8142.430029897969, 8148.714794982266, 8182.263596276363, 8325.625346859406, 8463.915360846313, 8521.786955326166, 8561.0582835888, 8652.97417959679, 3103.8292893890602, 3849.345611246095, 3898.805059747058, 3956.4460975463458, 3968.345223038224, 1929.4308179087202, 1934.8839069655867, 1941.9126139323764, 1995.7305763722002, 1998.3805020443708, 782.5265714242915, 836.4270474724833, 920.3235621817959, 925.6991477769752, 1022.7903279827766, 19554.413367598994, 21033.474163146042, 21072.840993745493, 21198.576028479343, 21345.46636334073, 925.6112432778249, 926.9062506242699, 938.333783014054, 951.7810903492235, 992.2409356842022, 10592.788144237073, 11141.442927853575, 11151.498182581803, 11164.888351116548, 11184.832227516594, 561246.0, 563306.0, 566844.0, 569745.0, 577232.0, 99815.0, 123276.0, 126964.0, 127366.0, 130698.0, 778.6768491076253, 946.6355453150586, 957.8132264208413, 959.491469521155, 963.4749061761216, 18368.448946531626, 18878.622427551647, 19011.0, 19066.904665477137, 19185.0, 52414.68963275949, 52658.68353291045, 52739.472605273455, 53812.578433969786, 53838.46161538334, 52498.0, 74680.50638987082, 77689.61155194188, 78453.60773196098, 79527.60236198783, 7843.176466471166, 7847.332976697045, 7878.369730421668, 7924.6037698116015, 7989.600519974094, 10147.949260253652, 11937.641870743892, 12075.51697932108, 12139.210951288138, 12182.02543796512, 10168.796624067329, 10248.128909042814, 10411.323263987817, 10588.84116738265, 10611.787764244518, 9094.909050909406, 10054.547545360623, 10328.948355258175, 10373.740656483647, 10659.147268218368, 10329.74175645615, 10456.529456174643, 10615.469226538797, 10631.255812093079, 10939.28894621847, 5465.289512363367, 5787.0, 6810.625415601991, 7240.275972402717, 7599.822027585733, 6476.287608362982, 10601.893981060091, 10724.624638137628, 10765.3540787553, 10967.903209678969], "value": 8901.409657734388, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1405.7610206264933, 1451.0785651111541, 1620.3842539834861, 1760.3486709917333, 1795.3357257814603, 6172.722227499762, 7069.681864316105, 7805.843883122334, 8021.719239826607, 8528.232459078681, 6552.868942621157, 8189.426740128193, 8223.62993665285, 8314.91685083149, 8327.333813294937, 1926.2969016309087, 2313.248194336839, 2335.532893421314, 2338.6608941703444, 2353.62342025276, 2320.071971211512, 2350.165691179632, 2364.361622361961, 2368.5973384524673, 2368.7039120109985, 2315.571619250444, 2321.512482378694, 2343.5547246023284, 2345.57779599672, 2352.529494101178, 2295.540891821626, 2301.0450662974845, 2313.838031337807, 2324.8140148788057, 2326.638916234005, 2322.5354929014097, 2342.7774361435636, 2354.587947109266, 2357.846739961897, 2368.1237941961413, 8246.299064579518, 8383.664653413856, 8437.325013998927, 8460.2385785279, 8482.575871206414, 4290.485141783006, 4960.578350840183, 5090.032893750175, 5131.281620573122, 5146.562542183881, 2958.9408211835644, 3259.6577359377397, 3423.7089847363004, 3526.612072672004, 3570.0182449826248, 1050.4642632257528, 1057.7778666479971, 1095.1348434736549, 1101.3336931156614, 1103.1230172013268, 10890.237683362167, 10902.836457453144, 10922.344659320466, 10953.671389858317, 11016.724581885437, 4502.099580083996, 4584.839530616412, 4664.673472856935, 4688.859334219979, 4735.881602959885, 7932.444728868923, 9344.90655093454, 9403.90596094044, 9417.0, 9422.717318480456, 5126.154184559506, 5147.742612869341, 5183.637145399785, 5193.584513238932, 5210.53105220532, 4055.5538890721996, 4690.273007683814, 4701.177293973541, 4744.312074749142, 4769.952300476951, 3305.3389322135667, 3795.867144649924, 3800.847966081326, 3862.1696335288048, 3890.2219556088894, 1085.9619913302997, 1262.6464589914838, 1270.3521204185931, 1273.7579859826603, 1279.4754150798128, 877.2280393254023, 877.4998250996871, 894.6063731957918, 901.427593478133, 907.3784457646533, 5533.889322213533, 5715.657060576295, 5900.970495147497, 5907.586468947218, 5968.074948383008, 765.9310662040443, 836.2599099796723, 843.8396704626101, 892.5849479991705, 935.704049890906, 3897.162110146332, 3909.276783795007, 3918.314294998421, 3922.0391004204153, 3923.8234279457492, 9666.661666841626, 9992.950035249778, 10161.644342447978, 10170.745731356626, 10186.083252507162, 9158.358914875895, 9518.333716639769, 9833.950830245803, 10085.041921017491, 10106.343087699275, 6659.700313485905, 6818.624975626289, 7165.6417179140835, 7834.0, 7865.331446826969, 4723.669343145947, 5429.864253393696, 5448.673079615236, 5817.389174136824, 5882.5293976482635, 7631.694732210872, 9022.368434209542, 9133.725988220363, 9233.261339092722, 9297.0, 7307.5980821055355, 9316.580753865957, 9370.10983956523, 9392.013838546854, 9571.947085820693, 6787.762428314985, 7645.1972542882195, 7712.5758083305955, 7725.845483090195, 7870.134285228506, 7856.214378562327, 7899.802504937423, 7971.760847174594, 7972.402069844668, 7973.880391794011, 7001.929980700128, 7140.964295178491, 7182.102237220348, 7192.992980982562, 7202.459815513962, 5521.503064724194, 6943.201531823905, 7000.404965577983, 7115.075040244735, 7129.679164437509, 6125.142480052886, 6619.933800662125, 6642.468602511691, 6689.26418094019, 6707.396334329934, 4795.640326975421, 4807.6394270429155, 4831.396075490564, 4861.270809378621, 4951.628627852853, 4144.191882582867, 4777.402824646919, 4801.95198048029, 4889.071076495455, 5067.594592432522], "value": 4369.5432049437, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.71705728386183, 303.81467304943993, 304.19236925961553, 306.7316098413888, 315.4243505602275, 4762.833300834472, 5272.6836389816635, 5447.346318441792, 5471.701885208212, 5588.888222235553, 4464.5758652928, 4959.23131914553, 5199.324087868572, 5284.841454756353, 5422.864428389292, 4144.564820693821, 4149.336106223005, 4161.0845613964875, 4165.312723400635, 4182.414461975326, 3800.6959443244473, 4072.3891416287497, 4164.812583433738, 4170.812313445902, 4186.37204419338, 3231.6588615724445, 3326.6839650233187, 4009.7594144351287, 4011.1376054148354, 4045.59544045596, 4006.934694958359, 4029.657479114279, 4036.4752582164424, 4050.9670034141104, 4090.7750073745933, 3521.6830485256196, 3523.0, 3527.9823600882087, 3542.8405721742583, 3544.007677850206, 3999.580044095357, 4959.677620954626, 5265.341832270966, 5328.200769884548, 5344.91982620261, 3892.922141557153, 4654.301854721785, 4658.813647454098, 4662.976685116552, 4666.4633567139535, 5056.84829455117, 5559.527440167591, 5694.715264236772, 5754.625949313281, 5758.827235182951, 3617.981910090459, 3860.864869729546, 3867.864624738149, 3873.845046198149, 3896.5129358830145, 5299.198272587378, 5920.644761314335, 5938.6436813791315, 5955.642661440284, 5992.412010817137, 3563.0937448464947, 3638.5633723952765, 3906.2382835346834, 3909.237698648793, 3918.8236529356245, 3242.691944265291, 3837.808109594509, 3882.611738826095, 3894.786800889278, 3915.784631845219, 3904.763331784988, 4057.14799892026, 4203.495580530356, 4559.726416415026, 4615.838445654386, 5097.541221290102, 5464.562834973272, 5710.3433105193435, 5739.885202295931, 5796.362400135981, 1047.664747280863, 1240.3240234072452, 1268.4398190226073, 1273.840769903762, 1277.5145444730942, 752.8255920763627, 756.5233902641301, 827.2761333832897, 870.9085546017703, 872.9301655867515, 4283.6144746972295, 4668.206404911173, 4678.60231880294, 5038.5969122470115, 5046.798128074909, 5456.854060647309, 5538.529225015919, 5569.359523654751, 5576.860578485489, 5643.746031428595, 4336.9783151084675, 4754.833580824724, 5017.774200161001, 5065.936153407825, 5088.600634825379, 2894.6250530998027, 2907.7496676428914, 2910.4179164167253, 2913.21343237329, 2916.2855100500515, 4861.611071114373, 5552.5280351169795, 5711.742971566289, 5769.480746732815, 5779.33537643168, 4726.952730472652, 5228.947710522846, 5579.856129493444, 5589.916151257653, 5689.971550142222, 5161.174212126042, 5226.63413561047, 5255.421903590525, 5510.972445137749, 5742.54059675225, 5069.308512763265, 5248.448912864094, 5529.502344788988, 5541.806036788693, 5545.417731138173, 5146.150885103916, 5379.704116273642, 5389.434109418455, 5436.429174936733, 5453.618246722692, 4880.633952453652, 5166.689998600096, 5685.545156387397, 5753.8561535961935, 5782.855428614318, 3466.3067386522293, 3479.5476588043393, 3543.2559162575117, 3714.0157858167067, 3725.9254814904093, 4988.4263309719845, 5469.72651367438, 5520.641158324696, 5926.880817831738, 5955.553333499918, 5499.725013749377, 5650.802221922213, 5693.0, 5753.654780713171, 5828.766849326149, 4619.445666520039, 4799.28010798383, 5724.883647688661, 5774.653520788766, 5812.389699081705, 5374.247605335335, 5389.784408623611, 5642.294713160855, 5643.573263496736, 5696.487316141402, 4727.912580106664, 5386.946130538645, 5799.536037117105, 5807.390224026416, 5812.0, 5669.31968163823, 5696.784379559179, 5700.686462244616, 5722.512146841935, 5783.768649253982], "value": 4041.864043454539, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [667.5927684112692, 688.7761477519804, 769.1846642558892, 770.5838847022608, 833.5415521463194, 14372.928135359321, 14997.17515536645, 15018.849811501881, 15034.94755367122, 15060.24698765063, 12649.114561980641, 12664.240145591271, 12737.872621273784, 12738.80891786624, 12742.17175883567, 782.3076577229136, 815.6370415165254, 840.6343240690319, 841.3353450773882, 844.7423535821572, 679.8164495586188, 814.7474282972267, 823.6005537314397, 825.63671984327, 830.995845020774, 804.569555287921, 855.0978717453085, 867.7093173786789, 885.1635204731531, 890.9153630405101, 833.6915341323719, 856.5075081827974, 871.1854416120947, 872.7032808844991, 876.4916348517876, 619.2074145094286, 650.9479241660656, 660.2934859700123, 663.7013343995221, 665.0024962556166, 12395.256284622952, 12429.751404971941, 12444.440000200013, 12480.81278780819, 12487.438065287084, 10182.9490852546, 10589.417582032986, 11600.187986840925, 11863.28820270778, 11994.760104797855, 10174.51513934944, 10216.846747298796, 10337.89662103377, 10525.210609204263, 10576.206784491194, 3493.755437119403, 3515.6308587598446, 3548.077499850032, 3661.2677464506937, 3665.358562251622, 87742.0, 89006.10993890042, 91296.63055054245, 92207.07792922053, 93300.53349733276, 7498.250174982458, 8693.695720649746, 8783.121687831166, 8870.73387798367, 9185.954070229605, 18783.90608046951, 18784.342548010754, 18798.812011879978, 18866.0, 18940.147693353556, 7433.442491813135, 7633.809154771176, 7719.498232614862, 7903.762887113281, 7904.762857114296, 4214.072903961123, 4236.661067114624, 4259.361095835649, 4341.283688191413, 4392.450943632046, 2745.32739478825, 2845.9857700711777, 3399.8810041648426, 3456.827158642058, 3484.6341134180548, 581.6539159200269, 609.3053918532876, 620.3238470067648, 643.6556442303345, 660.1088530483809, 513.7662363624542, 518.3753576939779, 534.8367301120048, 575.9280089988752, 582.6766144789682, 7607.0, 8878.467291962503, 8944.0, 8981.595828187617, 9234.584443700049, 541.9647722898, 632.7880160146402, 638.6551262318296, 649.7238673563717, 690.6822861483673, 3663.8717644882836, 4256.45804008647, 4398.978005109954, 4501.707389019703, 4578.0, 72490.27509724941, 94579.10841783263, 99328.50335748275, 110927.0, 113143.86856131499, 82966.0, 84906.0, 91589.62615560772, 99015.0, 100576.4971175154, 81187.0, 83349.0, 84765.72851407234, 85327.5733621328, 85613.57193213994, 8338.833223335621, 8673.783155421164, 8676.08901065379, 8699.695510657097, 8707.3020760951, 41071.589284106776, 47554.76222618865, 52697.73651131873, 56513.02204422825, 56681.71659141678, 59954.40045599488, 63495.6825215871, 66483.33516664771, 68262.0, 75549.0, 12081.335526545772, 12191.634250972485, 12332.753344932871, 12363.876361236273, 12391.132620716824, 10668.89331106679, 11846.940765296464, 12146.724593917865, 13637.181769093886, 13878.722425551634, 10865.293755906157, 11061.336319820837, 11631.243969141979, 13180.538681146114, 13311.33443327849, 11979.460924258256, 12703.618891433272, 12769.0, 13041.804372934603, 13233.007524435514, 12559.246445213317, 12585.24488530691, 12586.811197832223, 12745.681357966125, 13396.93301533486, 10517.368957862553, 10779.622713205, 10806.297590656583, 10811.405372704578, 11092.833607495733, 9558.378705384128, 10268.794624107326, 11181.273217240854, 11439.370834603844, 11490.712732181762], "value": 6967.877990281639, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1582.3999999999999, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [116.0, 119.0, 117.0, 119.0, 126.0, 162.0, 117.0, 118.0, 117.0, 151.0], "value": 126.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 149.0, 147.0, 138.0, 145.0, 150.0, 142.0, 115.0, 141.0, 117.0], "value": 142.0, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 202.0, 196.0, 195.0, 200.0, 219.0, 271.0, 194.0, 194.0, 196.0], "value": 206.1, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 66.0, 86.0, 111.0, 67.0, 61.0, 60.0, 61.0, 98.0, 82.0], "value": 77.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [86.0, 71.0, 91.0, 72.0, 73.0, 104.0, 105.0, 70.0, 69.0, 88.0], "value": 82.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 116.0, 89.0, 99.0, 121.0, 97.0, 94.0, 86.0, 89.0, 87.0], "value": 97.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 157.0, 122.0, 96.0, 95.0, 96.0, 96.0, 95.0, 95.0, 96.0], "value": 104.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [80.0, 139.0, 127.0, 117.0, 90.0, 92.0, 89.0, 86.0, 86.0, 138.0], "value": 104.4, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 53.0, 61.0, 66.0, 89.0, 96.0, 94.0, 95.0, 91.0], "value": 76.0, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 70.0, 73.0, 77.0, 77.0, 79.0, 88.0, 61.0, 90.0, 108.0], "value": 78.9, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [88.0, 86.0, 85.0, 86.0, 82.0, 74.0, 101.0, 77.0, 98.0, 104.0], "value": 88.1, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [157.0, 166.0, 157.0, 138.0, 151.0, 144.0, 147.0, 175.0, 201.0, 168.0], "value": 160.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [166.0, 167.0, 166.0, 163.0, 167.0, 166.0, 163.0, 167.0, 162.0, 159.0], "value": 164.6, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 66.0, 67.0, 79.0, 93.0, 92.0, 65.0, 67.0, 68.0, 68.0], "value": 73.4, "unit": "score"}]}]} 14:22:20 INFO - Return code: 0 14:22:20 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:22:20 ERROR - # TBPL SUCCESS # 14:22:20 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 14:22:20 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 14:22:20 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 14:22:20 INFO - 2017-01-05 14:22:20.979 screenresolution[1656:11124] starting screenresolution argv=screenresolution get 14:22:20 INFO - 2017-01-05 14:22:20.982 screenresolution[1656:11124] Display 0: 1600x1200x32@60 14:22:20 INFO - 2017-01-05 14:22:20.988 screenresolution[1657:11127] starting screenresolution argv=screenresolution list 14:22:20 INFO - Available Modes on Display 0 14:22:20 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 14:22:20 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 14:22:20 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 14:22:20 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 14:22:21 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 14:22:21 INFO - Intel Iris: 14:22:21 INFO - Chipset Model: Intel Iris 14:22:21 INFO - Type: GPU 14:22:21 INFO - Bus: Built-In 14:22:21 INFO - VRAM (Dynamic, Max): 1536 MB 14:22:21 INFO - Vendor: Intel (0x8086) 14:22:21 INFO - Device ID: 0x0a2e 14:22:21 INFO - Revision ID: 0x0009 14:22:21 INFO - Displays: 14:22:21 INFO - Display: 14:22:21 INFO - Resolution: 1600 x 1200 @ 60 Hz 14:22:21 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 14:22:21 INFO - Display Serial Number: 1600x1200 60 14:22:21 INFO - Main Display: Yes 14:22:21 INFO - Mirror: Off 14:22:21 INFO - Online: Yes 14:22:21 INFO - Rotation: Supported 14:22:21 INFO - Return code: 0 14:22:21 INFO - Running post-action listener: _resource_record_post_action 14:22:21 INFO - [mozharness: 2017-01-05 22:22:21.236678Z] Finished run-tests step (success) 14:22:21 INFO - Running post-run listener: _resource_record_post_run 14:22:21 INFO - Total resource usage - Wall time: 1210s; CPU: 55.0%; Read bytes: 39750144; Write bytes: 660074496; Read time: 384; Write time: 1895 14:22:21 INFO - TinderboxPrint: CPU usage
54.6% 14:22:21 INFO - TinderboxPrint: I/O read bytes / time
39,750,144 / 384 14:22:21 INFO - TinderboxPrint: I/O write bytes / time
660,074,496 / 1,895 14:22:21 INFO - TinderboxPrint: CPU idle
2,196.6 (45.4%) 14:22:21 INFO - TinderboxPrint: CPU system
986.9 (20.4%) 14:22:21 INFO - TinderboxPrint: CPU user
1,658.2 (34.2%) 14:22:21 INFO - TinderboxPrint: Swap in / out
389,054,464 / 0 14:22:21 INFO - install - Wall time: 31s; CPU: 54.0%; Read bytes: 348365312; Write bytes: 346142720; Read time: 26876; Write time: 662 14:22:21 INFO - run-tests - Wall time: 1180s; CPU: 55.0%; Read bytes: 35625472; Write bytes: 306649088; Read time: 354; Write time: 1204 14:22:21 INFO - Running post-run listener: _upload_blobber_files 14:22:21 INFO - Blob upload gear active. 14:22:21 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:22:21 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:22:21 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Aurora', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:22:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Aurora -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:22:22 INFO - (blobuploader) - INFO - Open directory for files ... 14:22:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 14:22:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 14:22:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:23 INFO - (blobuploader) - INFO - Done attempting. 14:22:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 14:22:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 14:22:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:24 INFO - (blobuploader) - INFO - Done attempting. 14:22:24 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:22:24 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:22:24 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:22:26 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:22:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:22:26 INFO - (blobuploader) - INFO - Done attempting. 14:22:26 INFO - (blobuploader) - INFO - Iteration through files over. 14:22:26 INFO - Return code: 0 14:22:26 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:22:26 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:22:26 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b252b5675ff9d6c17d3eda7884e1b3783b6dde44611e235a298cdba2d335db3cda429ddf624e51ac0701d514edccdadc0590677e025cf23b493e4c6c5e08841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e73f0b0f49f07b5ce78e6a2dd3ed9e17eabdd16c9770299487d8cbd60b6ee4e3c5ffe6dc858a403ccc32963cafbce60dc15fb5379486d329467fb4098ab43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d265118ed950a7594144b23cf5185c8454a38773eaf6b19f580d81a376a0b259337da58a5266bfa071522cefff43e242703f678146994c29376ae8350bd4e7d"} 14:22:26 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:22:26 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:22:26 INFO - Contents: 14:22:26 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b252b5675ff9d6c17d3eda7884e1b3783b6dde44611e235a298cdba2d335db3cda429ddf624e51ac0701d514edccdadc0590677e025cf23b493e4c6c5e08841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e73f0b0f49f07b5ce78e6a2dd3ed9e17eabdd16c9770299487d8cbd60b6ee4e3c5ffe6dc858a403ccc32963cafbce60dc15fb5379486d329467fb4098ab43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d265118ed950a7594144b23cf5185c8454a38773eaf6b19f580d81a376a0b259337da58a5266bfa071522cefff43e242703f678146994c29376ae8350bd4e7d"} 14:22:26 INFO - Running post-run listener: copy_logs_to_upload_dir 14:22:26 INFO - Copying logs to upload dir... 14:22:26 INFO - mkdir: /builds/slave/test/build/upload/logs 14:22:26 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.297625 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 12 secs) (at 2017-01-05 14:22:26.603972) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:26.607615) ========= 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.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b252b5675ff9d6c17d3eda7884e1b3783b6dde44611e235a298cdba2d335db3cda429ddf624e51ac0701d514edccdadc0590677e025cf23b493e4c6c5e08841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e73f0b0f49f07b5ce78e6a2dd3ed9e17eabdd16c9770299487d8cbd60b6ee4e3c5ffe6dc858a403ccc32963cafbce60dc15fb5379486d329467fb4098ab43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d265118ed950a7594144b23cf5185c8454a38773eaf6b19f580d81a376a0b259337da58a5266bfa071522cefff43e242703f678146994c29376ae8350bd4e7d"} build_url:https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011134 build_url: 'https://queue.taskcluster.net/v1/task/GtYnqIEAQ0q8efmqISKkRA/artifacts/public/build/firefox-52.0a2.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/0b252b5675ff9d6c17d3eda7884e1b3783b6dde44611e235a298cdba2d335db3cda429ddf624e51ac0701d514edccdadc0590677e025cf23b493e4c6c5e08841", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/02e73f0b0f49f07b5ce78e6a2dd3ed9e17eabdd16c9770299487d8cbd60b6ee4e3c5ffe6dc858a403ccc32963cafbce60dc15fb5379486d329467fb4098ab43f", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Aurora/sha512/3d265118ed950a7594144b23cf5185c8454a38773eaf6b19f580d81a376a0b259337da58a5266bfa071522cefff43e242703f678146994c29376ae8350bd4e7d"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:26.641968) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:26.642295) ========= 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.03eqGfd3Gt/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.XkeoO2THfR/Listeners TMPDIR=/var/folders/99/84w0k_014gv77_6qrv0b4tn000000w/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.005025 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-05 14:22:26.690929) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 14:22:26.691258) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-05 14:22:26.691573) ========= ========= Total master_lag: 0.19 =========