builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0087 starttime: 1476985041.49 results: success (0) buildid: 20161020074850 builduid: 53e31627a5e14ebaad912fd7148a032d revision: 3f0aeafe59c40c5e92ba9636fa718cf26088e127 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.485521) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.486057) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.486488) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.511769) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.512197) ========= 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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2016-10-20 10:37:21-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 26.3M=0s 2016-10-20 10:37:21 (26.3 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.125806 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.661592) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.662090) ========= 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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.025490 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:21.711792) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:37:21.712438) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 3f0aeafe59c40c5e92ba9636fa718cf26088e127 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev 3f0aeafe59c40c5e92ba9636fa718cf26088e127 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2016-10-20 10:37:21,782 truncating revision to first 12 chars 2016-10-20 10:37:21,782 Setting DEBUG logging. 2016-10-20 10:37:21,782 attempt 1/10 2016-10-20 10:37:21,782 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/3f0aeafe59c4?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-20 10:37:22,859 unpacking tar archive at: mozilla-central-3f0aeafe59c4/testing/mozharness/ program finished with exit code 0 elapsedTime=1.331469 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-10-20 10:37:23.067259) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:23.067646) ========= script_repo_revision: 3f0aeafe59c40c5e92ba9636fa718cf26088e127 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:23.068106) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:23.068455) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-20 10:37:23.087069) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-20 10:37:23.087432) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:37:23 INFO - MultiFileLogger online at 20161020 10:37:23 in /builds/slave/test 10:37:23 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 10:37:23 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 10:37:23 INFO - {'append_to_log': False, 10:37:23 INFO - 'base_work_dir': '/builds/slave/test', 10:37:23 INFO - 'blob_upload_branch': 'Firefox', 10:37:23 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 10:37:23 INFO - 'branch': 'Firefox', 10:37:23 INFO - 'buildbot_json_path': 'buildprops.json', 10:37:23 INFO - 'config_files': ('talos/mac_config.py',), 10:37:23 INFO - 'default_actions': ('clobber', 10:37:23 INFO - 'read-buildbot-config', 10:37:23 INFO - 'download-and-extract', 10:37:23 INFO - 'populate-webroot', 10:37:23 INFO - 'create-virtualenv', 10:37:23 INFO - 'install', 10:37:23 INFO - 'run-tests'), 10:37:23 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 10:37:23 INFO - 'download_minidump_stackwalk': True, 10:37:23 INFO - 'download_symbols': 'ondemand', 10:37:23 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 10:37:23 INFO - 'tooltool.py': '/tools/tooltool.py', 10:37:23 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 10:37:23 INFO - '/tools/misc-python/virtualenv.py')}, 10:37:23 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 10:37:23 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 10:37:23 INFO - 'installer_path': 'installer.exe', 10:37:23 INFO - 'log_level': 'info', 10:37:23 INFO - 'log_name': 'talos', 10:37:23 INFO - 'log_to_console': True, 10:37:23 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 10:37:23 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 10:37:23 INFO - 'opt_config_files': (), 10:37:23 INFO - 'pip_index': False, 10:37:23 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:37:23 INFO - 'cmd': ('bash', 10:37:23 INFO - '-c', 10:37:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:37:23 INFO - 'enabled': True, 10:37:23 INFO - 'halt_on_failure': False, 10:37:23 INFO - 'name': 'check_screen_resolution'},), 10:37:23 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 10:37:23 INFO - 'cmd': ('bash', 10:37:23 INFO - '-c', 10:37:23 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 10:37:23 INFO - 'enabled': True, 10:37:23 INFO - 'halt_on_failure': False, 10:37:23 INFO - 'name': 'check_screen_resolution'},), 10:37:23 INFO - 'run_cmd_checks_enabled': True, 10:37:23 INFO - 'sps_profile': False, 10:37:23 INFO - 'sps_profile_interval': 0, 10:37:23 INFO - 'suite': 'dromaeojs-e10s', 10:37:23 INFO - 'system_bits': '32', 10:37:23 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 10:37:23 INFO - 'title': 't-yosemite-r7-0087', 10:37:23 INFO - 'tooltool_cache': '/builds/tooltool_cache', 10:37:23 INFO - 'use_talos_json': True, 10:37:23 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 10:37:23 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 10:37:23 INFO - 'work_dir': 'build'} 10:37:23 INFO - [mozharness: 2016-10-20 17:37:23.290213Z] Running clobber step. 10:37:23 INFO - Running pre-action listener: _resource_record_pre_action 10:37:23 INFO - Running main action method: clobber 10:37:23 INFO - rmtree: /builds/slave/test/build 10:37:23 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 10:37:25 INFO - Running post-action listener: _resource_record_post_action 10:37:25 INFO - [mozharness: 2016-10-20 17:37:25.389678Z] Finished clobber step (success) 10:37:25 INFO - [mozharness: 2016-10-20 17:37:25.389837Z] Running read-buildbot-config step. 10:37:25 INFO - Running pre-action listener: _resource_record_pre_action 10:37:25 INFO - Running main action method: read_buildbot_config 10:37:25 INFO - Using buildbot properties: 10:37:25 INFO - { 10:37:25 INFO - "project": "", 10:37:25 INFO - "product": "firefox", 10:37:25 INFO - "script_repo_revision": "production", 10:37:25 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 10:37:25 INFO - "repository": "", 10:37:25 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 10:37:25 INFO - "buildid": "20161020074850", 10:37:25 INFO - "pgo_build": "False", 10:37:25 INFO - "basedir": "/builds/slave/test", 10:37:25 INFO - "buildnumber": 28, 10:37:25 INFO - "slavename": "t-yosemite-r7-0087", 10:37:25 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 10:37:25 INFO - "platform": "yosemite_r7", 10:37:25 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 10:37:25 INFO - "branch": "mozilla-central", 10:37:25 INFO - "repo_path": "mozilla-central", 10:37:25 INFO - "revision": "3f0aeafe59c40c5e92ba9636fa718cf26088e127", 10:37:25 INFO - "stage_platform": "macosx64", 10:37:25 INFO - "builduid": "53e31627a5e14ebaad912fd7148a032d", 10:37:25 INFO - "slavebuilddir": "test" 10:37:25 INFO - } 10:37:25 INFO - Found installer url https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 10:37:25 INFO - Running post-action listener: _resource_record_post_action 10:37:25 INFO - [mozharness: 2016-10-20 17:37:25.393302Z] Finished read-buildbot-config step (success) 10:37:25 INFO - [mozharness: 2016-10-20 17:37:25.393419Z] Running download-and-extract step. 10:37:25 INFO - Running pre-action listener: _resource_record_pre_action 10:37:25 INFO - Running main action method: download_and_extract 10:37:25 INFO - mkdir: /builds/slave/test/build/tests 10:37:25 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:25 INFO - trying https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 10:37:25 INFO - Downloading https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:37:25 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json'}, attempt #1 10:37:26 INFO - Downloaded 1199 bytes. 10:37:26 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 10:37:26 INFO - Using the following test package requirements: 10:37:26 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:37:26 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 10:37:26 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 10:37:26 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'jsshell-mac.zip'], 10:37:26 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 10:37:26 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 10:37:26 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 10:37:26 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 10:37:26 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 10:37:26 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 10:37:26 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 10:37:26 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 10:37:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:37:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:37:26 INFO - Fetch https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:37:26 INFO - Http code: 200 10:37:26 INFO - via: 1.1 b9a8ce441329f20cea895e08f19f1ec8.cloudfront.net (CloudFront) 10:37:26 INFO - x-amz-cf-id: i46pNRvDrayGlXBr8J3dX2KnxoRjXDwRyb6Z882blz3dPTxqDLk0RA== 10:37:26 INFO - x-amz-version-id: C3QjzYFrTcMLlgn9VIhe4_hZtYUZSGa0 10:37:27 INFO - Expected file size: 16507671 10:37:27 INFO - Obtained file size: 16507671 10:37:34 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip', u'firefox-52.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 10:37:34 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 10:37:34 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 10:37:34 INFO - Fetch https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 10:37:34 INFO - Http code: 200 10:37:34 INFO - via: 1.1 1b52a5dd431f9e3c81753e61dfdf467a.cloudfront.net (CloudFront) 10:37:34 INFO - x-amz-cf-id: tfDQrBIz2HYIDHNnRjkb3PHO6oFoLgmM0nMoV_hoOy3jLGSWDQ8QEw== 10:37:34 INFO - x-amz-version-id: C3QjzYFrTcMLlgn9VIhe4_hZtYUZSGa0 10:37:35 INFO - Expected file size: 16507671 10:37:35 INFO - Obtained file size: 16507671 10:37:41 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 10:37:41 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 10:37:41 INFO - Fetch https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 10:37:41 INFO - Http code: 200 10:37:41 INFO - via: 1.1 e2af8a85927835558866752f53562ecd.cloudfront.net (CloudFront) 10:37:41 INFO - x-amz-cf-id: Ro8gsqB0jpKqjfhjSi2_YtHHc7-_1tUHqs7gwpPvAaIZBbMLEEPK3A== 10:37:41 INFO - x-amz-version-id: R1oDdo5IqdWGwans24KHct5.zUq_mHD4 10:37:42 INFO - Expected file size: 14409028 10:37:42 INFO - Obtained file size: 14409028 10:37:42 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:42 INFO - trying https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:37:42 INFO - Downloading https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 10:37:42 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg'}, attempt #1 10:37:45 INFO - Downloaded 111636940 bytes. 10:37:45 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:37:45 INFO - mkdir: /builds/slave/test/properties 10:37:45 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 10:37:45 INFO - Writing to file /builds/slave/test/properties/build_url 10:37:45 INFO - Contents: 10:37:45 INFO - build_url:https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 10:37:46 INFO - Running post-action listener: _resource_record_post_action 10:37:46 INFO - Running post-action listener: set_extra_try_arguments 10:37:46 INFO - [mozharness: 2016-10-20 17:37:46.246021Z] Finished download-and-extract step (success) 10:37:46 INFO - [mozharness: 2016-10-20 17:37:46.246147Z] Running populate-webroot step. 10:37:46 INFO - Running pre-action listener: _resource_record_pre_action 10:37:46 INFO - Running main action method: populate_webroot 10:37:46 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 10:37:46 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 10:37:46 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 10:37:46 INFO - '--tppagecycles', 10:37:46 INFO - '7'], 10:37:46 INFO - 'tests': ['tsvgm']}}, 10:37:46 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['tresize', 'tcanvasmark']}, 10:37:46 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 10:37:46 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['dromaeo_css', 'kraken']}, 10:37:46 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 10:37:46 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:46 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 10:37:46 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['damp', 'tps']}, 10:37:46 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'tests': ['damp', 'tps']}, 10:37:46 INFO - 'g3': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['dromaeo_dom']}, 10:37:46 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 10:37:46 INFO - 'g4': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['basic_compositor_video']}, 10:37:46 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 10:37:46 INFO - 'other': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'other-e10s': {'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'other-e10s_l64': {'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['a11yr', 10:37:46 INFO - 'ts_paint', 10:37:46 INFO - 'tpaint', 10:37:46 INFO - 'sessionrestore', 10:37:46 INFO - 'sessionrestore_no_auto_restore', 10:37:46 INFO - 'tabpaint']}, 10:37:46 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['tsvgx', 10:37:46 INFO - 'tsvgr_opacity', 10:37:46 INFO - 'tart', 10:37:46 INFO - 'tscrollx', 10:37:46 INFO - 'cart']}, 10:37:46 INFO - 'svgr-e10s': {'tests': ['tsvgx', 10:37:46 INFO - 'tsvgr_opacity', 10:37:46 INFO - 'tart', 10:37:46 INFO - 'tscrollx', 10:37:46 INFO - 'cart']}, 10:37:46 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'talos_options': ['--disable-e10s'], 10:37:46 INFO - 'tests': ['tp5o']}, 10:37:46 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'tests': ['tp5o']}, 10:37:46 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'talos_options': ['--disable-e10s', 10:37:46 INFO - '--xperf_path', 10:37:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:46 INFO - 'tests': ['tp5n']}, 10:37:46 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 10:37:46 INFO - 'talos_options': ['--xperf_path', 10:37:46 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 10:37:46 INFO - 'tests': ['tp5n']}}, 10:37:46 INFO - 'talos.zip': {'path': '', 10:37:46 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 10:37:46 INFO - Running post-action listener: _resource_record_post_action 10:37:46 INFO - [mozharness: 2016-10-20 17:37:46.264553Z] Finished populate-webroot step (success) 10:37:46 INFO - [mozharness: 2016-10-20 17:37:46.264685Z] Running create-virtualenv step. 10:37:46 INFO - Running pre-action listener: _resource_record_pre_action 10:37:46 INFO - Running main action method: create_virtualenv 10:37:46 INFO - Creating virtualenv /builds/slave/test/build/venv 10:37:46 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 10:37:46 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 10:37:46 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 10:37:46 INFO - Using real prefix '/tools/python27' 10:37:46 INFO - New python executable in /builds/slave/test/build/venv/bin/python 10:37:47 INFO - Installing distribute.............................................................................................................................................................................................done. 10:37:49 INFO - Installing pip.................done. 10:37:49 INFO - Return code: 0 10:37:49 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 10:37:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 10:37:50 INFO - Reading from file tmpfile_stdout 10:37:50 INFO - Output received: 10:37:50 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) 10:37:50 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 10:37: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')]} 10:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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')]} 10:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:37: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', 'pip>=1.5'] in /builds/slave/test/build 10:37: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 pip>=1.5 10:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:50 INFO - 'HOME': '/Users/cltbld', 10:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:50 INFO - 'LOGNAME': 'cltbld', 10:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:50 INFO - 'MOZ_NO_REMOTE': '1', 10:37:50 INFO - 'NO_EM_RESTART': '1', 10:37:50 INFO - 'PAGER': '/bin/cat', 10:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:50 INFO - 'PWD': '/builds/slave/test', 10:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:50 INFO - 'SHELL': '/bin/bash', 10:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:37:50 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:50 INFO - 'USER': 'cltbld', 10:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:50 INFO - 'XPC_FLAGS': '0x0', 10:37:50 INFO - 'XPC_SERVICE_NAME': '0', 10:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:50 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 10:37:50 INFO - Cleaning up... 10:37:50 INFO - Return code: 0 10:37:50 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 10:37: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')]} 10:37:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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')]} 10:37:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37: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', '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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:37: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', 'psutil>=3.1.1'] in /builds/slave/test/build 10:37: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 psutil>=3.1.1 10:37:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:37:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:50 INFO - 'HOME': '/Users/cltbld', 10:37:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:50 INFO - 'LOGNAME': 'cltbld', 10:37:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:50 INFO - 'MOZ_NO_REMOTE': '1', 10:37:50 INFO - 'NO_EM_RESTART': '1', 10:37:50 INFO - 'PAGER': '/bin/cat', 10:37:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:50 INFO - 'PWD': '/builds/slave/test', 10:37:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:50 INFO - 'SHELL': '/bin/bash', 10:37:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:37:50 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:37:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:50 INFO - 'USER': 'cltbld', 10:37:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:50 INFO - 'XPC_FLAGS': '0x0', 10:37:50 INFO - 'XPC_SERVICE_NAME': '0', 10:37:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:50 INFO - Downloading/unpacking psutil>=3.1.1 10:37: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 10:37: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 10:37: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 10:37: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 10:37: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 10:37: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 10:37:53 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 10:37:54 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:54 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:54 INFO - Installing collected packages: psutil 10:37:54 INFO - Running setup.py install for psutil 10:37:54 INFO - building 'psutil._psutil_osx' extension 10:37:54 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 10:37:54 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 10:37:54 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 10:37:54 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 10:37:55 INFO - building 'psutil._psutil_posix' extension 10:37:55 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 10:37:55 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 10:37:55 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 10:37:55 INFO - ^ 10:37:55 INFO - 1 warning generated. 10:37:55 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 10:37:55 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 10:37:55 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 10:37:55 INFO - Successfully installed psutil 10:37:55 INFO - Cleaning up... 10:37:55 INFO - Return code: 0 10:37:55 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 10:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:37:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 10:37:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 10:37:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:37:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:55 INFO - 'HOME': '/Users/cltbld', 10:37:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:55 INFO - 'LOGNAME': 'cltbld', 10:37:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:55 INFO - 'MOZ_NO_REMOTE': '1', 10:37:55 INFO - 'NO_EM_RESTART': '1', 10:37:55 INFO - 'PAGER': '/bin/cat', 10:37:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:55 INFO - 'PWD': '/builds/slave/test', 10:37:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:55 INFO - 'SHELL': '/bin/bash', 10:37:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:37:55 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:37:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:55 INFO - 'USER': 'cltbld', 10:37:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:55 INFO - 'XPC_FLAGS': '0x0', 10:37:55 INFO - 'XPC_SERVICE_NAME': '0', 10:37:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:55 INFO - Downloading/unpacking mozsystemmonitor==0.3 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:55 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 10:37:58 INFO - Downloading mozsystemmonitor-0.3.tar.gz 10:37:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 10:37:58 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 10:37:58 INFO - Installing collected packages: mozsystemmonitor 10:37:58 INFO - Running setup.py install for mozsystemmonitor 10:37:58 INFO - Successfully installed mozsystemmonitor 10:37:58 INFO - Cleaning up... 10:37:58 INFO - Return code: 0 10:37:58 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 10:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:37:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:37:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:37:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:37:58 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:37:58 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 10:37:58 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 10:37:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:37:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:37:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:37:58 INFO - 'HOME': '/Users/cltbld', 10:37:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:37:58 INFO - 'LOGNAME': 'cltbld', 10:37:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:37:58 INFO - 'MOZ_NO_REMOTE': '1', 10:37:58 INFO - 'NO_EM_RESTART': '1', 10:37:58 INFO - 'PAGER': '/bin/cat', 10:37:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:37:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:37:58 INFO - 'PWD': '/builds/slave/test', 10:37:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:37:58 INFO - 'SHELL': '/bin/bash', 10:37:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:37:58 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:37:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:37:58 INFO - 'USER': 'cltbld', 10:37:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:37:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:37:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:37:58 INFO - 'XPC_FLAGS': '0x0', 10:37:58 INFO - 'XPC_SERVICE_NAME': '0', 10:37:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:37:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:37:58 INFO - Downloading/unpacking jsonschema==2.5.1 10:37: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 10:37: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 10:37: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 10:37: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 10:37: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 10:37: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 10:38:01 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 10:38:01 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 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 10:38:01 INFO - Downloading functools32-3.2.3-2.tar.gz 10:38:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 10:38:01 INFO - warning: no files found matching '*.txt' 10:38:01 INFO - no previously-included directories found matching 'build' 10:38:01 INFO - no previously-included directories found matching 'dist' 10:38:01 INFO - no previously-included directories found matching '.git*' 10:38:01 INFO - Installing collected packages: jsonschema, functools32 10:38:01 INFO - Running setup.py install for functools32 10:38:01 INFO - warning: no files found matching '*.txt' 10:38:01 INFO - no previously-included directories found matching 'build' 10:38:01 INFO - no previously-included directories found matching 'dist' 10:38:01 INFO - no previously-included directories found matching '.git*' 10:38:01 INFO - Successfully installed jsonschema functools32 10:38:01 INFO - Cleaning up... 10:38:01 INFO - Return code: 0 10:38:01 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 10:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:38:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 10:38:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 10:38:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 10:38:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:01 INFO - 'HOME': '/Users/cltbld', 10:38:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:01 INFO - 'LOGNAME': 'cltbld', 10:38:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:01 INFO - 'MOZ_NO_REMOTE': '1', 10:38:01 INFO - 'NO_EM_RESTART': '1', 10:38:01 INFO - 'PAGER': '/bin/cat', 10:38:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:01 INFO - 'PWD': '/builds/slave/test', 10:38:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:01 INFO - 'SHELL': '/bin/bash', 10:38:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:01 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:01 INFO - 'USER': 'cltbld', 10:38:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:01 INFO - 'XPC_FLAGS': '0x0', 10:38:01 INFO - 'XPC_SERVICE_NAME': '0', 10:38:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:02 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 10:38:02 INFO - Cleaning up... 10:38:02 INFO - Return code: 0 10:38:02 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 10:38:02 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')]} 10:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:02 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')]} 10:38:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:02 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:02 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 10:38:02 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 10:38:02 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:02 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:02 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:02 INFO - 'HOME': '/Users/cltbld', 10:38:02 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:02 INFO - 'LOGNAME': 'cltbld', 10:38:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:02 INFO - 'MOZ_NO_REMOTE': '1', 10:38:02 INFO - 'NO_EM_RESTART': '1', 10:38:02 INFO - 'PAGER': '/bin/cat', 10:38:02 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:02 INFO - 'PWD': '/builds/slave/test', 10:38:02 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:02 INFO - 'SHELL': '/bin/bash', 10:38:02 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:02 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:02 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:02 INFO - 'USER': 'cltbld', 10:38:02 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:02 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:02 INFO - 'XPC_FLAGS': '0x0', 10:38:02 INFO - 'XPC_SERVICE_NAME': '0', 10:38:02 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:02 INFO - Downloading/unpacking blobuploader==1.2.4 10:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:02 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:02 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:02 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:02 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:04 INFO - Downloading blobuploader-1.2.4.tar.gz 10:38:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 10:38:05 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 10:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:05 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 10:38:05 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 10:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 10:38:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 10:38:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 10:38:06 INFO - Downloading docopt-0.6.1.tar.gz 10:38:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 10:38:06 INFO - Installing collected packages: blobuploader, requests, docopt 10:38:06 INFO - Running setup.py install for blobuploader 10:38:06 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 10:38:06 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 10:38:06 INFO - Running setup.py install for requests 10:38:06 INFO - Running setup.py install for docopt 10:38:07 INFO - Successfully installed blobuploader requests docopt 10:38:07 INFO - Cleaning up... 10:38:07 INFO - Return code: 0 10:38:07 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38:07 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')]} 10:38:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:07 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')]} 10:38:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:07 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:07 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 10:38:07 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 10:38:07 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:07 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:07 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:07 INFO - 'HOME': '/Users/cltbld', 10:38:07 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:07 INFO - 'LOGNAME': 'cltbld', 10:38:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:07 INFO - 'MOZ_NO_REMOTE': '1', 10:38:07 INFO - 'NO_EM_RESTART': '1', 10:38:07 INFO - 'PAGER': '/bin/cat', 10:38:07 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:07 INFO - 'PWD': '/builds/slave/test', 10:38:07 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:07 INFO - 'SHELL': '/bin/bash', 10:38:07 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:07 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:07 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:07 INFO - 'USER': 'cltbld', 10:38:07 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:07 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:07 INFO - 'XPC_FLAGS': '0x0', 10:38:07 INFO - 'XPC_SERVICE_NAME': '0', 10:38:07 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-z7pHxq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-MBiMq1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-BqGZBJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-SWX6Ta-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-vvmkGu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:38:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-r_SgWl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:38:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-L10xQU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-qBGP4D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-bmMJ4Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-0k28Xm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-a8cP6i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-xRx2Ig-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-HgJibs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-4K6qJS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-S5CuUD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:08 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:38:08 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-fo15y0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:38:09 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:38:09 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-6IZQuf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:09 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 10:38:09 INFO - Running setup.py install for manifestparser 10:38:09 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 10:38:09 INFO - Running setup.py install for mozcrash 10:38:09 INFO - Running setup.py install for mozdebug 10:38:09 INFO - Running setup.py install for mozdevice 10:38:09 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 10:38:09 INFO - Installing dm script to /builds/slave/test/build/venv/bin 10:38:09 INFO - Running setup.py install for mozfile 10:38:09 INFO - Running setup.py install for mozhttpd 10:38:10 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Running setup.py install for mozinfo 10:38:10 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Running setup.py install for mozInstall 10:38:10 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Running setup.py install for mozleak 10:38:10 INFO - Running setup.py install for mozlog 10:38:10 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Running setup.py install for moznetwork 10:38:10 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 10:38:10 INFO - Running setup.py install for mozprocess 10:38:10 INFO - Running setup.py install for mozprofile 10:38:11 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 10:38:11 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 10:38:11 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 10:38:11 INFO - Running setup.py install for mozrunner 10:38:11 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 10:38:11 INFO - Running setup.py install for mozscreenshot 10:38:11 INFO - Running setup.py install for moztest 10:38:11 INFO - Running setup.py install for mozversion 10:38:11 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 10:38:11 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 10:38:11 INFO - Cleaning up... 10:38:11 INFO - Return code: 0 10:38:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38:11 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')]} 10:38:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:11 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')]} 10:38:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:11 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:11 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 10:38:11 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 10:38:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:11 INFO - 'HOME': '/Users/cltbld', 10:38:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:11 INFO - 'LOGNAME': 'cltbld', 10:38:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:11 INFO - 'MOZ_NO_REMOTE': '1', 10:38:11 INFO - 'NO_EM_RESTART': '1', 10:38:11 INFO - 'PAGER': '/bin/cat', 10:38:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:11 INFO - 'PWD': '/builds/slave/test', 10:38:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:11 INFO - 'SHELL': '/bin/bash', 10:38:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:11 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:11 INFO - 'USER': 'cltbld', 10:38:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:11 INFO - 'XPC_FLAGS': '0x0', 10:38:11 INFO - 'XPC_SERVICE_NAME': '0', 10:38:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 10:38:11 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-xLJhpG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-3JRoi5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 10:38:12 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==0.17 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-bDFAoY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-_rdK3A-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Sy7eDG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-btieWX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-cKwBsM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-2S9fWh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-qD0psI-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 10:38:12 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)) 10:38:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 10:38:12 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-GGBGI1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 10:38:13 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)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-AHJPUd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 10:38:13 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)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-FOMKDF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 10:38:13 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)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-zqf5QQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 10:38:13 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)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-_z9nIm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 10:38:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.12 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-9QKPj4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 10:38:13 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)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-51xCLa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 10:38:13 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.7 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 10:38:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 10:38:13 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-_Y5aDc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 10:38:13 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)) 10:38:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:38:13 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==0.17->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 10:38:13 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)) 10:38:13 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)) 10:38:13 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)) 10:38:13 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 10:38:13 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 10:38:13 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 10:38:13 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 10:38:13 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 10:38:13 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 10:38:13 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 10:38:16 INFO - Downloading blessings-1.6.tar.gz 10:38:16 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 10:38:16 INFO - Installing collected packages: blessings 10:38:16 INFO - Running setup.py install for blessings 10:38:16 INFO - Successfully installed blessings 10:38:16 INFO - Cleaning up... 10:38:16 INFO - Return code: 0 10:38:16 INFO - Done creating virtualenv /builds/slave/test/build/venv. 10:38:16 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:16 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:16 INFO - Reading from file tmpfile_stdout 10:38:16 INFO - Current package versions: 10:38:16 INFO - blessings == 1.6 10:38:16 INFO - blobuploader == 1.2.4 10:38:16 INFO - docopt == 0.6.1 10:38:16 INFO - functools32 == 3.2.3-2 10:38:16 INFO - jsonschema == 2.5.1 10:38:16 INFO - manifestparser == 1.1 10:38:16 INFO - mozInstall == 1.12 10:38:16 INFO - mozcrash == 0.17 10:38:16 INFO - mozdebug == 0.1 10:38:16 INFO - mozdevice == 0.48 10:38:16 INFO - mozfile == 1.2 10:38:16 INFO - mozhttpd == 0.7 10:38:16 INFO - mozinfo == 0.9 10:38:16 INFO - mozleak == 0.1 10:38:16 INFO - mozlog == 3.4 10:38:16 INFO - moznetwork == 0.27 10:38:16 INFO - mozprocess == 0.23 10:38:16 INFO - mozprofile == 0.28 10:38:16 INFO - mozrunner == 6.12 10:38:16 INFO - mozscreenshot == 0.1 10:38:16 INFO - mozsystemmonitor == 0.3 10:38:16 INFO - moztest == 0.7 10:38:16 INFO - mozversion == 1.4 10:38:16 INFO - psutil == 3.1.1 10:38:16 INFO - requests == 1.2.3 10:38:16 INFO - wsgiref == 0.1.2 10:38:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 10:38: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')]} 10:38:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38: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')]} 10:38:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:16 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:16 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 10:38:16 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 10:38:16 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:16 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:16 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:16 INFO - 'HOME': '/Users/cltbld', 10:38:16 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:16 INFO - 'LOGNAME': 'cltbld', 10:38:16 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:16 INFO - 'MOZ_NO_REMOTE': '1', 10:38:16 INFO - 'NO_EM_RESTART': '1', 10:38:16 INFO - 'PAGER': '/bin/cat', 10:38:16 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:16 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:16 INFO - 'PWD': '/builds/slave/test', 10:38:16 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:16 INFO - 'SHELL': '/bin/bash', 10:38:16 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:16 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:16 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:16 INFO - 'USER': 'cltbld', 10:38:16 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:16 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:16 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:16 INFO - 'XPC_FLAGS': '0x0', 10:38:16 INFO - 'XPC_SERVICE_NAME': '0', 10:38:16 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 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)) 10:38:17 INFO - Cleaning up... 10:38:17 INFO - Return code: 0 10:38:17 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 10:38:17 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')]} 10:38:17 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 10:38:17 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:17 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')]} 10:38:17 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 10:38:17 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 10:38:17 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 0x105f46490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105ebc570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7ffa10477c20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, '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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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 10:38:17 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 10:38:17 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 10:38:17 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:17 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:17 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:17 INFO - 'HOME': '/Users/cltbld', 10:38:17 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:17 INFO - 'LOGNAME': 'cltbld', 10:38:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:17 INFO - 'MOZ_NO_REMOTE': '1', 10:38:17 INFO - 'NO_EM_RESTART': '1', 10:38:17 INFO - 'PAGER': '/bin/cat', 10:38:17 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:17 INFO - 'PWD': '/builds/slave/test', 10:38:17 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:17 INFO - 'SHELL': '/bin/bash', 10:38:17 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:17 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:17 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:17 INFO - 'USER': 'cltbld', 10:38:17 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:17 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:17 INFO - 'XPC_FLAGS': '0x0', 10:38:17 INFO - 'XPC_SERVICE_NAME': '0', 10:38:17 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 10:38:17 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 10:38:17 INFO - Cleaning up... 10:38:17 INFO - Return code: 0 10:38:17 INFO - Running post-action listener: _resource_record_post_action 10:38:17 INFO - Running post-action listener: _start_resource_monitoring 10:38:17 INFO - Starting resource monitoring. 10:38:17 INFO - [mozharness: 2016-10-20 17:38:17.556318Z] Finished create-virtualenv step (success) 10:38:17 INFO - [mozharness: 2016-10-20 17:38:17.557317Z] Running install step. 10:38:17 INFO - Running pre-action listener: _resource_record_pre_action 10:38:17 INFO - Running main action method: install 10:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 10:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 10:38:17 INFO - Reading from file tmpfile_stdout 10:38:17 INFO - Detecting whether we're running mozinstall >=1.0... 10:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 10:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 10:38:17 INFO - Reading from file tmpfile_stdout 10:38:17 INFO - Output received: 10:38:17 INFO - Usage: mozinstall [options] installer 10:38:17 INFO - Options: 10:38:17 INFO - -h, --help show this help message and exit 10:38:17 INFO - -d DEST, --destination=DEST 10:38:17 INFO - Directory to install application into. [default: 10:38:17 INFO - "/builds/slave/test"] 10:38:17 INFO - --app=APP Application being installed. [default: firefox] 10:38:17 INFO - mkdir: /builds/slave/test/build/application 10:38:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 10:38:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 10:38:52 INFO - Reading from file tmpfile_stdout 10:38:52 INFO - Output received: 10:38:52 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 10:38:52 INFO - Running post-action listener: _resource_record_post_action 10:38:52 INFO - [mozharness: 2016-10-20 17:38:52.733284Z] Finished install step (success) 10:38:52 INFO - [mozharness: 2016-10-20 17:38:52.733431Z] Running run-tests step. 10:38:52 INFO - Running pre-action listener: _resource_record_pre_action 10:38:52 INFO - Running main action method: run_tests 10:38:52 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:38:52 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:38:52 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:38:52 INFO - 2016-10-20 10:38:52.747 screenresolution[1636:7409] starting screenresolution argv=screenresolution get 10:38:52 INFO - 2016-10-20 10:38:52.750 screenresolution[1636:7409] Display 0: 1600x1200x32@60 10:38:52 INFO - 2016-10-20 10:38:52.756 screenresolution[1637:7411] starting screenresolution argv=screenresolution list 10:38:52 INFO - Available Modes on Display 0 10:38:52 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:38:52 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:38:52 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:38:52 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:38:52 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:38:52 INFO - Intel Iris: 10:38:52 INFO - Chipset Model: Intel Iris 10:38:52 INFO - Type: GPU 10:38:52 INFO - Bus: Built-In 10:38:52 INFO - VRAM (Dynamic, Max): 1536 MB 10:38:52 INFO - Vendor: Intel (0x8086) 10:38:52 INFO - Device ID: 0x0a2e 10:38:52 INFO - Revision ID: 0x0009 10:38:52 INFO - Displays: 10:38:52 INFO - Display: 10:38:52 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:38:52 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:38:52 INFO - Display Serial Number: 1600x1200 60 10:38:52 INFO - Main Display: Yes 10:38:52 INFO - Mirror: Off 10:38:52 INFO - Online: Yes 10:38:52 INFO - Rotation: Supported 10:38:52 INFO - Return code: 0 10:38:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 10:38:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 10:38:52 INFO - Python 2.7.3 10:38:52 INFO - Return code: 0 10:38:52 INFO - grabbing minidump binary from tooltool 10:38:52 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')]} 10:38:52 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 0x105d5ad30>, '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 0x105b80db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x105d52b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 10:38:52 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 10:38:52 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 10:38:52 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 10:38:53 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 10:38:53 INFO - Return code: 0 10:38:53 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 10:38:53 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 10:38:53 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 10:38:53 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 10:38:53 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 10:38:53 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 10:38:53 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0087 --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 10:38:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.ZnrubcEdCK/Render', 10:38:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 10:38:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 10:38:53 INFO - 'HOME': '/Users/cltbld', 10:38:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 10:38:53 INFO - 'LOGNAME': 'cltbld', 10:38:53 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 10:38:53 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 10:38:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 10:38:53 INFO - 'MOZ_NO_REMOTE': '1', 10:38:53 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 10:38:53 INFO - 'NO_EM_RESTART': '1', 10:38:53 INFO - 'PAGER': '/bin/cat', 10:38:53 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 10:38:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 10:38:53 INFO - 'PWD': '/builds/slave/test', 10:38:53 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 10:38:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 10:38:53 INFO - 'SHELL': '/bin/bash', 10:38:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.gaR8HlpRh0/Listeners', 10:38:53 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 10:38:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 10:38:53 INFO - 'USER': 'cltbld', 10:38:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 10:38:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 10:38:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 10:38:53 INFO - 'XPC_FLAGS': '0x0', 10:38:53 INFO - 'XPC_SERVICE_NAME': '0', 10:38:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 10:38:53 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0087', '--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 10:38:53 INFO - mozversion application_buildid: 20161020074850 10:38:53 INFO - mozversion application_changeset: 3f0aeafe59c40c5e92ba9636fa718cf26088e127 10:38:53 INFO - mozversion application_display_name: Nightly 10:38:53 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 10:38:53 INFO - mozversion application_name: Firefox 10:38:53 INFO - mozversion application_remotingname: firefox 10:38:53 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 10:38:53 INFO - mozversion application_vendor: Mozilla 10:38:53 INFO - mozversion application_version: 52.0a1 10:38:53 INFO - mozversion platform_buildid: 20161020074850 10:38:53 INFO - mozversion platform_changeset: 3f0aeafe59c40c5e92ba9636fa718cf26088e127 10:38:53 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 10:38:53 INFO - mozversion platform_version: 52.0a1 10:38:53 INFO - using testdate: 1476985133 10:38:53 INFO - actual date: 1476985133 10:38:53 INFO - starting webserver on 'localhost:49274' 10:38:53 INFO - SUITE-START | Running 2 tests 10:38:53 INFO - TEST-START | dromaeo_css 10:38:53 INFO - operating with platform_type : mac_ 10:38:53 INFO - Initialising browser for dromaeo_css test... 10:38:53 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp6IwBN6/profile http://localhost:49274/getInfo.html) 10:38:56 INFO - PROCESS | 1647 | 2016-10-20 10:38:56.230 plugin-container[1652:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4947, name = 'com.apple.tsm.portname' 10:38:56 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:56 INFO - PROCESS | 1647 | 2016-10-20 10:38:56.233 plugin-container[1652:7645] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950b, name = 'com.apple.CFPasteboardClient' 10:38:56 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:56 INFO - PROCESS | 1647 | 2016-10-20 10:38:56.233 plugin-container[1652:7645] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:38:56 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 10:38:56 INFO - PROCESS | 1647 | colorDepth:24 10:38:56 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 10:38:56 INFO - PROCESS | 1647 | __metrics 10:38:56 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:38:57 INFO - TEST-INFO | 1647: exit 0 10:38:57 INFO - Browser initialized. 10:38:57 INFO - Running cycle 1/1 for dromaeo_css test... 10:38:57 INFO - TEST-INFO | started process 1653 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp6IwBN6/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:38:59 INFO - PROCESS | 1653 | 2016-10-20 10:38:59.782 plugin-container[1654:7761] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a4f, name = 'com.apple.tsm.portname' 10:38:59 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:59 INFO - PROCESS | 1653 | 2016-10-20 10:38:59.782 plugin-container[1654:7761] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 10:38:59 INFO - PROCESS | 1653 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:38:59 INFO - PROCESS | 1653 | 2016-10-20 10:38:59.782 plugin-container[1654:7761] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:39:09 INFO - PROCESS | 1653 | RSS: Main: 236855296 10:39:09 INFO - PROCESS | 1653 | 10:39:58 INFO - PROCESS | 1653 | 1476985198944 addons.productaddons ERROR Request failed certificate checks: [Exception... "SSL is required and URI scheme is not https." nsresult: "0x8000ffff (NS_ERROR_UNEXPECTED)" location: "JS frame :: resource://gre/modules/CertUtils.jsm :: checkCert :: line 145" data: no] 10:42:11 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49274/tests/dromaeo/cssquery-ext.html) 10:42:13 INFO - PROCESS | 1653 | RSS: Main: 249524224 10:42:13 INFO - PROCESS | 1653 | 10:45:15 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-ext.html (next: http://localhost:49274/tests/dromaeo/cssquery-jquery.html) 10:45:16 INFO - PROCESS | 1653 | RSS: Main: 263368704 10:45:16 INFO - PROCESS | 1653 | 10:48:18 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49274/tests/dromaeo/cssquery-mootools.html) 10:48:19 INFO - PROCESS | 1653 | RSS: Main: 269172736 10:48:19 INFO - PROCESS | 1653 | 10:51:20 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49274/tests/dromaeo/cssquery-prototype.html) 10:51:21 INFO - PROCESS | 1653 | RSS: Main: 268525568 10:51:21 INFO - PROCESS | 1653 | 10:54:24 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49274/tests/dromaeo/cssquery-yui.html) 10:54:25 INFO - PROCESS | 1653 | RSS: Main: 274006016 10:54:25 INFO - PROCESS | 1653 | 10:57:27 INFO - PROCESS | 1653 | Cycle 1(1): loaded http://localhost:49274/tests/dromaeo/cssquery-yui.html (next: http://localhost:49274/tests/dromaeo/cssquery-dojo.html) 10:57:28 INFO - PROCESS | 1653 | RSS: Main: 276922368 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | __start_tp_report 10:57:28 INFO - PROCESS | 1653 | _x_x_mozilla_page_load 10:57:28 INFO - PROCESS | 1653 | _x_x_mozilla_page_load_details 10:57:28 INFO - PROCESS | 1653 | |i|pagename|runs| 10:57:28 INFO - PROCESS | 1653 | |0;dojo.html;2769.321516228524;2840.8295502269866;3023.6976302369767;3053.4351145038186;3362.4451965425706;4382.342648602711;4942.233953737176;5602.551795856332;5620.915686264704;5658.236138121352;5672.120821272699;5711.543076553876;5746.310442746866;5844.912326315109;5846.707664616773;5185.948140518593;5287.2862163608;5316.760745766431;5317.12267475866;5335.866603334928;5237.083510385686;5314.149736042234;5324.440933701963;5348.91976620351;5369.006733754249;5306.495882891119;5325.707086110262;5343.118385466413;5354.41101478837;5361.839144825662;4485.573870482299;5306.840794776143;5315.0432922074015;5319.547838433699;5323.361196656388;5206.167013277895;5235.502627250406;5254.8686282842855;5266.078436273675;5278.313819203518;5760.913586296209;5767.913481297784;5823;5860.589758716891;5876.676782776945;4926.3842019747535;6123.050927106306;6161.383861613847;6166.414190651881;6208.037754148114;6101.908471372933;6114.875284800388;6128.930927367305;6138.020573254751;6206.193194884681;4939.234418665077;5625.40933202012;5861.150133230658;5869.1196320551835;5908.468237858613;6309.08518264849;6319.715612797435;6346.714397852108;6351.904721429182;6360.681965901686;6067.696615169224;6089.786857459968;6118.265808103056;6175.475084617858;6181.320054793969;6088.4215999479975;6116.510679145657;6148.385161483816;6172.9074063889075;6198.938010619926;6138.318911621515;6157.692115394212;6169.5064394848305;6210.4100110489435;6230.408111229426;5137.280780690707;6111.318268824492;6179.443850053427;6197.380261973766;6208.1308616793685;4947.332110165154;4967.627427942919;4978.327925730053;4985.90028199434;5002.799888004512;3371.005553361764;4430.689851710414;4436.268015777425;4437.955620443819;4485.775711214426;5695.345035320991;5752.884942301131;5761.85595360111;5819.679917604572;5829;6166.229221347332;6185.010398336244;6186.907196392058;6203.689815509207;6205.2553693557065;5889.911651325234;5908.7045647718305;5917.295178205385;5941.851453713692;5964.135200396005;5392.110301800159;5403.1899313729555;5408.4050754416985;5444.373897001896;5459.181122831607;6343.746250150035;6355.682215889279;6371.394717501923;6375.713092910737;6406;6193.783217587363;6199.256089269316;6215.65813880241;6241.812745617639;6243.656598887105;6185.938140618537;6209.534284928739;6259.311475737574;6404.487640988803;6415.518836087405;6102.6643534604245;6183.81448556544;6188.659623720559;6202;6237.906431403623;5472.452754724655;5835.270591176103;6203.410675985774;6205.47253483459;6224.439800417984;4897.118518666674;5592.4966752990795;6152.723127459186;6175.9073613894925;6189.907151392823;4904.901901962012;6099.237595300588;6143.354947730602;6169.413905678954;6178.505719542516;5854.619449735754;6004.159417681615;6016.789412370546;6048.535298850849;6084.11780291864;6122.5408094392205;6134.754609815557;6144.354842741626;6153.2616086068165;6160.476359509313;5870.647761134345;5936.851578710394;5951.91072133927;5972.283326000907;6031.095335699505 10:57:28 INFO - PROCESS | 1653 | |1;ext.html;464.8396303275369;485.7352742755198;489.56673344090467;510.18879980830474;524.1979770950446;38409.80795096024;39964.40053399201;40146.59853401465;42761.93095172622;42840.78579607105;41168.382474262835;41939.37090943638;41940;41945.06384553082;41961.79019104908;9391.389559678617;9599.568019439108;9681.903180968206;9719.46542940138;9722.222222222204;9479.099485548897;9589.472579008152;9593.66422175224;9674.032596740304;9677.467739274338;9616.134547890688;9654.662086826962;9715.708528744113;9727.951360243223;9753.414795112281;7484.812879677997;8744.519051452167;9032.68385606504;9058.501782401965;9683.467409292487;9530.046995300483;9550.37922535033;9550.570224339921;9651.855222171673;9655.372400793925;30869.067438593298;30888.227794305098;31067;31150;31170.844145779352;3088.984555077233;3213.4256845659197;3999.7800120993334;4039.939400908959;4093.3246014407587;29880.70119298801;30186.245343866358;30231.395372092436;30563.847180764176;30613.23466913345;3173.4763763978917;3208.7433005359517;3218.3080637663015;3250.1062207892783;3306.7850589711593;469626;472410;475365;477469.612651938;481402;56195.71902140463;56620.71689641525;56622;56628.58428539237;56756.71621641865;58916.82166356649;59317.70341148267;59598;59733.402665973656;59917.700411497666;15092.849071509365;15308.464203753038;15369.692606147815;15387;15661.921690391475;12205.633830985085;12252.938735306267;12255.693607659701;12288.569900053455;12304.692382690504;14838.70322593542;14860.92569537167;14884.92557537227;14913.552593422215;15030.699386012218;6309.769594928945;6444.323346048691;6501.674916254168;6506.902396464056;6565.967170164214;855.7005048233069;886.7738726624669;925.7407925780792;945.9054094590485;1037.305005646219;25296.494070118864;25483.74516254851;27147.592786108224;28804.42391152207;29819.552706709415;1045.29442626227;1053.6934201589972;1067.5249513966367;1076.278893141597;1112.504935303782;14636.560903172922;14991.276003259547;15465.845341546665;15539.06765594068;15560.677342425794;640667;700337;706663;714986;899121;56969.7151514248;57605;57672.42327576754;57679.8464030717;57764.71117644469;1205.4033253539553;1268.105985280375;1354.4243696428978;1372.6431127906817;1397.9790303145462;22983.540329193656;26815;29581.26046848846;30229.546556801226;30719.539206912363;51307.48692513027;51713.741431292605;51848;51941.22088168756;51996.74001629968;587282;592241;594825;598242;601854;24601.138960136308;24711.50576988414;24839.627405589294;24984.750152498244;25093.74906250914;43249.135017300105;43689.12621747611;44352.778236108614;44399.55600443954;44625.55374446214;32739;34738.131546711535;36514.26971460609;37428.43857342088;37689.869303920925;33748.66251337554;36368;36433.089172770895;37220.62779372172;37581.4362784553;35035;35717.82141089278;36251.45622815713;36285.0928726784;36512;31705.36589268248;32778.344433111684;32947.01158965235;33069;33208.33583328368;23956.760432395455;24325.75674243235;24463.51072978495;24566.877165614056;25076.122335718163 10:57:28 INFO - PROCESS | 1653 | |2;jquery.html;295.41065574179515;376.5613060784185;378.0171553959705;382.9578746337901;402.01105281008716;16546.007239565635;20321.796782032176;21103.47241318964;21190.364289071353;21425.57148857022;20227.292044778435;20333.08501117453;20484.07821648026;20596.073176707047;20801.583968320625;4424.8008839602135;5595.132754423071;5893.20441740366;5894;5921.703914804264;4138.213739389522;4348.043430445313;5671.971640141794;5858.238429004224;6204.47261982732;6402.807915762534;6485.935140648604;6559.836004099888;6561.048647946045;6585;6278.37216278373;6475.449586785082;6496.9675151624415;6513.967430162819;6517.380848819378;5425.6202065855405;5541.113421852484;5630.634008789416;5635.633683810539;5642.125470552071;25594.616080758802;26124.085657001913;26157.869210653826;26179.738202617922;26218.34454138643;2177.7604463509;2405.7473965233576;2618.6988496322974;2678.9819868449863;2681.611166380864;15361.558263086357;15791.526254212393;15822.208889555477;16007.599810004727;16286.42997495094;1858.4982054845116;1868.439852722978;1892.3944337811768;1944.3000519812856;1953.4627977306213;95982.08035839314;114827;115767.42116289365;117190;117629.23556146664;10567.735806604896;10663.520141593646;10744.194185436123;10815.729606759895;10837.891621083689;13287.867121328663;13307.800382994263;13331.133476324008;13354.198748075147;13361.66595835112;8192.795180120545;8349.958250208832;8366.456180348257;8384.664613415394;8454.61954212062;6927.757528486479;8347.874781878276;8480.151984801594;8495.023072346636;8495.065542790288;6398.488120950313;6572.112764776694;6573.276939536647;6583.736650534021;6621.602703837785;1422.381264150098;1565.8590726834639;1658.477579562434;1838.724191371305;1879.5207222158535;851.4891065360735;871.5816408124009;918.5807926753182;997.6757553795046;1027.337367398024;17604;17993.740438169465;20170.89914550418;20487.8975605121;20683;772.6252767407802;934.9345545811864;944.4805357053593;951.1012093571646;951.362587066667;10663.306885052602;10702;10910.399928003877;11087;11102.500387482583;532665;533891;557028;574673;578203;125882.74117258894;126081;127978;129934.35032824775;131162;962.7496850818698;963.643451922793;994.2195376629311;994.796066806303;994.9007593976703;18276.81723182751;18573.628527429646;18718.438446846616;18746.71879921775;18747.43757687217;51283.97432051413;51749.48250517446;52812.73593632161;52996.205056923405;53864.461355385945;62656;66744.3325566738;72426.55146896926;75020.24979750132;75488.86766698395;6212.751489940351;6410.846047711457;6765.627890466071;6948.096747422803;7150.320719531636;9678.467684277432;11223.10215182767;12046.819297710366;12489.064195049781;12584.056195785535;9897.960714124913;10039.297249192487;10370.844437333295;10604.734881628021;10902.781944361226;8482.533460659477;10272.640457583948;10347.63783267582;10641.521131549254;10798.298110622783;10878.728031798952;11005.559777608805;11041.39272340029;11174.66476005721;11278.210525263154;7670.348020418327;7724.382049435919;7827.686892524235;7855.646495907584;7966.840663186588;10700.732481688021;10856.45717714127;10864.674059778219;10993.395363254778;11009.954054364822 10:57:28 INFO - PROCESS | 1653 | |3;mootools.html;1486.7323881701295;1536.7157075940952;1805.8284462976012;1847.2333981397721;1941.9029048547568;7630.8855367169535;7863.724769633064;8334.70828521002;8947.418417802839;8969.730908072752;8340.91659083409;8607.827843443143;8634.784130396727;8724.73825785226;8754.606042728077;2334.1480359668726;2366.0299277296313;2369.7511761265077;2373.311739595515;2373.964390534143;2275.2377953385635;2322.547103314854;2344.7186337639505;2370.762923707624;2373.6795532603137;2100.390886642872;2352.752960939102;2362.137819695816;2368.5854975379325;2388.4984153327823;2282.075759317479;2289.3360925331635;2332.4985128197436;2343.8828058597087;2350.647402889563;2327.94180145496;2328.092044102801;2329.114936324186;2337.6610391493136;2342.6954495915593;8733.344999125027;8780.297576193952;8814.647414103429;8879.556022198864;8882.42264252828;5219.112750832368;5337.852361742244;5357.7589008494715;5384.973075134639;5395.946040539584;3456.9654303456896;3536.6816986471085;3641.6904563112166;3666.120131168527;3681.300552894968;1026.779242462874;1057.5346847387202;1065.8614380130534;1072.51736718477;1079.7678499122726;11414.086873050219;11452.71368215793;11552.76894462106;11553.94223028888;11569.305841649528;4524.276115821519;4582.450105987236;4783.354247176656;4784.4497882743235;4794.304825800239;9813.460259685786;9891.35706179096;9899.901000989898;9913.30606857513;9916.851247231298;5336.893262134813;5372;5380.273663055437;5387.272718183073;5397.35231772182;4356.891077723082;4525.909481810345;4708.764561771966;4875.414950205998;4916.0905232532095;3898.6881049516537;3939.783311917872;3940.231654827281;4028.576999415078;4071.4528479177725;1289.509986205236;1295.7431291647088;1296.5526893221822;1304.302198323892;1309.0771006440436;709.2729951799344;867.3538214030461;894.9149830766066;902.1429641840278;922.5848368234322;5810.128480727924;5851.297844258716;5883.852903677357;5884.205632239678;6099.420555047263;807.4832107451236;907.8275127725711;908.3913777768911;928.3872644054892;970.2722957781664;3884.1260716338597;3898.2593307272095;3928.508936382952;4017.13631569214;4030.214108248922;10404.947975260075;10642.946785266178;10662.987016233445;10663.893361066444;10665.413402262795;10468.267221294436;10487;10532.999365060461;10609.787804244026;10661.413622250695;6116.877662446726;6704.698288577026;7423.703051877865;7820.960895195488;8083.393745469113;5206.140986737146;5494.423085575957;5555.638883472561;5569.220309156803;5588.552915766811;9630.614775409185;9634.277429192978;9779.657711980046;9882.950585247028;9983.450910199717;9728.75678108053;9886.357386769838;10006.44964526958;10128.44293563861;10143.898561014295;6775.051492790914;6996.090508233897;7248.528845625017;7370.594617295886;7616.35261002821;7159.57042577447;8203.958980205061;8291.461055031403;8413.495190288602;8429.87355189685;7256.818579535343;7389.963050184715;7399.815004624712;7451.478396512192;7546.622668866645;6164.907526387018;7244.746433874789;7267.454940879349;7275.12698476165;7328.816779580553;6828.760993365208;6852.451803855779;6928.584284942919;6938.410235130071;6943.444524438134;4915.8033678652455;4959.975200123977;5008.1235783736975;5015.097282489041;5113.079645663816;4950.504949505164;4994.550490455877;5004.3994720633755;5081;5146.845594632167 10:57:28 INFO - PROCESS | 1653 | |4;prototype.html;279.88384820299575;288.8570157771903;302.17958243369253;308.61577336216413;308.68822489285816;4707.411573553306;4952.236300411686;5511.311086114236;5599.580031497633;5616.213730077792;4528.20756367636;4748.287756836477;4865.635077369194;5392.703401312936;5531.723413829302;4155.522114956773;4204;4217.692515320246;4224.493060832704;4245.745255284693;4230.788460576959;4243.809028593705;4251.128518653684;4266.274733295331;4269.907745204859;3517.437210046393;4024.1549274652357;4136.627703506684;4156.944645685418;4202.403086827001;4097.938530922039;4132.669386449107;4135.462389889295;4142.171565686875;4154.605312495308;2993.835339056351;3571.214392803598;3604.27914417115;3630.8547658093644;3634.182308980485;5150.639455238135;5323.733813309319;5403.351597808249;5404.865078294052;5450.727463626842;4678.625709943196;4726.125666751627;4741.933065060334;4743.525647435229;4785.545373189541;4618.584327410549;4932.125792198942;5015.498450154992;5749.88500229993;5852.9807216510235;3033.3933213357195;3824.388097904322;3849.711271654637;3876.205377897524;3880.9223815523815;5264.6051546134195;5280.577553795687;6030.532443844807;6135.9079613805825;6181.81454556364;3414.975507347786;3783.6027217142355;3830.751001184914;3904.511936007999;3911.9608803912165;3271.9672803272138;3742.933264019796;4023.6982226332557;4028.939565906514;4030.778307193074;3538.203904121552;4271.273883439822;4324.437823083042;4441.822916927017;4723.385959825201;5540.168974653834;5608.9719551402795;5818.883622327529;5828.213191219216;5899.734511946973;1165.7959857024987;1343.2343564168525;1362;1363.2502123831853;1366.3782978744648;778.7073458059707;815.3151352863623;820.4216027700576;833.3208435125354;840.4159109419018;3978.562358140602;4049.4612047421792;4571.38286331341;4620.099080679235;4651;4691.366665500182;4881.973176630512;5015.238571071696;5047.343845300087;5075.512998630645;3776.678982286536;4117.608827161415;4174.91650167001;4580.519045500175;4653;2287.485315803931;2408.65197375648;2647.907323243716;2654.5665340715796;2657.3223827923757;4420.425344705168;4758.476567577563;4779.828941909254;5185.770118848931;5206.193040078794;4501.167284052461;4559.6124329431705;4880.755962201875;4926.827561035347;5079.034983353225;4190.177272886304;4611.792469338888;4727.338172655762;5018;5235.507880254109;4502.864914052584;4877.292792545132;5060.026589629973;5067.645264831426;5091.796328146833;4520.88697782558;4597.747123908083;4645.767711614473;4664.344157824059;4946;4426.896035462022;4718.8584342469785;5033.515113041734;5214.9400986610835;6002;3784.791836449022;3888.9416658750706;3892.5134358205223;3925.2345792570177;3929.60294220531;5883.764409474058;5980.910286345621;6016.87966240664;6026.306974697965;6093.542984276109;5467.863303417446;5791.681457519877;5801.027650598817;5849.590528662953;5892.5285977122585;5226.63413561047;5674.460926212003;5861.3366645846345;5871.412858714094;5908.3205431375945;5106.234064890296;5449.583108391767;5792.652440853562;5808.128780682931;5848.327442344185;5396.541293990055;5564.831385408947;5839.824805255849;5887.410399192279;5895.7052147393315;5792.247007888947;5793.710314484175;5801.970990145022;5810.82567522975;5857.26784151981 10:57:28 INFO - PROCESS | 1653 | |5;yui.html;655.4330504113943;668.9431398331142;724.3878922310646;772.8570214510313;817.3338728935919;12995;16207.756883646754;16211.351545938178;16216.918915405437;16254.187290635466;14754.26228688564;14894.776578351306;14920.477783277587;14930.701385972301;14965.476208332711;704.844934114494;724.7861880745166;872.7469033980154;873.7466134820894;891.6210610490548;597.1043434847728;668.318315318375;899.3794281945469;903.20969151992;919.6045700348848;824.6618886256606;910.4400793511976;961.7403301108696;962.7111866440075;963.1572374172599;894.369469523984;928.7167413938746;954.8376775948071;956.7034219391941;958.5925981457888;705.9869087858939;709.9432045436405;710.7725527831096;712.5688958180278;714.2857142857143;14621;14877.7768333474;15006.549803505912;15031.549053528302;15049.247537623076;14472.710545789025;14591.854081459156;14628.122312661275;14678.779818302733;14754.63113422173;11768.58809941648;11932.343721095336;12200.755984880341;12250.142490025703;12480.375981200903;3756.398976163828;3765.981170094132;3958.8614398495915;3992.7803970781597;3996.6003399660094;122387;122686;123079;123858.38070809678;124382;11275.943620281847;11293.491792869341;11344.829827552428;11386.829197562043;11783.234089784137;23702.762972370056;23764.04943802263;23819.166329178395;23860.64209036866;23906.52186956251;7872.094709108412;7925.405594580429;7968.601569921597;8089.474184178127;8233.094359620436;4340.131973605292;4362.105768317488;4433;4455.821767129279;4543.750093744876;3580.5166302548823;3688.299223147647;3696.537932758405;3704.9629503705155;3811.5426148862316;696.251529605676;700.9053777740041;717.1465955512923;733.2117973178854;735.2977906099769;607.6870411737958;643.8068579426154;649.2922714241499;675.5102550650837;683.962382068981;7027.578345299298;7904.263275991025;9493.525323733924;9627.277954153467;9811.411315321104;623.5292354272495;660.5673283998989;675.1054852320597;687.9489464226173;711.4735096029004;4089.9795501022304;4534.682572219913;4645.024544845553;4777.235642297216;4815.3017812417;137332.31333843467;157812.42187577978;158753.2062339704;160990;162275;113660;123959;125356;130742.9999999981;142299;78625.82061269086;109208;111996;114007;114592;8373.413861029669;8467.280281176169;8979;9960.55177517028;10029.348092373972;43281.56718432901;49502.257466137315;59853;60795;60908.390916092045;85016.5749171275;86263;86920.1307986912;87630.56184719036;87702.56148719216;10646.89353106459;13017.284049377375;13254.337283135612;13379.197248165141;13479.32603369808;14742.273155727453;14924.85075149278;15052.397904083715;15311.923440382727;15636.92181539085;13371;13797.034207605371;13826.170429774245;14190.929045354707;14468.760855540835;13757.518486852912;13886.375113120135;13903.026788124735;14092.929535352258;14502.782458262924;13417.092447708937;13617.251051191883;13657.590272291847;13668.248246346546;13777.862221377658;11270.94364528172;11384.772304553697;11406.486708098322;11421.714457138638;11568.363739994382;8870.778230544289;10269.948650257;11236.438178091226;11622.709432263922;11808.940955295513 10:57:28 INFO - PROCESS | 1653 | __end_tp_report 10:57:28 INFO - PROCESS | 1653 | __start_cc_report 10:57:28 INFO - PROCESS | 1653 | _x_x_mozilla_cycle_collect,363 10:57:28 INFO - PROCESS | 1653 | __end_cc_report 10:57:28 INFO - PROCESS | 1653 | __startTimestamp1476986248131__endTimestamp 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | ------- Summary: start ------- 10:57:28 INFO - PROCESS | 1653 | Number of tests: 6 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#0] dojo.html Cycles:165 Average:5696.95 Median:5936.85 stddev:706.48 (11.9%) stddev-sans-first:670.27 10:57:28 INFO - PROCESS | 1653 | Values: 2769.3 2840.8 3023.7 3053.4 3362.4 4382.3 4942.2 5602.6 5620.9 5658.2 5672.1 5711.5 5746.3 5844.9 5846.7 5185.9 5287.3 5316.8 5317.1 5335.9 5237.1 5314.1 5324.4 5348.9 5369.0 5306.5 5325.7 5343.1 5354.4 5361.8 4485.6 5306.8 5315.0 5319.5 5323.4 5206.2 5235.5 5254.9 5266.1 5278.3 5760.9 5767.9 5823.0 5860.6 5876.7 4926.4 6123.1 6161.4 6166.4 6208.0 6101.9 6114.9 6128.9 6138.0 6206.2 4939.2 5625.4 5861.2 5869.1 5908.5 6309.1 6319.7 6346.7 6351.9 6360.7 6067.7 6089.8 6118.3 6175.5 6181.3 6088.4 6116.5 6148.4 6172.9 6198.9 6138.3 6157.7 6169.5 6210.4 6230.4 5137.3 6111.3 6179.4 6197.4 6208.1 4947.3 4967.6 4978.3 4985.9 5002.8 3371.0 4430.7 4436.3 4438.0 4485.8 5695.3 5752.9 5761.9 5819.7 5829.0 6166.2 6185.0 6186.9 6203.7 6205.3 5889.9 5908.7 5917.3 5941.9 5964.1 5392.1 5403.2 5408.4 5444.4 5459.2 6343.7 6355.7 6371.4 6375.7 6406.0 6193.8 6199.3 6215.7 6241.8 6243.7 6185.9 6209.5 6259.3 6404.5 6415.5 6102.7 6183.8 6188.7 6202.0 6237.9 5472.5 5835.3 6203.4 6205.5 6224.4 4897.1 5592.5 6152.7 6175.9 6189.9 4904.9 6099.2 6143.4 6169.4 6178.5 5854.6 6004.2 6016.8 6048.5 6084.1 6122.5 6134.8 6144.4 6153.3 6160.5 5870.6 5936.9 5951.9 5972.3 6031.1 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#1] ext.html Cycles:180 Average:71767.63 Median:22073.19 stddev:164386.76 (744.7%) stddev-sans-first:164760.73 10:57:28 INFO - PROCESS | 1653 | Values: 464.8 485.7 489.6 510.2 524.2 38409.8 39964.4 40146.6 42761.9 42840.8 41168.4 41939.4 41940.0 41945.1 41961.8 9391.4 9599.6 9681.9 9719.5 9722.2 9479.1 9589.5 9593.7 9674.0 9677.5 9616.1 9654.7 9715.7 9728.0 9753.4 7484.8 8744.5 9032.7 9058.5 9683.5 9530.0 9550.4 9550.6 9651.9 9655.4 30869.1 30888.2 31067.0 31150.0 31170.8 3089.0 3213.4 3999.8 4039.9 4093.3 29880.7 30186.2 30231.4 30563.8 30613.2 3173.5 3208.7 3218.3 3250.1 3306.8 469626.0 472410.0 475365.0 477469.6 481402.0 56195.7 56620.7 56622.0 56628.6 56756.7 58916.8 59317.7 59598.0 59733.4 59917.7 15092.8 15308.5 15369.7 15387.0 15661.9 12205.6 12252.9 12255.7 12288.6 12304.7 14838.7 14860.9 14884.9 14913.6 15030.7 6309.8 6444.3 6501.7 6506.9 6566.0 855.7 886.8 925.7 945.9 1037.3 25296.5 25483.7 27147.6 28804.4 29819.6 1045.3 1053.7 1067.5 1076.3 1112.5 14636.6 14991.3 15465.8 15539.1 15560.7 640667.0 700337.0 706663.0 714986.0 899121.0 56969.7 57605.0 57672.4 57679.8 57764.7 1205.4 1268.1 1354.4 1372.6 1398.0 22983.5 26815.0 29581.3 30229.5 30719.5 51307.5 51713.7 51848.0 51941.2 51996.7 587282.0 592241.0 594825.0 598242.0 601854.0 24601.1 24711.5 24839.6 24984.8 25093.7 43249.1 43689.1 44352.8 44399.6 44625.6 32739.0 34738.1 36514.3 37428.4 37689.9 33748.7 36368.0 36433.1 37220.6 37581.4 35035.0 35717.8 36251.5 36285.1 36512.0 31705.4 32778.3 32947.0 33069.0 33208.3 23956.8 24325.8 24463.5 24566.9 25076.1 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#2] jquery.html Cycles:180 Average:33457.35 Median:2680.30 stddev:92942.27 (3467.6%) stddev-sans-first:93169.65 10:57:28 INFO - PROCESS | 1653 | Values: 295.4 376.6 378.0 383.0 402.0 16546.0 20321.8 21103.5 21190.4 21425.6 20227.3 20333.1 20484.1 20596.1 20801.6 4424.8 5595.1 5893.2 5894.0 5921.7 4138.2 4348.0 5672.0 5858.2 6204.5 6402.8 6485.9 6559.8 6561.0 6585.0 6278.4 6475.4 6497.0 6514.0 6517.4 5425.6 5541.1 5630.6 5635.6 5642.1 25594.6 26124.1 26157.9 26179.7 26218.3 2177.8 2405.7 2618.7 2679.0 2681.6 15361.6 15791.5 15822.2 16007.6 16286.4 1858.5 1868.4 1892.4 1944.3 1953.5 95982.1 114827.0 115767.4 117190.0 117629.2 10567.7 10663.5 10744.2 10815.7 10837.9 13287.9 13307.8 13331.1 13354.2 13361.7 8192.8 8350.0 8366.5 8384.7 8454.6 6927.8 8347.9 8480.2 8495.0 8495.1 6398.5 6572.1 6573.3 6583.7 6621.6 1422.4 1565.9 1658.5 1838.7 1879.5 851.5 871.6 918.6 997.7 1027.3 17604.0 17993.7 20170.9 20487.9 20683.0 772.6 934.9 944.5 951.1 951.4 10663.3 10702.0 10910.4 11087.0 11102.5 532665.0 533891.0 557028.0 574673.0 578203.0 125882.7 126081.0 127978.0 129934.4 131162.0 962.7 963.6 994.2 994.8 994.9 18276.8 18573.6 18718.4 18746.7 18747.4 51284.0 51749.5 52812.7 52996.2 53864.5 62656.0 66744.3 72426.6 75020.2 75488.9 6212.8 6410.8 6765.6 6948.1 7150.3 9678.5 11223.1 12046.8 12489.1 12584.1 9898.0 10039.3 10370.8 10604.7 10902.8 8482.5 10272.6 10347.6 10641.5 10798.3 10878.7 11005.6 11041.4 11174.7 11278.2 7670.3 7724.4 7827.7 7855.6 7966.8 10700.7 10856.5 10864.7 10993.4 11010.0 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#3] mootools.html Cycles:180 Average:5626.12 Median:5097.04 stddev:3129.54 (61.4%) stddev-sans-first:3122.86 10:57:28 INFO - PROCESS | 1653 | Values: 1486.7 1536.7 1805.8 1847.2 1941.9 7630.9 7863.7 8334.7 8947.4 8969.7 8340.9 8607.8 8634.8 8724.7 8754.6 2334.1 2366.0 2369.8 2373.3 2374.0 2275.2 2322.5 2344.7 2370.8 2373.7 2100.4 2352.8 2362.1 2368.6 2388.5 2282.1 2289.3 2332.5 2343.9 2350.6 2327.9 2328.1 2329.1 2337.7 2342.7 8733.3 8780.3 8814.6 8879.6 8882.4 5219.1 5337.9 5357.8 5385.0 5395.9 3457.0 3536.7 3641.7 3666.1 3681.3 1026.8 1057.5 1065.9 1072.5 1079.8 11414.1 11452.7 11552.8 11553.9 11569.3 4524.3 4582.5 4783.4 4784.4 4794.3 9813.5 9891.4 9899.9 9913.3 9916.9 5336.9 5372.0 5380.3 5387.3 5397.4 4356.9 4525.9 4708.8 4875.4 4916.1 3898.7 3939.8 3940.2 4028.6 4071.5 1289.5 1295.7 1296.6 1304.3 1309.1 709.3 867.4 894.9 902.1 922.6 5810.1 5851.3 5883.9 5884.2 6099.4 807.5 907.8 908.4 928.4 970.3 3884.1 3898.3 3928.5 4017.1 4030.2 10404.9 10642.9 10663.0 10663.9 10665.4 10468.3 10487.0 10533.0 10609.8 10661.4 6116.9 6704.7 7423.7 7821.0 8083.4 5206.1 5494.4 5555.6 5569.2 5588.6 9630.6 9634.3 9779.7 9883.0 9983.5 9728.8 9886.4 10006.4 10128.4 10143.9 6775.1 6996.1 7248.5 7370.6 7616.4 7159.6 8204.0 8291.5 8413.5 8429.9 7256.8 7390.0 7399.8 7451.5 7546.6 6164.9 7244.7 7267.5 7275.1 7328.8 6828.8 6852.5 6928.6 6938.4 6943.4 4915.8 4960.0 5008.1 5015.1 5113.1 4950.5 4994.6 5004.4 5081.0 5146.8 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#4] prototype.html Cycles:180 Average:4413.08 Median:4721.12 stddev:1368.51 (29.0%) stddev-sans-first:1336.72 10:57:28 INFO - PROCESS | 1653 | Values: 279.9 288.9 302.2 308.6 308.7 4707.4 4952.2 5511.3 5599.6 5616.2 4528.2 4748.3 4865.6 5392.7 5531.7 4155.5 4204.0 4217.7 4224.5 4245.7 4230.8 4243.8 4251.1 4266.3 4269.9 3517.4 4024.2 4136.6 4156.9 4202.4 4097.9 4132.7 4135.5 4142.2 4154.6 2993.8 3571.2 3604.3 3630.9 3634.2 5150.6 5323.7 5403.4 5404.9 5450.7 4678.6 4726.1 4741.9 4743.5 4785.5 4618.6 4932.1 5015.5 5749.9 5853.0 3033.4 3824.4 3849.7 3876.2 3880.9 5264.6 5280.6 6030.5 6135.9 6181.8 3415.0 3783.6 3830.8 3904.5 3912.0 3272.0 3742.9 4023.7 4028.9 4030.8 3538.2 4271.3 4324.4 4441.8 4723.4 5540.2 5609.0 5818.9 5828.2 5899.7 1165.8 1343.2 1362.0 1363.3 1366.4 778.7 815.3 820.4 833.3 840.4 3978.6 4049.5 4571.4 4620.1 4651.0 4691.4 4882.0 5015.2 5047.3 5075.5 3776.7 4117.6 4174.9 4580.5 4653.0 2287.5 2408.7 2647.9 2654.6 2657.3 4420.4 4758.5 4779.8 5185.8 5206.2 4501.2 4559.6 4880.8 4926.8 5079.0 4190.2 4611.8 4727.3 5018.0 5235.5 4502.9 4877.3 5060.0 5067.6 5091.8 4520.9 4597.7 4645.8 4664.3 4946.0 4426.9 4718.9 5033.5 5214.9 6002.0 3784.8 3888.9 3892.5 3925.2 3929.6 5883.8 5980.9 6016.9 6026.3 6093.5 5467.9 5791.7 5801.0 5849.6 5892.5 5226.6 5674.5 5861.3 5871.4 5908.3 5106.2 5449.6 5792.7 5808.1 5848.3 5396.5 5564.8 5839.8 5887.4 5895.7 5792.2 5793.7 5802.0 5810.8 5857.3 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - PROCESS | 1653 | [#5] yui.html Cycles:180 Average:25046.81 Median:3692.42 stddev:40364.64 (1093.2%) stddev-sans-first:40436.33 10:57:28 INFO - PROCESS | 1653 | Values: 655.4 668.9 724.4 772.9 817.3 12995.0 16207.8 16211.4 16216.9 16254.2 14754.3 14894.8 14920.5 14930.7 14965.5 704.8 724.8 872.7 873.7 891.6 597.1 668.3 899.4 903.2 919.6 824.7 910.4 961.7 962.7 963.2 894.4 928.7 954.8 956.7 958.6 706.0 709.9 710.8 712.6 714.3 14621.0 14877.8 15006.5 15031.5 15049.2 14472.7 14591.9 14628.1 14678.8 14754.6 11768.6 11932.3 12200.8 12250.1 12480.4 3756.4 3766.0 3958.9 3992.8 3996.6 122387.0 122686.0 123079.0 123858.4 124382.0 11275.9 11293.5 11344.8 11386.8 11783.2 23702.8 23764.0 23819.2 23860.6 23906.5 7872.1 7925.4 7968.6 8089.5 8233.1 4340.1 4362.1 4433.0 4455.8 4543.8 3580.5 3688.3 3696.5 3705.0 3811.5 696.3 700.9 717.1 733.2 735.3 607.7 643.8 649.3 675.5 684.0 7027.6 7904.3 9493.5 9627.3 9811.4 623.5 660.6 675.1 687.9 711.5 4090.0 4534.7 4645.0 4777.2 4815.3 137332.3 157812.4 158753.2 160990.0 162275.0 113660.0 123959.0 125356.0 130743.0 142299.0 78625.8 109208.0 111996.0 114007.0 114592.0 8373.4 8467.3 8979.0 9960.6 10029.3 43281.6 49502.3 59853.0 60795.0 60908.4 85016.6 86263.0 86920.1 87630.6 87702.6 10646.9 13017.3 13254.3 13379.2 13479.3 14742.3 14924.9 15052.4 15311.9 15636.9 13371.0 13797.0 13826.2 14190.9 14468.8 13757.5 13886.4 13903.0 14092.9 14502.8 13417.1 13617.3 13657.6 13668.2 13777.9 11270.9 11384.8 11406.5 11421.7 11568.4 8870.8 10269.9 11236.4 11622.7 11808.9 10:57:28 INFO - PROCESS | 1653 | -------- Summary: end -------- 10:57:28 INFO - PROCESS | 1653 | 10:57:28 INFO - TEST-INFO | 1653: exit 0 10:57:28 INFO - TEST-OK | dromaeo_css | took 1115454ms 10:57:28 INFO - TEST-START | kraken 10:57:28 INFO - operating with platform_type : mac_ 10:57:28 INFO - Initialising browser for kraken test... 10:57:28 INFO - TEST-INFO | started process 1661 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpKd2s7F/profile http://localhost:49274/getInfo.html) 10:57:31 INFO - PROCESS | 1661 | 2016-10-20 10:57:31.589 plugin-container[1662:9868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 10:57:31 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:57:31 INFO - PROCESS | 1661 | 2016-10-20 10:57:31.592 plugin-container[1662:9868] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 10:57:31 INFO - PROCESS | 1661 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:57:31 INFO - PROCESS | 1661 | 2016-10-20 10:57:31.592 plugin-container[1662:9868] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:57:31 INFO - PROCESS | 1661 | __metrics Screen width/height:1600/1200 10:57:31 INFO - PROCESS | 1661 | colorDepth:24 10:57:31 INFO - PROCESS | 1661 | Browser inner width/height: 1024/689 10:57:31 INFO - PROCESS | 1661 | __metrics 10:57:31 ERROR - PROCESS | 1661 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3097: TypeError: this.tabs is undefined 10:57:32 INFO - TEST-INFO | 1661: exit 0 10:57:32 INFO - Browser initialized. 10:57:32 INFO - Running cycle 1/1 for kraken test... 10:57:32 INFO - TEST-INFO | started process 1663 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpKd2s7F/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 10:57:35 INFO - PROCESS | 1663 | 2016-10-20 10:57:35.009 plugin-container[1664:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x863f, name = 'com.apple.tsm.portname' 10:57:35 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:57:35 INFO - PROCESS | 1663 | 2016-10-20 10:57:35.010 plugin-container[1664:9984] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 10:57:35 INFO - PROCESS | 1663 | See /usr/include/servers/bootstrap_defs.h for the error codes. 10:57:35 INFO - PROCESS | 1663 | 2016-10-20 10:57:35.010 plugin-container[1664:9984] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 10:57:44 INFO - PROCESS | 1663 | RSS: Main: 236789760 10:57:44 INFO - PROCESS | 1663 | 10:57:47 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection) 10:57:48 INFO - PROCESS | 1663 | RSS: Main: 249253888 10:57:48 INFO - PROCESS | 1663 | 10:57:51 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-dft) 10:57:52 INFO - PROCESS | 1663 | RSS: Main: 256774144 10:57:52 INFO - PROCESS | 1663 | 10:57:55 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-fft) 10:57:56 INFO - PROCESS | 1663 | RSS: Main: 259694592 10:57:56 INFO - PROCESS | 1663 | 10:57:58 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator) 10:57:58 INFO - PROCESS | 1663 | RSS: Main: 261812224 10:57:58 INFO - PROCESS | 1663 | 10:58:00 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur) 10:58:01 INFO - PROCESS | 1663 | RSS: Main: 262254592 10:58:01 INFO - PROCESS | 1663 | 10:58:04 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom) 10:58:04 INFO - PROCESS | 1663 | RSS: Main: 263208960 10:58:04 INFO - PROCESS | 1663 | 10:58:07 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate) 10:58:08 INFO - PROCESS | 1663 | RSS: Main: 263438336 10:58:08 INFO - PROCESS | 1663 | 10:58:11 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial) 10:58:12 INFO - PROCESS | 1663 | RSS: Main: 264101888 10:58:12 INFO - PROCESS | 1663 | 10:58:13 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox) 10:58:14 INFO - PROCESS | 1663 | RSS: Main: 262778880 10:58:14 INFO - PROCESS | 1663 | 10:58:16 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes) 10:58:16 INFO - PROCESS | 1663 | RSS: Main: 262803456 10:58:16 INFO - PROCESS | 1663 | 10:58:19 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm) 10:58:19 INFO - PROCESS | 1663 | RSS: Main: 262762496 10:58:19 INFO - PROCESS | 1663 | 10:58:22 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 10:58:23 INFO - PROCESS | 1663 | RSS: Main: 263159808 10:58:23 INFO - PROCESS | 1663 | 10:58:26 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 10:58:27 INFO - PROCESS | 1663 | RSS: Main: 263000064 10:58:27 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | Cycle 1(1): loaded http://localhost:49274/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49274/tests/kraken/driver.html?testName=ai-astar) 10:58:29 INFO - PROCESS | 1663 | RSS: Main: 264732672 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | __start_tp_report 10:58:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load 10:58:29 INFO - PROCESS | 1663 | _x_x_mozilla_page_load_details 10:58:29 INFO - PROCESS | 1663 | |i|pagename|runs| 10:58:29 INFO - PROCESS | 1663 | |0;ai-astar;103;121;118;117;118;130;169;162;120;118 10:58:29 INFO - PROCESS | 1663 | |1;audio-beat-detection;182;171;137;152;117;116;116;144;132;132 10:58:29 INFO - PROCESS | 1663 | |2;audio-dft;226;209;197;200;195;228;260;195;194;192 10:58:29 INFO - PROCESS | 1663 | |3;audio-fft;82;56;72;81;52;51;58;69;103;84 10:58:29 INFO - PROCESS | 1663 | |4;audio-oscillator;110;70;71;59;72;89;121;101;94;86 10:58:29 INFO - PROCESS | 1663 | |5;imaging-gaussian-blur;89;86;89;86;103;85;89;86;98;86 10:58:29 INFO - PROCESS | 1663 | |6;imaging-darkroom;121;131;102;149;150;103;149;94;95;96 10:58:29 INFO - PROCESS | 1663 | |7;imaging-desaturate;96;76;135;134;89;93;135;90;74;133 10:58:29 INFO - PROCESS | 1663 | |8;json-parse-financial;56;55;56;58;57;58;57;64;77;89 10:58:29 INFO - PROCESS | 1663 | |9;json-stringify-tinderbox;71;71;80;79;75;64;67;69;72;72 10:58:29 INFO - PROCESS | 1663 | |10;stanford-crypto-aes;112;110;111;85;88;86;92;90;90;93 10:58:29 INFO - PROCESS | 1663 | |11;stanford-crypto-ccm;141;176;157;149;162;155;164;150;184;148 10:58:29 INFO - PROCESS | 1663 | |12;stanford-crypto-pbkdf2;176;180;201;237;190;200;176;211;166;158 10:58:29 INFO - PROCESS | 1663 | |13;stanford-crypto-sha256-iterative;69;71;73;74;97;88;91;92;79;72 10:58:29 INFO - PROCESS | 1663 | __end_tp_report 10:58:29 INFO - PROCESS | 1663 | __start_cc_report 10:58:29 INFO - PROCESS | 1663 | _x_x_mozilla_cycle_collect,937 10:58:29 INFO - PROCESS | 1663 | __end_cc_report 10:58:29 INFO - PROCESS | 1663 | __startTimestamp1476986309381__endTimestamp 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | ------- Summary: start ------- 10:58:29 INFO - PROCESS | 1663 | Number of tests: 14 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#0] ai-astar Cycles:10 Average:127.60 Median:120.50 stddev:21.08 (17.5%) stddev-sans-first:20.39 10:58:29 INFO - PROCESS | 1663 | Values: 103.0 121.0 118.0 117.0 118.0 130.0 169.0 162.0 120.0 118.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:140.50 stddev:22.86 (16.3%) stddev-sans-first:18.48 10:58:29 INFO - PROCESS | 1663 | Values: 182.0 171.0 137.0 152.0 117.0 116.0 116.0 144.0 132.0 132.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#2] audio-dft Cycles:10 Average:209.60 Median:204.50 stddev:22.06 (10.8%) stddev-sans-first:22.58 10:58:29 INFO - PROCESS | 1663 | Values: 226.0 209.0 197.0 200.0 195.0 228.0 260.0 195.0 194.0 192.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#3] audio-fft Cycles:10 Average:70.80 Median:70.50 stddev:16.91 (24.0%) stddev-sans-first:17.44 10:58:29 INFO - PROCESS | 1663 | Values: 82.0 56.0 72.0 81.0 52.0 51.0 58.0 69.0 103.0 84.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#4] audio-oscillator Cycles:10 Average:87.30 Median:71.50 stddev:19.69 (27.5%) stddev-sans-first:19.09 10:58:29 INFO - PROCESS | 1663 | Values: 110.0 70.0 71.0 59.0 72.0 89.0 121.0 101.0 94.0 86.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#5] imaging-gaussian-blur Cycles:10 Average:89.70 Median:87.50 stddev:6.00 (6.9%) stddev-sans-first:6.36 10:58:29 INFO - PROCESS | 1663 | Values: 89.0 86.0 89.0 86.0 103.0 85.0 89.0 86.0 98.0 86.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#6] imaging-darkroom Cycles:10 Average:119.00 Median:149.50 stddev:24.00 (16.1%) stddev-sans-first:25.44 10:58:29 INFO - PROCESS | 1663 | Values: 121.0 131.0 102.0 149.0 150.0 103.0 149.0 94.0 95.0 96.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#7] imaging-desaturate Cycles:10 Average:105.50 Median:82.50 stddev:25.67 (31.1%) stddev-sans-first:27.00 10:58:29 INFO - PROCESS | 1663 | Values: 96.0 76.0 135.0 134.0 89.0 93.0 135.0 90.0 74.0 133.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:58.00 stddev:11.33 (19.5%) stddev-sans-first:11.76 10:58:29 INFO - PROCESS | 1663 | Values: 56.0 55.0 56.0 58.0 57.0 58.0 57.0 64.0 77.0 89.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#9] json-stringify-tinderbox Cycles:10 Average:72.00 Median:72.00 stddev:4.97 (6.9%) stddev-sans-first:5.25 10:58:29 INFO - PROCESS | 1663 | Values: 71.0 71.0 80.0 79.0 75.0 64.0 67.0 69.0 72.0 72.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#10] stanford-crypto-aes Cycles:10 Average:95.70 Median:89.00 stddev:10.84 (12.2%) stddev-sans-first:9.77 10:58:29 INFO - PROCESS | 1663 | Values: 112.0 110.0 111.0 85.0 88.0 86.0 92.0 90.0 90.0 93.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#11] stanford-crypto-ccm Cycles:10 Average:158.60 Median:159.50 stddev:13.30 (8.3%) stddev-sans-first:12.49 10:58:29 INFO - PROCESS | 1663 | Values: 141.0 176.0 157.0 149.0 162.0 155.0 164.0 150.0 184.0 148.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.50 Median:195.00 stddev:23.48 (12.0%) stddev-sans-first:24.39 10:58:29 INFO - PROCESS | 1663 | Values: 176.0 180.0 201.0 237.0 190.0 200.0 176.0 211.0 166.0 158.0 10:58:29 INFO - PROCESS | 1663 | 10:58:29 INFO - PROCESS | 1663 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:80.60 Median:83.50 stddev:10.36 (12.4%) stddev-sans-first:10.11 10:58:29 INFO - PROCESS | 1663 | Values: 69.0 71.0 73.0 74.0 97.0 88.0 91.0 92.0 79.0 72.0 10:58:29 INFO - PROCESS | 1663 | -------- Summary: end -------- 10:58:29 INFO - PROCESS | 1663 | 10:58:30 INFO - TEST-INFO | 1663: exit 0 10:58:30 INFO - TEST-OK | kraken | took 61478ms 10:58:30 INFO - SUITE-END | took 1176s 10:58:30 INFO - Completed test suite (00:19:37) 10:58:30 INFO - javascript benchmark 10:58:30 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7269.824771073303, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2769.321516228524, 2840.8295502269866, 3023.6976302369767, 3053.4351145038186, 3362.4451965425706, 4382.342648602711, 4942.233953737176, 5602.551795856332, 5620.915686264704, 5658.236138121352, 5672.120821272699, 5711.543076553876, 5746.310442746866, 5844.912326315109, 5846.707664616773, 5185.948140518593, 5287.2862163608, 5316.760745766431, 5317.12267475866, 5335.866603334928, 5237.083510385686, 5314.149736042234, 5324.440933701963, 5348.91976620351, 5369.006733754249, 5306.495882891119, 5325.707086110262, 5343.118385466413, 5354.41101478837, 5361.839144825662, 4485.573870482299, 5306.840794776143, 5315.0432922074015, 5319.547838433699, 5323.361196656388, 5206.167013277895, 5235.502627250406, 5254.8686282842855, 5266.078436273675, 5278.313819203518, 5760.913586296209, 5767.913481297784, 5823.0, 5860.589758716891, 5876.676782776945, 4926.3842019747535, 6123.050927106306, 6161.383861613847, 6166.414190651881, 6208.037754148114, 6101.908471372933, 6114.875284800388, 6128.930927367305, 6138.020573254751, 6206.193194884681, 4939.234418665077, 5625.40933202012, 5861.150133230658, 5869.1196320551835, 5908.468237858613, 6309.08518264849, 6319.715612797435, 6346.714397852108, 6351.904721429182, 6360.681965901686, 6067.696615169224, 6089.786857459968, 6118.265808103056, 6175.475084617858, 6181.320054793969, 6088.4215999479975, 6116.510679145657, 6148.385161483816, 6172.9074063889075, 6198.938010619926, 6138.318911621515, 6157.692115394212, 6169.5064394848305, 6210.4100110489435, 6230.408111229426, 5137.280780690707, 6111.318268824492, 6179.443850053427, 6197.380261973766, 6208.1308616793685, 4947.332110165154, 4967.627427942919, 4978.327925730053, 4985.90028199434, 5002.799888004512, 3371.005553361764, 4430.689851710414, 4436.268015777425, 4437.955620443819, 4485.775711214426, 5695.345035320991, 5752.884942301131, 5761.85595360111, 5819.679917604572, 5829.0, 6166.229221347332, 6185.010398336244, 6186.907196392058, 6203.689815509207, 6205.2553693557065, 5889.911651325234, 5908.7045647718305, 5917.295178205385, 5941.851453713692, 5964.135200396005, 5392.110301800159, 5403.1899313729555, 5408.4050754416985, 5444.373897001896, 5459.181122831607, 6343.746250150035, 6355.682215889279, 6371.394717501923, 6375.713092910737, 6406.0, 6193.783217587363, 6199.256089269316, 6215.65813880241, 6241.812745617639, 6243.656598887105, 6185.938140618537, 6209.534284928739, 6259.311475737574, 6404.487640988803, 6415.518836087405, 6102.6643534604245, 6183.81448556544, 6188.659623720559, 6202.0, 6237.906431403623, 5472.452754724655, 5835.270591176103, 6203.410675985774, 6205.47253483459, 6224.439800417984, 4897.118518666674, 5592.4966752990795, 6152.723127459186, 6175.9073613894925, 6189.907151392823, 4904.901901962012, 6099.237595300588, 6143.354947730602, 6169.413905678954, 6178.505719542516, 5854.619449735754, 6004.159417681615, 6016.789412370546, 6048.535298850849, 6084.11780291864, 6122.5408094392205, 6134.754609815557, 6144.354842741626, 6153.2616086068165, 6160.476359509313, 5870.647761134345, 5936.851578710394, 5951.91072133927, 5972.283326000907, 6031.095335699505], "value": 5647.841648059883, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [464.8396303275369, 485.7352742755198, 489.56673344090467, 510.18879980830474, 524.1979770950446, 38409.80795096024, 39964.40053399201, 40146.59853401465, 42761.93095172622, 42840.78579607105, 41168.382474262835, 41939.37090943638, 41940.0, 41945.06384553082, 41961.79019104908, 9391.389559678617, 9599.568019439108, 9681.903180968206, 9719.46542940138, 9722.222222222204, 9479.099485548897, 9589.472579008152, 9593.66422175224, 9674.032596740304, 9677.467739274338, 9616.134547890688, 9654.662086826962, 9715.708528744113, 9727.951360243223, 9753.414795112281, 7484.812879677997, 8744.519051452167, 9032.68385606504, 9058.501782401965, 9683.467409292487, 9530.046995300483, 9550.37922535033, 9550.570224339921, 9651.855222171673, 9655.372400793925, 30869.067438593298, 30888.227794305098, 31067.0, 31150.0, 31170.844145779352, 3088.984555077233, 3213.4256845659197, 3999.7800120993334, 4039.939400908959, 4093.3246014407587, 29880.70119298801, 30186.245343866358, 30231.395372092436, 30563.847180764176, 30613.23466913345, 3173.4763763978917, 3208.7433005359517, 3218.3080637663015, 3250.1062207892783, 3306.7850589711593, 469626.0, 472410.0, 475365.0, 477469.612651938, 481402.0, 56195.71902140463, 56620.71689641525, 56622.0, 56628.58428539237, 56756.71621641865, 58916.82166356649, 59317.70341148267, 59598.0, 59733.402665973656, 59917.700411497666, 15092.849071509365, 15308.464203753038, 15369.692606147815, 15387.0, 15661.921690391475, 12205.633830985085, 12252.938735306267, 12255.693607659701, 12288.569900053455, 12304.692382690504, 14838.70322593542, 14860.92569537167, 14884.92557537227, 14913.552593422215, 15030.699386012218, 6309.769594928945, 6444.323346048691, 6501.674916254168, 6506.902396464056, 6565.967170164214, 855.7005048233069, 886.7738726624669, 925.7407925780792, 945.9054094590485, 1037.305005646219, 25296.494070118864, 25483.74516254851, 27147.592786108224, 28804.42391152207, 29819.552706709415, 1045.29442626227, 1053.6934201589972, 1067.5249513966367, 1076.278893141597, 1112.504935303782, 14636.560903172922, 14991.276003259547, 15465.845341546665, 15539.06765594068, 15560.677342425794, 640667.0, 700337.0, 706663.0, 714986.0, 899121.0, 56969.7151514248, 57605.0, 57672.42327576754, 57679.8464030717, 57764.71117644469, 1205.4033253539553, 1268.105985280375, 1354.4243696428978, 1372.6431127906817, 1397.9790303145462, 22983.540329193656, 26815.0, 29581.26046848846, 30229.546556801226, 30719.539206912363, 51307.48692513027, 51713.741431292605, 51848.0, 51941.22088168756, 51996.74001629968, 587282.0, 592241.0, 594825.0, 598242.0, 601854.0, 24601.138960136308, 24711.50576988414, 24839.627405589294, 24984.750152498244, 25093.74906250914, 43249.135017300105, 43689.12621747611, 44352.778236108614, 44399.55600443954, 44625.55374446214, 32739.0, 34738.131546711535, 36514.26971460609, 37428.43857342088, 37689.869303920925, 33748.66251337554, 36368.0, 36433.089172770895, 37220.62779372172, 37581.4362784553, 35035.0, 35717.82141089278, 36251.45622815713, 36285.0928726784, 36512.0, 31705.36589268248, 32778.344433111684, 32947.01158965235, 33069.0, 33208.33583328368, 23956.760432395455, 24325.75674243235, 24463.51072978495, 24566.877165614056, 25076.122335718163], "value": 19240.163267695883, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [295.41065574179515, 376.5613060784185, 378.0171553959705, 382.9578746337901, 402.01105281008716, 16546.007239565635, 20321.796782032176, 21103.47241318964, 21190.364289071353, 21425.57148857022, 20227.292044778435, 20333.08501117453, 20484.07821648026, 20596.073176707047, 20801.583968320625, 4424.8008839602135, 5595.132754423071, 5893.20441740366, 5894.0, 5921.703914804264, 4138.213739389522, 4348.043430445313, 5671.971640141794, 5858.238429004224, 6204.47261982732, 6402.807915762534, 6485.935140648604, 6559.836004099888, 6561.048647946045, 6585.0, 6278.37216278373, 6475.449586785082, 6496.9675151624415, 6513.967430162819, 6517.380848819378, 5425.6202065855405, 5541.113421852484, 5630.634008789416, 5635.633683810539, 5642.125470552071, 25594.616080758802, 26124.085657001913, 26157.869210653826, 26179.738202617922, 26218.34454138643, 2177.7604463509, 2405.7473965233576, 2618.6988496322974, 2678.9819868449863, 2681.611166380864, 15361.558263086357, 15791.526254212393, 15822.208889555477, 16007.599810004727, 16286.42997495094, 1858.4982054845116, 1868.439852722978, 1892.3944337811768, 1944.3000519812856, 1953.4627977306213, 95982.08035839314, 114827.0, 115767.42116289365, 117190.0, 117629.23556146664, 10567.735806604896, 10663.520141593646, 10744.194185436123, 10815.729606759895, 10837.891621083689, 13287.867121328663, 13307.800382994263, 13331.133476324008, 13354.198748075147, 13361.66595835112, 8192.795180120545, 8349.958250208832, 8366.456180348257, 8384.664613415394, 8454.61954212062, 6927.757528486479, 8347.874781878276, 8480.151984801594, 8495.023072346636, 8495.065542790288, 6398.488120950313, 6572.112764776694, 6573.276939536647, 6583.736650534021, 6621.602703837785, 1422.381264150098, 1565.8590726834639, 1658.477579562434, 1838.724191371305, 1879.5207222158535, 851.4891065360735, 871.5816408124009, 918.5807926753182, 997.6757553795046, 1027.337367398024, 17604.0, 17993.740438169465, 20170.89914550418, 20487.8975605121, 20683.0, 772.6252767407802, 934.9345545811864, 944.4805357053593, 951.1012093571646, 951.362587066667, 10663.306885052602, 10702.0, 10910.399928003877, 11087.0, 11102.500387482583, 532665.0, 533891.0, 557028.0, 574673.0, 578203.0, 125882.74117258894, 126081.0, 127978.0, 129934.35032824775, 131162.0, 962.7496850818698, 963.643451922793, 994.2195376629311, 994.796066806303, 994.9007593976703, 18276.81723182751, 18573.628527429646, 18718.438446846616, 18746.71879921775, 18747.43757687217, 51283.97432051413, 51749.48250517446, 52812.73593632161, 52996.205056923405, 53864.461355385945, 62656.0, 66744.3325566738, 72426.55146896926, 75020.24979750132, 75488.86766698395, 6212.751489940351, 6410.846047711457, 6765.627890466071, 6948.096747422803, 7150.320719531636, 9678.467684277432, 11223.10215182767, 12046.819297710366, 12489.064195049781, 12584.056195785535, 9897.960714124913, 10039.297249192487, 10370.844437333295, 10604.734881628021, 10902.781944361226, 8482.533460659477, 10272.640457583948, 10347.63783267582, 10641.521131549254, 10798.298110622783, 10878.728031798952, 11005.559777608805, 11041.39272340029, 11174.66476005721, 11278.210525263154, 7670.348020418327, 7724.382049435919, 7827.686892524235, 7855.646495907584, 7966.840663186588, 10700.732481688021, 10856.45717714127, 10864.674059778219, 10993.395363254778, 11009.954054364822], "value": 9508.341178128328, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1486.7323881701295, 1536.7157075940952, 1805.8284462976012, 1847.2333981397721, 1941.9029048547568, 7630.8855367169535, 7863.724769633064, 8334.70828521002, 8947.418417802839, 8969.730908072752, 8340.91659083409, 8607.827843443143, 8634.784130396727, 8724.73825785226, 8754.606042728077, 2334.1480359668726, 2366.0299277296313, 2369.7511761265077, 2373.311739595515, 2373.964390534143, 2275.2377953385635, 2322.547103314854, 2344.7186337639505, 2370.762923707624, 2373.6795532603137, 2100.390886642872, 2352.752960939102, 2362.137819695816, 2368.5854975379325, 2388.4984153327823, 2282.075759317479, 2289.3360925331635, 2332.4985128197436, 2343.8828058597087, 2350.647402889563, 2327.94180145496, 2328.092044102801, 2329.114936324186, 2337.6610391493136, 2342.6954495915593, 8733.344999125027, 8780.297576193952, 8814.647414103429, 8879.556022198864, 8882.42264252828, 5219.112750832368, 5337.852361742244, 5357.7589008494715, 5384.973075134639, 5395.946040539584, 3456.9654303456896, 3536.6816986471085, 3641.6904563112166, 3666.120131168527, 3681.300552894968, 1026.779242462874, 1057.5346847387202, 1065.8614380130534, 1072.51736718477, 1079.7678499122726, 11414.086873050219, 11452.71368215793, 11552.76894462106, 11553.94223028888, 11569.305841649528, 4524.276115821519, 4582.450105987236, 4783.354247176656, 4784.4497882743235, 4794.304825800239, 9813.460259685786, 9891.35706179096, 9899.901000989898, 9913.30606857513, 9916.851247231298, 5336.893262134813, 5372.0, 5380.273663055437, 5387.272718183073, 5397.35231772182, 4356.891077723082, 4525.909481810345, 4708.764561771966, 4875.414950205998, 4916.0905232532095, 3898.6881049516537, 3939.783311917872, 3940.231654827281, 4028.576999415078, 4071.4528479177725, 1289.509986205236, 1295.7431291647088, 1296.5526893221822, 1304.302198323892, 1309.0771006440436, 709.2729951799344, 867.3538214030461, 894.9149830766066, 902.1429641840278, 922.5848368234322, 5810.128480727924, 5851.297844258716, 5883.852903677357, 5884.205632239678, 6099.420555047263, 807.4832107451236, 907.8275127725711, 908.3913777768911, 928.3872644054892, 970.2722957781664, 3884.1260716338597, 3898.2593307272095, 3928.508936382952, 4017.13631569214, 4030.214108248922, 10404.947975260075, 10642.946785266178, 10662.987016233445, 10663.893361066444, 10665.413402262795, 10468.267221294436, 10487.0, 10532.999365060461, 10609.787804244026, 10661.413622250695, 6116.877662446726, 6704.698288577026, 7423.703051877865, 7820.960895195488, 8083.393745469113, 5206.140986737146, 5494.423085575957, 5555.638883472561, 5569.220309156803, 5588.552915766811, 9630.614775409185, 9634.277429192978, 9779.657711980046, 9882.950585247028, 9983.450910199717, 9728.75678108053, 9886.357386769838, 10006.44964526958, 10128.44293563861, 10143.898561014295, 6775.051492790914, 6996.090508233897, 7248.528845625017, 7370.594617295886, 7616.35261002821, 7159.57042577447, 8203.958980205061, 8291.461055031403, 8413.495190288602, 8429.87355189685, 7256.818579535343, 7389.963050184715, 7399.815004624712, 7451.478396512192, 7546.622668866645, 6164.907526387018, 7244.746433874789, 7267.454940879349, 7275.12698476165, 7328.816779580553, 6828.760993365208, 6852.451803855779, 6928.584284942919, 6938.410235130071, 6943.444524438134, 4915.8033678652455, 4959.975200123977, 5008.1235783736975, 5015.097282489041, 5113.079645663816, 4950.504949505164, 4994.550490455877, 5004.3994720633755, 5081.0, 5146.845594632167], "value": 4538.5749642992505, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [279.88384820299575, 288.8570157771903, 302.17958243369253, 308.61577336216413, 308.68822489285816, 4707.411573553306, 4952.236300411686, 5511.311086114236, 5599.580031497633, 5616.213730077792, 4528.20756367636, 4748.287756836477, 4865.635077369194, 5392.703401312936, 5531.723413829302, 4155.522114956773, 4204.0, 4217.692515320246, 4224.493060832704, 4245.745255284693, 4230.788460576959, 4243.809028593705, 4251.128518653684, 4266.274733295331, 4269.907745204859, 3517.437210046393, 4024.1549274652357, 4136.627703506684, 4156.944645685418, 4202.403086827001, 4097.938530922039, 4132.669386449107, 4135.462389889295, 4142.171565686875, 4154.605312495308, 2993.835339056351, 3571.214392803598, 3604.27914417115, 3630.8547658093644, 3634.182308980485, 5150.639455238135, 5323.733813309319, 5403.351597808249, 5404.865078294052, 5450.727463626842, 4678.625709943196, 4726.125666751627, 4741.933065060334, 4743.525647435229, 4785.545373189541, 4618.584327410549, 4932.125792198942, 5015.498450154992, 5749.88500229993, 5852.9807216510235, 3033.3933213357195, 3824.388097904322, 3849.711271654637, 3876.205377897524, 3880.9223815523815, 5264.6051546134195, 5280.577553795687, 6030.532443844807, 6135.9079613805825, 6181.81454556364, 3414.975507347786, 3783.6027217142355, 3830.751001184914, 3904.511936007999, 3911.9608803912165, 3271.9672803272138, 3742.933264019796, 4023.6982226332557, 4028.939565906514, 4030.778307193074, 3538.203904121552, 4271.273883439822, 4324.437823083042, 4441.822916927017, 4723.385959825201, 5540.168974653834, 5608.9719551402795, 5818.883622327529, 5828.213191219216, 5899.734511946973, 1165.7959857024987, 1343.2343564168525, 1362.0, 1363.2502123831853, 1366.3782978744648, 778.7073458059707, 815.3151352863623, 820.4216027700576, 833.3208435125354, 840.4159109419018, 3978.562358140602, 4049.4612047421792, 4571.38286331341, 4620.099080679235, 4651.0, 4691.366665500182, 4881.973176630512, 5015.238571071696, 5047.343845300087, 5075.512998630645, 3776.678982286536, 4117.608827161415, 4174.91650167001, 4580.519045500175, 4653.0, 2287.485315803931, 2408.65197375648, 2647.907323243716, 2654.5665340715796, 2657.3223827923757, 4420.425344705168, 4758.476567577563, 4779.828941909254, 5185.770118848931, 5206.193040078794, 4501.167284052461, 4559.6124329431705, 4880.755962201875, 4926.827561035347, 5079.034983353225, 4190.177272886304, 4611.792469338888, 4727.338172655762, 5018.0, 5235.507880254109, 4502.864914052584, 4877.292792545132, 5060.026589629973, 5067.645264831426, 5091.796328146833, 4520.88697782558, 4597.747123908083, 4645.767711614473, 4664.344157824059, 4946.0, 4426.896035462022, 4718.8584342469785, 5033.515113041734, 5214.9400986610835, 6002.0, 3784.791836449022, 3888.9416658750706, 3892.5134358205223, 3925.2345792570177, 3929.60294220531, 5883.764409474058, 5980.910286345621, 6016.87966240664, 6026.306974697965, 6093.542984276109, 5467.863303417446, 5791.681457519877, 5801.027650598817, 5849.590528662953, 5892.5285977122585, 5226.63413561047, 5674.460926212003, 5861.3366645846345, 5871.412858714094, 5908.3205431375945, 5106.234064890296, 5449.583108391767, 5792.652440853562, 5808.128780682931, 5848.327442344185, 5396.541293990055, 5564.831385408947, 5839.824805255849, 5887.410399192279, 5895.7052147393315, 5792.247007888947, 5793.710314484175, 5801.970990145022, 5810.82567522975, 5857.26784151981], "value": 3975.3915414958246, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [655.4330504113943, 668.9431398331142, 724.3878922310646, 772.8570214510313, 817.3338728935919, 12995.0, 16207.756883646754, 16211.351545938178, 16216.918915405437, 16254.187290635466, 14754.26228688564, 14894.776578351306, 14920.477783277587, 14930.701385972301, 14965.476208332711, 704.844934114494, 724.7861880745166, 872.7469033980154, 873.7466134820894, 891.6210610490548, 597.1043434847728, 668.318315318375, 899.3794281945469, 903.20969151992, 919.6045700348848, 824.6618886256606, 910.4400793511976, 961.7403301108696, 962.7111866440075, 963.1572374172599, 894.369469523984, 928.7167413938746, 954.8376775948071, 956.7034219391941, 958.5925981457888, 705.9869087858939, 709.9432045436405, 710.7725527831096, 712.5688958180278, 714.2857142857143, 14621.0, 14877.7768333474, 15006.549803505912, 15031.549053528302, 15049.247537623076, 14472.710545789025, 14591.854081459156, 14628.122312661275, 14678.779818302733, 14754.63113422173, 11768.58809941648, 11932.343721095336, 12200.755984880341, 12250.142490025703, 12480.375981200903, 3756.398976163828, 3765.981170094132, 3958.8614398495915, 3992.7803970781597, 3996.6003399660094, 122387.0, 122686.0, 123079.0, 123858.38070809678, 124382.0, 11275.943620281847, 11293.491792869341, 11344.829827552428, 11386.829197562043, 11783.234089784137, 23702.762972370056, 23764.04943802263, 23819.166329178395, 23860.64209036866, 23906.52186956251, 7872.094709108412, 7925.405594580429, 7968.601569921597, 8089.474184178127, 8233.094359620436, 4340.131973605292, 4362.105768317488, 4433.0, 4455.821767129279, 4543.750093744876, 3580.5166302548823, 3688.299223147647, 3696.537932758405, 3704.9629503705155, 3811.5426148862316, 696.251529605676, 700.9053777740041, 717.1465955512923, 733.2117973178854, 735.2977906099769, 607.6870411737958, 643.8068579426154, 649.2922714241499, 675.5102550650837, 683.962382068981, 7027.578345299298, 7904.263275991025, 9493.525323733924, 9627.277954153467, 9811.411315321104, 623.5292354272495, 660.5673283998989, 675.1054852320597, 687.9489464226173, 711.4735096029004, 4089.9795501022304, 4534.682572219913, 4645.024544845553, 4777.235642297216, 4815.3017812417, 137332.31333843467, 157812.42187577978, 158753.2062339704, 160990.0, 162275.0, 113660.0, 123959.0, 125356.0, 130742.9999999981, 142299.0, 78625.82061269086, 109208.0, 111996.0, 114007.0, 114592.0, 8373.413861029669, 8467.280281176169, 8979.0, 9960.55177517028, 10029.348092373972, 43281.56718432901, 49502.257466137315, 59853.0, 60795.0, 60908.390916092045, 85016.5749171275, 86263.0, 86920.1307986912, 87630.56184719036, 87702.56148719216, 10646.89353106459, 13017.284049377375, 13254.337283135612, 13379.197248165141, 13479.32603369808, 14742.273155727453, 14924.85075149278, 15052.397904083715, 15311.923440382727, 15636.92181539085, 13371.0, 13797.034207605371, 13826.170429774245, 14190.929045354707, 14468.760855540835, 13757.518486852912, 13886.375113120135, 13903.026788124735, 14092.929535352258, 14502.782458262924, 13417.092447708937, 13617.251051191883, 13657.590272291847, 13668.248246346546, 13777.862221377658, 11270.94364528172, 11384.772304553697, 11406.486708098322, 11421.714457138638, 11568.363739994382, 8870.778230544289, 10269.948650257, 11236.438178091226, 11622.709432263922, 11808.940955295513], "value": 7917.749061679077, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1608.4999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [103.0, 121.0, 118.0, 117.0, 118.0, 130.0, 169.0, 162.0, 120.0, 118.0], "value": 127.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [182.0, 171.0, 137.0, 152.0, 117.0, 116.0, 116.0, 144.0, 132.0, 132.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [226.0, 209.0, 197.0, 200.0, 195.0, 228.0, 260.0, 195.0, 194.0, 192.0], "value": 209.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 56.0, 72.0, 81.0, 52.0, 51.0, 58.0, 69.0, 103.0, 84.0], "value": 70.8, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [110.0, 70.0, 71.0, 59.0, 72.0, 89.0, 121.0, 101.0, 94.0, 86.0], "value": 87.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 86.0, 89.0, 86.0, 103.0, 85.0, 89.0, 86.0, 98.0, 86.0], "value": 89.7, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [121.0, 131.0, 102.0, 149.0, 150.0, 103.0, 149.0, 94.0, 95.0, 96.0], "value": 119.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 76.0, 135.0, 134.0, 89.0, 93.0, 135.0, 90.0, 74.0, 133.0], "value": 105.5, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 55.0, 56.0, 58.0, 57.0, 58.0, 57.0, 64.0, 77.0, 89.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 71.0, 80.0, 79.0, 75.0, 64.0, 67.0, 69.0, 72.0, 72.0], "value": 72.0, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [112.0, 110.0, 111.0, 85.0, 88.0, 86.0, 92.0, 90.0, 90.0, 93.0], "value": 95.7, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 176.0, 157.0, 149.0, 162.0, 155.0, 164.0, 150.0, 184.0, 148.0], "value": 158.6, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [176.0, 180.0, 201.0, 237.0, 190.0, 200.0, 176.0, 211.0, 166.0, 158.0], "value": 189.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 71.0, 73.0, 74.0, 97.0, 88.0, 91.0, 92.0, 79.0, 72.0], "value": 80.6, "unit": "score"}]}]} 10:58:30 INFO - Return code: 0 10:58:30 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 10:58:30 ERROR - # TBPL SUCCESS # 10:58:30 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 10:58:30 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 10:58:30 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 10:58:30 INFO - 2016-10-20 10:58:30.727 screenresolution[1668:10375] starting screenresolution argv=screenresolution get 10:58:30 INFO - 2016-10-20 10:58:30.730 screenresolution[1668:10375] Display 0: 1600x1200x32@60 10:58:30 INFO - 2016-10-20 10:58:30.736 screenresolution[1669:10378] starting screenresolution argv=screenresolution list 10:58:30 INFO - Available Modes on Display 0 10:58:30 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 10:58:30 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 10:58:30 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 10:58:30 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 10:58:30 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 10:58:30 INFO - Intel Iris: 10:58:30 INFO - Chipset Model: Intel Iris 10:58:30 INFO - Type: GPU 10:58:30 INFO - Bus: Built-In 10:58:30 INFO - VRAM (Dynamic, Max): 1536 MB 10:58:30 INFO - Vendor: Intel (0x8086) 10:58:30 INFO - Device ID: 0x0a2e 10:58:30 INFO - Revision ID: 0x0009 10:58:30 INFO - Displays: 10:58:30 INFO - Display: 10:58:30 INFO - Resolution: 1600 x 1200 @ 60 Hz 10:58:30 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 10:58:30 INFO - Display Serial Number: 1600x1200 60 10:58:30 INFO - Main Display: Yes 10:58:30 INFO - Mirror: Off 10:58:30 INFO - Online: Yes 10:58:30 INFO - Rotation: Supported 10:58:30 INFO - Return code: 0 10:58:30 INFO - Running post-action listener: _resource_record_post_action 10:58:30 INFO - [mozharness: 2016-10-20 17:58:30.972832Z] Finished run-tests step (success) 10:58:30 INFO - Running post-run listener: _resource_record_post_run 10:58:31 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 39369216; Write bytes: 709541376; Read time: 386; Write time: 2024 10:58:31 INFO - TinderboxPrint: CPU usage
54.6% 10:58:31 INFO - TinderboxPrint: I/O read bytes / time
39,369,216 / 386 10:58:31 INFO - TinderboxPrint: I/O write bytes / time
709,541,376 / 2,024 10:58:31 INFO - TinderboxPrint: CPU idle
2,201.3 (45.4%) 10:58:31 INFO - TinderboxPrint: CPU system
983.6 (20.3%) 10:58:31 INFO - TinderboxPrint: CPU user
1,664.8 (34.3%) 10:58:31 INFO - TinderboxPrint: Swap in / out
445,698,048 / 0 10:58:31 INFO - install - Wall time: 35s; CPU: 54.0%; Read bytes: 407214592; Write bytes: 406382592; Read time: 30382; Write time: 784 10:58:31 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 35396096; Write bytes: 298063360; Read time: 353; Write time: 1216 10:58:31 INFO - Running post-run listener: _upload_blobber_files 10:58:31 INFO - Blob upload gear active. 10:58:31 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 10:58:31 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 10:58:31 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 10:58:31 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 10:58:31 INFO - (blobuploader) - INFO - Open directory for files ... 10:58:31 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 10:58:31 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:31 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:32 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 10:58:32 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:32 INFO - (blobuploader) - INFO - Done attempting. 10:58:32 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 10:58:32 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:32 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:33 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 10:58:33 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:33 INFO - (blobuploader) - INFO - Done attempting. 10:58:33 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 10:58:33 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 10:58:33 INFO - (blobuploader) - INFO - Uploading, attempt #1. 10:58:35 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 10:58:35 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 10:58:35 INFO - (blobuploader) - INFO - Done attempting. 10:58:35 INFO - (blobuploader) - INFO - Iteration through files over. 10:58:35 INFO - Return code: 0 10:58:35 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 10:58:35 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 10:58:35 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c1cc50361ae2c537c05a57f37130429af4efbb6c961d5bcbbb74c7f923031aa299ac466234e6c9da0ca57cec252252cb784b3ce379f28e74238408f81c8de40", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11275a6a8e0e3b437de24fa486e4bd4a65bc930550552209a76f5c48dea8b65f7241ea4ad0b0bc6566414fe0da99c9f67176f866cdeb6c20a7537dcade9f7427", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57f8a76bcb04dfb46bb7a52c3bba3d9a3a7bd13ce0445c42d822cc3d9793d4dcc8c8baf5992bec0f2bd30094220a7e8c5d504cb53337e928c827fa42f6eae3c8"} 10:58:35 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 10:58:35 INFO - Writing to file /builds/slave/test/properties/blobber_files 10:58:35 INFO - Contents: 10:58:35 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c1cc50361ae2c537c05a57f37130429af4efbb6c961d5bcbbb74c7f923031aa299ac466234e6c9da0ca57cec252252cb784b3ce379f28e74238408f81c8de40", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11275a6a8e0e3b437de24fa486e4bd4a65bc930550552209a76f5c48dea8b65f7241ea4ad0b0bc6566414fe0da99c9f67176f866cdeb6c20a7537dcade9f7427", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57f8a76bcb04dfb46bb7a52c3bba3d9a3a7bd13ce0445c42d822cc3d9793d4dcc8c8baf5992bec0f2bd30094220a7e8c5d504cb53337e928c827fa42f6eae3c8"} 10:58:35 INFO - Running post-run listener: copy_logs_to_upload_dir 10:58:35 INFO - Copying logs to upload dir... 10:58:35 INFO - mkdir: /builds/slave/test/build/upload/logs 10:58:35 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1272.618257 ========= master_lag: 0.98 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 13 secs) (at 2016-10-20 10:58:36.681050) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 10:58:36.684639) ========= 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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c1cc50361ae2c537c05a57f37130429af4efbb6c961d5bcbbb74c7f923031aa299ac466234e6c9da0ca57cec252252cb784b3ce379f28e74238408f81c8de40", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11275a6a8e0e3b437de24fa486e4bd4a65bc930550552209a76f5c48dea8b65f7241ea4ad0b0bc6566414fe0da99c9f67176f866cdeb6c20a7537dcade9f7427", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57f8a76bcb04dfb46bb7a52c3bba3d9a3a7bd13ce0445c42d822cc3d9793d4dcc8c8baf5992bec0f2bd30094220a7e8c5d504cb53337e928c827fa42f6eae3c8"} build_url:https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010941 build_url: 'https://queue.taskcluster.net/v1/task/GlaGsXpyThqJjMPxVx0Qog/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0c1cc50361ae2c537c05a57f37130429af4efbb6c961d5bcbbb74c7f923031aa299ac466234e6c9da0ca57cec252252cb784b3ce379f28e74238408f81c8de40", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11275a6a8e0e3b437de24fa486e4bd4a65bc930550552209a76f5c48dea8b65f7241ea4ad0b0bc6566414fe0da99c9f67176f866cdeb6c20a7537dcade9f7427", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/57f8a76bcb04dfb46bb7a52c3bba3d9a3a7bd13ce0445c42d822cc3d9793d4dcc8c8baf5992bec0f2bd30094220a7e8c5d504cb53337e928c827fa42f6eae3c8"}' ========= master_lag: 0.03 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-20 10:58:36.721736) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:58:36.722574) ========= 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.ZnrubcEdCK/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.gaR8HlpRh0/Listeners TMPDIR=/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/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.005057 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-20 10:58:36.774054) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 10:58:36.774492) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-20 10:58:36.774953) ========= ========= Total master_lag: 1.12 =========