builder: autoland_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0087 starttime: 1476876853.52 results: success (0) buildid: 20161019025422 builduid: f4e8f33cad2848949391f1a61231a29a revision: 9efcbf2796dee874ac5754cf6adf1e47b2ff395c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:13.519449) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:13.519839) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:13.520147) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:13.630235) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:13.630526) ========= 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.W2uPWnqkIu/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.QYBq22X9jT/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-19 04:34:13-- 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% 8.57M=0.001s 2016-10-19 04:34:14 (8.57 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.663857 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.315850) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.316202) ========= 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.W2uPWnqkIu/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.QYBq22X9jT/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.019240 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.365222) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.365565) ========= bash -c 'python archiver_client.py mozharness --repo integration/autoland --rev 9efcbf2796dee874ac5754cf6adf1e47b2ff395c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo integration/autoland --rev 9efcbf2796dee874ac5754cf6adf1e47b2ff395c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W2uPWnqkIu/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.QYBq22X9jT/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-19 04:34:14,439 truncating revision to first 12 chars 2016-10-19 04:34:14,439 Setting DEBUG logging. 2016-10-19 04:34:14,439 attempt 1/10 2016-10-19 04:34:14,439 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/integration/autoland/9efcbf2796de?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-10-19 04:34:14,777 unpacking tar archive at: autoland-9efcbf2796de/testing/mozharness/ program finished with exit code 0 elapsedTime=0.589148 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.980279) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.980972) ========= script_repo_revision: 9efcbf2796dee874ac5754cf6adf1e47b2ff395c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.981908) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:14.982514) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-10-19 04:34:15.001837) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-19 04:34:15.002178) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 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', 'Autoland', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Autoland'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:15 INFO - MultiFileLogger online at 20161019 04:34:15 in /builds/slave/test 04:34:15 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Autoland --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Autoland 04:34:15 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:34:15 INFO - {'append_to_log': False, 04:34:15 INFO - 'base_work_dir': '/builds/slave/test', 04:34:15 INFO - 'blob_upload_branch': 'Autoland', 04:34:15 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:34:15 INFO - 'branch': 'Autoland', 04:34:15 INFO - 'buildbot_json_path': 'buildprops.json', 04:34:15 INFO - 'config_files': ('talos/mac_config.py',), 04:34:15 INFO - 'default_actions': ('clobber', 04:34:15 INFO - 'read-buildbot-config', 04:34:15 INFO - 'download-and-extract', 04:34:15 INFO - 'populate-webroot', 04:34:15 INFO - 'create-virtualenv', 04:34:15 INFO - 'install', 04:34:15 INFO - 'run-tests'), 04:34:15 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:34:15 INFO - 'download_minidump_stackwalk': True, 04:34:15 INFO - 'download_symbols': 'ondemand', 04:34:15 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:34:15 INFO - 'tooltool.py': '/tools/tooltool.py', 04:34:15 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:34:15 INFO - '/tools/misc-python/virtualenv.py')}, 04:34:15 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:34:15 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:34:15 INFO - 'installer_path': 'installer.exe', 04:34:15 INFO - 'log_level': 'info', 04:34:15 INFO - 'log_name': 'talos', 04:34:15 INFO - 'log_to_console': True, 04:34:15 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 04:34:15 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 04:34:15 INFO - 'opt_config_files': (), 04:34:15 INFO - 'pip_index': False, 04:34:15 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:34:15 INFO - 'cmd': ('bash', 04:34:15 INFO - '-c', 04:34:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:34:15 INFO - 'enabled': True, 04:34:15 INFO - 'halt_on_failure': False, 04:34:15 INFO - 'name': 'check_screen_resolution'},), 04:34:15 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 04:34:15 INFO - 'cmd': ('bash', 04:34:15 INFO - '-c', 04:34:15 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 04:34:15 INFO - 'enabled': True, 04:34:15 INFO - 'halt_on_failure': False, 04:34:15 INFO - 'name': 'check_screen_resolution'},), 04:34:15 INFO - 'run_cmd_checks_enabled': True, 04:34:15 INFO - 'sps_profile': False, 04:34:15 INFO - 'sps_profile_interval': 0, 04:34:15 INFO - 'suite': 'dromaeojs-e10s', 04:34:15 INFO - 'system_bits': '32', 04:34:15 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:34:15 INFO - 'title': 't-yosemite-r7-0087', 04:34:15 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:34:15 INFO - 'use_talos_json': True, 04:34:15 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:34:15 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:34:15 INFO - 'work_dir': 'build'} 04:34:15 INFO - [mozharness: 2016-10-19 11:34:15.205610Z] Running clobber step. 04:34:15 INFO - Running pre-action listener: _resource_record_pre_action 04:34:15 INFO - Running main action method: clobber 04:34:15 INFO - rmtree: /builds/slave/test/build 04:34:15 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:34:16 INFO - Running post-action listener: _resource_record_post_action 04:34:16 INFO - [mozharness: 2016-10-19 11:34:16.838591Z] Finished clobber step (success) 04:34:16 INFO - [mozharness: 2016-10-19 11:34:16.838749Z] Running read-buildbot-config step. 04:34:16 INFO - Running pre-action listener: _resource_record_pre_action 04:34:16 INFO - Running main action method: read_buildbot_config 04:34:16 INFO - Using buildbot properties: 04:34:16 INFO - { 04:34:16 INFO - "project": "", 04:34:16 INFO - "product": "firefox", 04:34:16 INFO - "script_repo_revision": "production", 04:34:16 INFO - "builddir": "autoland_yosemite_r7_test-dromaeojs-e10s", 04:34:16 INFO - "repository": "", 04:34:16 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 autoland talos dromaeojs-e10s", 04:34:16 INFO - "buildid": "20161019025422", 04:34:16 INFO - "pgo_build": "False", 04:34:16 INFO - "basedir": "/builds/slave/test", 04:34:16 INFO - "buildnumber": 51, 04:34:16 INFO - "slavename": "t-yosemite-r7-0087", 04:34:16 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 04:34:16 INFO - "platform": "yosemite_r7", 04:34:16 INFO - "scheduler": "tests-autoland-macosx64-talos-8-3600", 04:34:16 INFO - "branch": "autoland", 04:34:16 INFO - "repo_path": "integration/autoland", 04:34:16 INFO - "revision": "9efcbf2796dee874ac5754cf6adf1e47b2ff395c", 04:34:16 INFO - "stage_platform": "macosx64", 04:34:16 INFO - "builduid": "f4e8f33cad2848949391f1a61231a29a", 04:34:16 INFO - "slavebuilddir": "test" 04:34:16 INFO - } 04:34:16 INFO - Found installer url https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg. 04:34:16 INFO - Running post-action listener: _resource_record_post_action 04:34:16 INFO - [mozharness: 2016-10-19 11:34:16.842211Z] Finished read-buildbot-config step (success) 04:34:16 INFO - [mozharness: 2016-10-19 11:34:16.842332Z] Running download-and-extract step. 04:34:16 INFO - Running pre-action listener: _resource_record_pre_action 04:34:16 INFO - Running main action method: download_and_extract 04:34:16 INFO - mkdir: /builds/slave/test/build/tests 04:34: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')]} 04:34:16 INFO - trying https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.test_packages.json 04:34:16 INFO - Downloading https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:34:16 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:34:18 INFO - Downloaded 1199 bytes. 04:34:18 INFO - Reading from file /builds/slave/test/build/firefox-52.0a1.en-US.mac.test_packages.json 04:34:18 INFO - Using the following test package requirements: 04:34:18 INFO - {u'common': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:34:18 INFO - u'cppunittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.cppunittest.tests.zip'], 04:34:18 INFO - u'gtest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.gtest.tests.zip'], 04:34:18 INFO - u'jittest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'jsshell-mac.zip'], 04:34:18 INFO - u'mochitest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.mochitest.tests.zip'], 04:34:18 INFO - u'mozbase': [u'firefox-52.0a1.en-US.mac.common.tests.zip'], 04:34:18 INFO - u'reftest': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.reftest.tests.zip'], 04:34:18 INFO - u'talos': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.talos.tests.zip'], 04:34:18 INFO - u'web-platform': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.web-platform.tests.zip'], 04:34:18 INFO - u'xpcshell': [u'firefox-52.0a1.en-US.mac.common.tests.zip', 04:34:18 INFO - u'firefox-52.0a1.en-US.mac.xpcshell.tests.zip']} 04:34:18 INFO - Downloading packages: [u'firefox-52.0a1.en-US.mac.common.tests.zip'] for test suite category: common 04:34:18 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:34:18 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:34:18 INFO - Fetch https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:34:18 INFO - Http code: 200 04:34:18 INFO - via: 1.1 7f9e159e8ac7593f5d3d995abc6d2db3.cloudfront.net (CloudFront) 04:34:18 INFO - x-amz-cf-id: L25BFZB6XTrDC5z1vUhxU5dV8auI5eJSxMhPUGXX_PL4EgS1LNE5dw== 04:34:18 INFO - x-amz-version-id: 53gD3w0uyNhfxBdZaSJhUhvgT0CZjxHN 04:34:19 INFO - Expected file size: 16499876 04:34:19 INFO - Obtained file size: 16499876 04:34:26 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 04:34:26 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip 04:34:26 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip'}, attempt #1 04:34:26 INFO - Fetch https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.common.tests.zip into memory 04:34:26 INFO - Http code: 200 04:34:26 INFO - via: 1.1 f1a40337a32137e1c23ceffead6a50d5.cloudfront.net (CloudFront) 04:34:26 INFO - x-amz-cf-id: ZORxzR8TLVn6ExKaxkIGXuipZeHAvme3d6IFTQoF1md8pQnlS3QUGA== 04:34:26 INFO - x-amz-version-id: 53gD3w0uyNhfxBdZaSJhUhvgT0CZjxHN 04:34:27 INFO - Expected file size: 16499876 04:34:27 INFO - Obtained file size: 16499876 04:34:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip 04:34:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip'}, attempt #1 04:34:33 INFO - Fetch https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.talos.tests.zip into memory 04:34:34 INFO - Http code: 200 04:34:34 INFO - via: 1.1 a75342fa94ff4a9431476624fe74f02e.cloudfront.net (CloudFront) 04:34:34 INFO - x-amz-cf-id: 0ZOLIro6XKNzmhEdyilzQCAVdQSnEzFjSBG8G3cwBPG_1TRPynBviQ== 04:34:34 INFO - x-amz-version-id: QRJU6wOtQ54ptKTdIUTwR1ESSW0JmDm0 04:34:34 INFO - Expected file size: 14409028 04:34:34 INFO - Obtained file size: 14409028 04:34:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:34 INFO - trying https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:34:34 INFO - Downloading https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-52.0a1.en-US.mac.dmg 04:34:34 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:34:38 INFO - Downloaded 111655738 bytes. 04:34:38 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:34:38 INFO - mkdir: /builds/slave/test/properties 04:34:38 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:34:38 INFO - Writing to file /builds/slave/test/properties/build_url 04:34:38 INFO - Contents: 04:34:38 INFO - build_url:https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg 04:34:39 INFO - Running post-action listener: _resource_record_post_action 04:34:39 INFO - Running post-action listener: set_extra_try_arguments 04:34:39 INFO - [mozharness: 2016-10-19 11:34:39.684229Z] Finished download-and-extract step (success) 04:34:39 INFO - [mozharness: 2016-10-19 11:34:39.684357Z] Running populate-webroot step. 04:34:39 INFO - Running pre-action listener: _resource_record_pre_action 04:34:39 INFO - Running main action method: populate_webroot 04:34:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:34:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:34:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:34:39 INFO - '--tppagecycles', 04:34:39 INFO - '7'], 04:34:39 INFO - 'tests': ['tsvgm']}}, 04:34:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:34:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:34:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:34:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:34:39 INFO - 'g1': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:34:39 INFO - 'g1-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:34:39 INFO - 'g2': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['damp', 'tps']}, 04:34:39 INFO - 'g2-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'tests': ['damp', 'tps']}, 04:34:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['dromaeo_dom']}, 04:34:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:34:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['basic_compositor_video']}, 04:34:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:34:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'other-e10s': {'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['a11yr', 04:34:39 INFO - 'ts_paint', 04:34:39 INFO - 'tpaint', 04:34:39 INFO - 'sessionrestore', 04:34:39 INFO - 'sessionrestore_no_auto_restore', 04:34:39 INFO - 'tabpaint']}, 04:34:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['tsvgx', 04:34:39 INFO - 'tsvgr_opacity', 04:34:39 INFO - 'tart', 04:34:39 INFO - 'tscrollx', 04:34:39 INFO - 'cart']}, 04:34:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:34:39 INFO - 'tsvgr_opacity', 04:34:39 INFO - 'tart', 04:34:39 INFO - 'tscrollx', 04:34:39 INFO - 'cart']}, 04:34:39 INFO - 'tp5o': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'talos_options': ['--disable-e10s'], 04:34:39 INFO - 'tests': ['tp5o']}, 04:34:39 INFO - 'tp5o-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'tests': ['tp5o']}, 04:34:39 INFO - 'xperf': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'talos_options': ['--disable-e10s', 04:34:39 INFO - '--xperf_path', 04:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:34:39 INFO - 'tests': ['tp5n']}, 04:34:39 INFO - 'xperf-e10s': {'pagesets_url': 'http://talos-bundles.pvt.build.mozilla.org/zips/tp5n.zip', 04:34:39 INFO - 'talos_options': ['--xperf_path', 04:34:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:34:39 INFO - 'tests': ['tp5n']}}, 04:34:39 INFO - 'talos.zip': {'path': '', 04:34:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:34:39 INFO - Running post-action listener: _resource_record_post_action 04:34:39 INFO - [mozharness: 2016-10-19 11:34:39.702909Z] Finished populate-webroot step (success) 04:34:39 INFO - [mozharness: 2016-10-19 11:34:39.703044Z] Running create-virtualenv step. 04:34:39 INFO - Running pre-action listener: _resource_record_pre_action 04:34:39 INFO - Running main action method: create_virtualenv 04:34:39 INFO - Creating virtualenv /builds/slave/test/build/venv 04:34:39 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 04:34:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:34:39 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:34:39 INFO - Using real prefix '/tools/python27' 04:34:39 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:34:40 INFO - Installing distribute.............................................................................................................................................................................................done. 04:34:43 INFO - Installing pip.................done. 04:34:43 INFO - Return code: 0 04:34:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:34:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:34:43 INFO - Reading from file tmpfile_stdout 04:34:43 INFO - Output received: 04:34:43 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) 04:34:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:34:43 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')]} 04:34:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:43 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')]} 04:34:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:43 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:43 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 04:34:43 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 04:34:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:43 INFO - 'HOME': '/Users/cltbld', 04:34:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:43 INFO - 'LOGNAME': 'cltbld', 04:34:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:43 INFO - 'MOZ_NO_REMOTE': '1', 04:34:43 INFO - 'NO_EM_RESTART': '1', 04:34:43 INFO - 'PAGER': '/bin/cat', 04:34:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:43 INFO - 'PWD': '/builds/slave/test', 04:34:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:43 INFO - 'SHELL': '/bin/bash', 04:34:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:43 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:43 INFO - 'USER': 'cltbld', 04:34:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:43 INFO - 'XPC_FLAGS': '0x0', 04:34:43 INFO - 'XPC_SERVICE_NAME': '0', 04:34:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:44 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 04:34:44 INFO - Cleaning up... 04:34:44 INFO - Return code: 0 04:34:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:44 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:44 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 04:34:44 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 04:34:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:44 INFO - 'HOME': '/Users/cltbld', 04:34:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:44 INFO - 'LOGNAME': 'cltbld', 04:34:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:44 INFO - 'MOZ_NO_REMOTE': '1', 04:34:44 INFO - 'NO_EM_RESTART': '1', 04:34:44 INFO - 'PAGER': '/bin/cat', 04:34:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:44 INFO - 'PWD': '/builds/slave/test', 04:34:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:44 INFO - 'SHELL': '/bin/bash', 04:34:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:44 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:44 INFO - 'USER': 'cltbld', 04:34:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:44 INFO - 'XPC_FLAGS': '0x0', 04:34:44 INFO - 'XPC_SERVICE_NAME': '0', 04:34:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:44 INFO - Downloading/unpacking psutil>=3.1.1 04:34:44 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 04:34:44 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 04:34:44 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 04:34:44 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 04:34:44 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 04:34:44 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 04:34:48 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:34:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:34:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:34:48 INFO - Installing collected packages: psutil 04:34:48 INFO - Running setup.py install for psutil 04:34:48 INFO - building 'psutil._psutil_osx' extension 04:34:48 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 04:34:48 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 04:34:48 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 04:34:48 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 04:34:48 INFO - building 'psutil._psutil_posix' extension 04:34:48 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 04:34:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 04:34:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 04:34:48 INFO - ^ 04:34:48 INFO - 1 warning generated. 04:34:48 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 04:34:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:34:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:34:49 INFO - Successfully installed psutil 04:34:49 INFO - Cleaning up... 04:34:49 INFO - Return code: 0 04:34:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:34:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:34:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:49 INFO - 'HOME': '/Users/cltbld', 04:34:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:49 INFO - 'LOGNAME': 'cltbld', 04:34:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:49 INFO - 'MOZ_NO_REMOTE': '1', 04:34:49 INFO - 'NO_EM_RESTART': '1', 04:34:49 INFO - 'PAGER': '/bin/cat', 04:34:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:49 INFO - 'PWD': '/builds/slave/test', 04:34:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:49 INFO - 'SHELL': '/bin/bash', 04:34:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:49 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:49 INFO - 'USER': 'cltbld', 04:34:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:49 INFO - 'XPC_FLAGS': '0x0', 04:34:49 INFO - 'XPC_SERVICE_NAME': '0', 04:34:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:34:49 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 04:34:49 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 04:34:49 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 04:34:49 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 04:34:49 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 04:34:49 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 04:34:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:34:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:34:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:34:52 INFO - Installing collected packages: mozsystemmonitor 04:34:52 INFO - Running setup.py install for mozsystemmonitor 04:34:52 INFO - Successfully installed mozsystemmonitor 04:34:52 INFO - Cleaning up... 04:34:52 INFO - Return code: 0 04:34:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:34: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')]} 04:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34: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')]} 04:34:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:52 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:52 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 04:34:52 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 04:34:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:52 INFO - 'HOME': '/Users/cltbld', 04:34:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:52 INFO - 'LOGNAME': 'cltbld', 04:34:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:52 INFO - 'MOZ_NO_REMOTE': '1', 04:34:52 INFO - 'NO_EM_RESTART': '1', 04:34:52 INFO - 'PAGER': '/bin/cat', 04:34:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:52 INFO - 'PWD': '/builds/slave/test', 04:34:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:52 INFO - 'SHELL': '/bin/bash', 04:34:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:52 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:52 INFO - 'USER': 'cltbld', 04:34:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:52 INFO - 'XPC_FLAGS': '0x0', 04:34:52 INFO - 'XPC_SERVICE_NAME': '0', 04:34:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:52 INFO - Downloading/unpacking jsonschema==2.5.1 04:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:34:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:34: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 04:34: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 04:34: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 04:34: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 04:34: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 04:34: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 04:34:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:34:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:34:55 INFO - warning: no files found matching '*.txt' 04:34:55 INFO - no previously-included directories found matching 'build' 04:34:55 INFO - no previously-included directories found matching 'dist' 04:34:55 INFO - no previously-included directories found matching '.git*' 04:34:55 INFO - Installing collected packages: jsonschema, functools32 04:34:55 INFO - Running setup.py install for functools32 04:34:55 INFO - warning: no files found matching '*.txt' 04:34:55 INFO - no previously-included directories found matching 'build' 04:34:55 INFO - no previously-included directories found matching 'dist' 04:34:55 INFO - no previously-included directories found matching '.git*' 04:34:55 INFO - Successfully installed jsonschema functools32 04:34:55 INFO - Cleaning up... 04:34:56 INFO - Return code: 0 04:34:56 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:56 INFO - 'HOME': '/Users/cltbld', 04:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:56 INFO - 'LOGNAME': 'cltbld', 04:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:56 INFO - 'MOZ_NO_REMOTE': '1', 04:34:56 INFO - 'NO_EM_RESTART': '1', 04:34:56 INFO - 'PAGER': '/bin/cat', 04:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:56 INFO - 'PWD': '/builds/slave/test', 04:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:56 INFO - 'SHELL': '/bin/bash', 04:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:56 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:56 INFO - 'USER': 'cltbld', 04:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:56 INFO - 'XPC_FLAGS': '0x0', 04:34:56 INFO - 'XPC_SERVICE_NAME': '0', 04:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:34:56 INFO - Cleaning up... 04:34:56 INFO - Return code: 0 04:34:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:34:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:34:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:34:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:34:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:34:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:34:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:34:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:34:56 INFO - 'HOME': '/Users/cltbld', 04:34:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:34:56 INFO - 'LOGNAME': 'cltbld', 04:34:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:34:56 INFO - 'MOZ_NO_REMOTE': '1', 04:34:56 INFO - 'NO_EM_RESTART': '1', 04:34:56 INFO - 'PAGER': '/bin/cat', 04:34:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:34:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:34:56 INFO - 'PWD': '/builds/slave/test', 04:34:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:34:56 INFO - 'SHELL': '/bin/bash', 04:34:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:34:56 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:34:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:34:56 INFO - 'USER': 'cltbld', 04:34:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:34:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:34:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:34:56 INFO - 'XPC_FLAGS': '0x0', 04:34:56 INFO - 'XPC_SERVICE_NAME': '0', 04:34:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:34:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:34:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:34:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:34:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:34:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:34:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:34:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:34:59 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 04:35:00 INFO - Downloading docopt-0.6.1.tar.gz 04:35:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:35:00 INFO - Installing collected packages: blobuploader, requests, docopt 04:35:00 INFO - Running setup.py install for blobuploader 04:35:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:35:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:35:00 INFO - Running setup.py install for requests 04:35:00 INFO - Running setup.py install for docopt 04:35:01 INFO - Successfully installed blobuploader requests docopt 04:35:01 INFO - Cleaning up... 04:35:01 INFO - Return code: 0 04:35:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:35: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')]} 04:35:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35: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')]} 04:35:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:01 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:35:01 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 04:35:01 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 04:35:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:35:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:35:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:35:01 INFO - 'HOME': '/Users/cltbld', 04:35:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:35:01 INFO - 'LOGNAME': 'cltbld', 04:35:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:01 INFO - 'MOZ_NO_REMOTE': '1', 04:35:01 INFO - 'NO_EM_RESTART': '1', 04:35:01 INFO - 'PAGER': '/bin/cat', 04:35:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:35:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:01 INFO - 'PWD': '/builds/slave/test', 04:35:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:35:01 INFO - 'SHELL': '/bin/bash', 04:35:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:35:01 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:35:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:35:01 INFO - 'USER': 'cltbld', 04:35:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:35:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:35:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:01 INFO - 'XPC_FLAGS': '0x0', 04:35:01 INFO - 'XPC_SERVICE_NAME': '0', 04:35:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:35:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:35:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-LqQN0L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:35:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-RXpxEq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:35:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Tvcjdt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:35:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-c08owp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:35:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:35:01 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-2gvhiU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-jizmNL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-1W22zT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-KRf2Pi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-eyUwab-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-StN8un-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-U0Od09-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-P2963j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-rcwwO5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Ipfv54-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:35:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:02 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-p69IkK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:35:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-vmzPGh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:35:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:35:03 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-XLjd7j-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:35:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:35:03 INFO - Running setup.py install for manifestparser 04:35:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:35:03 INFO - Running setup.py install for mozcrash 04:35:03 INFO - Running setup.py install for mozdebug 04:35:03 INFO - Running setup.py install for mozdevice 04:35:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 04:35:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:35:03 INFO - Running setup.py install for mozfile 04:35:04 INFO - Running setup.py install for mozhttpd 04:35:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Running setup.py install for mozinfo 04:35:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Running setup.py install for mozInstall 04:35:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Running setup.py install for mozleak 04:35:04 INFO - Running setup.py install for mozlog 04:35:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Running setup.py install for moznetwork 04:35:04 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:35:04 INFO - Running setup.py install for mozprocess 04:35:05 INFO - Running setup.py install for mozprofile 04:35:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:35:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:35:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:35:05 INFO - Running setup.py install for mozrunner 04:35:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:35:05 INFO - Running setup.py install for mozscreenshot 04:35:05 INFO - Running setup.py install for moztest 04:35:05 INFO - Running setup.py install for mozversion 04:35:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:35:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:35:05 INFO - Cleaning up... 04:35:05 INFO - Return code: 0 04:35:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:05 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:35:05 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 04:35:05 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 04:35:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:35:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:35:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:35:05 INFO - 'HOME': '/Users/cltbld', 04:35:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:35:05 INFO - 'LOGNAME': 'cltbld', 04:35:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:05 INFO - 'MOZ_NO_REMOTE': '1', 04:35:05 INFO - 'NO_EM_RESTART': '1', 04:35:05 INFO - 'PAGER': '/bin/cat', 04:35:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:35:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:05 INFO - 'PWD': '/builds/slave/test', 04:35:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:35:05 INFO - 'SHELL': '/bin/bash', 04:35:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:35:05 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:35:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:35:05 INFO - 'USER': 'cltbld', 04:35:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:35:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:35:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:05 INFO - 'XPC_FLAGS': '0x0', 04:35:05 INFO - 'XPC_SERVICE_NAME': '0', 04:35:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:35:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-goM_fS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-8gxWuy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-Ym9dTZ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-aEfeGG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-eaWT1L-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-39GtWm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-aqqerU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-ToJQom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:35:06 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)) 04:35:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:35:06 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-o9XbRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-lq408Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-dJ0PVR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-GRvubB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-XI6pop-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-ILgmjM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-uSvbSw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-wquZcG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:35:07 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)) 04:35:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:35:07 INFO - Running setup.py (path:/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/pip-MZKaQQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:35:07 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)) 04:35:07 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)) 04:35:07 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)) 04:35:07 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)) 04:35:07 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)) 04:35:07 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)) 04:35:07 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:35:07 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:35:07 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:35:07 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:35:07 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:35:10 INFO - Downloading blessings-1.6.tar.gz 04:35:10 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:35:10 INFO - Installing collected packages: blessings 04:35:10 INFO - Running setup.py install for blessings 04:35:10 INFO - Successfully installed blessings 04:35:10 INFO - Cleaning up... 04:35:10 INFO - Return code: 0 04:35:10 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:35:10 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:35:10 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:35:11 INFO - Reading from file tmpfile_stdout 04:35:11 INFO - Current package versions: 04:35:11 INFO - blessings == 1.6 04:35:11 INFO - blobuploader == 1.2.4 04:35:11 INFO - docopt == 0.6.1 04:35:11 INFO - functools32 == 3.2.3-2 04:35:11 INFO - jsonschema == 2.5.1 04:35:11 INFO - manifestparser == 1.1 04:35:11 INFO - mozInstall == 1.12 04:35:11 INFO - mozcrash == 0.17 04:35:11 INFO - mozdebug == 0.1 04:35:11 INFO - mozdevice == 0.48 04:35:11 INFO - mozfile == 1.2 04:35:11 INFO - mozhttpd == 0.7 04:35:11 INFO - mozinfo == 0.9 04:35:11 INFO - mozleak == 0.1 04:35:11 INFO - mozlog == 3.4 04:35:11 INFO - moznetwork == 0.27 04:35:11 INFO - mozprocess == 0.23 04:35:11 INFO - mozprofile == 0.28 04:35:11 INFO - mozrunner == 6.12 04:35:11 INFO - mozscreenshot == 0.1 04:35:11 INFO - mozsystemmonitor == 0.3 04:35:11 INFO - moztest == 0.7 04:35:11 INFO - mozversion == 1.4 04:35:11 INFO - psutil == 3.1.1 04:35:11 INFO - requests == 1.2.3 04:35:11 INFO - wsgiref == 0.1.2 04:35:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:35: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')]} 04:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35: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')]} 04:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:11 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:35:11 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 04:35:11 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 04:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:35:11 INFO - 'HOME': '/Users/cltbld', 04:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:35:11 INFO - 'LOGNAME': 'cltbld', 04:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:11 INFO - 'MOZ_NO_REMOTE': '1', 04:35:11 INFO - 'NO_EM_RESTART': '1', 04:35:11 INFO - 'PAGER': '/bin/cat', 04:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:11 INFO - 'PWD': '/builds/slave/test', 04:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:35:11 INFO - 'SHELL': '/bin/bash', 04:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:35:11 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:35:11 INFO - 'USER': 'cltbld', 04:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:11 INFO - 'XPC_FLAGS': '0x0', 04:35:11 INFO - 'XPC_SERVICE_NAME': '0', 04:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 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)) 04:35:11 INFO - Cleaning up... 04:35:11 INFO - Return code: 0 04:35:11 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:35: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')]} 04:35:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:35:11 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35: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')]} 04:35:11 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:35:11 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:35:11 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 0x10fb9e490>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10fb14570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd42a4962f0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, '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.W2uPWnqkIu/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.QYBq22X9jT/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 04:35:11 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 04:35:11 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 04:35:11 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:35:11 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:35:11 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:35:11 INFO - 'HOME': '/Users/cltbld', 04:35:11 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:35:11 INFO - 'LOGNAME': 'cltbld', 04:35:11 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:11 INFO - 'MOZ_NO_REMOTE': '1', 04:35:11 INFO - 'NO_EM_RESTART': '1', 04:35:11 INFO - 'PAGER': '/bin/cat', 04:35:11 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:35:11 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:11 INFO - 'PWD': '/builds/slave/test', 04:35:11 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:35:11 INFO - 'SHELL': '/bin/bash', 04:35:11 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:35:11 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:35:11 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:35:11 INFO - 'USER': 'cltbld', 04:35:11 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:35:11 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:35:11 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:11 INFO - 'XPC_FLAGS': '0x0', 04:35:11 INFO - 'XPC_SERVICE_NAME': '0', 04:35:11 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:35:11 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:35:11 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:35:11 INFO - Cleaning up... 04:35:11 INFO - Return code: 0 04:35:11 INFO - Running post-action listener: _resource_record_post_action 04:35:11 INFO - Running post-action listener: _start_resource_monitoring 04:35:11 INFO - Starting resource monitoring. 04:35:11 INFO - [mozharness: 2016-10-19 11:35:11.680438Z] Finished create-virtualenv step (success) 04:35:11 INFO - [mozharness: 2016-10-19 11:35:11.681528Z] Running install step. 04:35:11 INFO - Running pre-action listener: _resource_record_pre_action 04:35:11 INFO - Running main action method: install 04:35:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:35:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:35:11 INFO - Reading from file tmpfile_stdout 04:35:11 INFO - Detecting whether we're running mozinstall >=1.0... 04:35:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:35:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:35:12 INFO - Reading from file tmpfile_stdout 04:35:12 INFO - Output received: 04:35:12 INFO - Usage: mozinstall [options] installer 04:35:12 INFO - Options: 04:35:12 INFO - -h, --help show this help message and exit 04:35:12 INFO - -d DEST, --destination=DEST 04:35:12 INFO - Directory to install application into. [default: 04:35:12 INFO - "/builds/slave/test"] 04:35:12 INFO - --app=APP Application being installed. [default: firefox] 04:35:12 INFO - mkdir: /builds/slave/test/build/application 04:35:12 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'] 04:35:12 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 04:35:46 INFO - Reading from file tmpfile_stdout 04:35:46 INFO - Output received: 04:35:46 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 04:35:46 INFO - Running post-action listener: _resource_record_post_action 04:35:46 INFO - [mozharness: 2016-10-19 11:35:46.458846Z] Finished install step (success) 04:35:46 INFO - [mozharness: 2016-10-19 11:35:46.459013Z] Running run-tests step. 04:35:46 INFO - Running pre-action listener: _resource_record_pre_action 04:35:46 INFO - Running main action method: run_tests 04:35:46 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:35:46 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:35:46 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:35:46 INFO - 2016-10-19 04:35:46.473 screenresolution[1936:15038] starting screenresolution argv=screenresolution get 04:35:46 INFO - 2016-10-19 04:35:46.475 screenresolution[1936:15038] Display 0: 1600x1200x32@60 04:35:46 INFO - 2016-10-19 04:35:46.481 screenresolution[1937:15040] starting screenresolution argv=screenresolution list 04:35:46 INFO - Available Modes on Display 0 04:35:46 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:35:46 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:35:46 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:35:46 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:35:46 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:35:46 INFO - Intel Iris: 04:35:46 INFO - Chipset Model: Intel Iris 04:35:46 INFO - Type: GPU 04:35:46 INFO - Bus: Built-In 04:35:46 INFO - VRAM (Dynamic, Max): 1536 MB 04:35:46 INFO - Vendor: Intel (0x8086) 04:35:46 INFO - Device ID: 0x0a2e 04:35:46 INFO - Revision ID: 0x0009 04:35:46 INFO - Displays: 04:35:46 INFO - Display: 04:35:46 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:35:46 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:35:46 INFO - Display Serial Number: 1600x1200 60 04:35:46 INFO - Main Display: Yes 04:35:46 INFO - Mirror: Off 04:35:46 INFO - Online: Yes 04:35:46 INFO - Rotation: Supported 04:35:46 INFO - Return code: 0 04:35:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:35:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:35:46 INFO - Python 2.7.3 04:35:46 INFO - Return code: 0 04:35:46 INFO - grabbing minidump binary from tooltool 04:35:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:35:46 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10f9b2d30>, '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 0x10f7d8db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10f9aab20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:35:46 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:35:46 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest -o -c /builds/tooltool_cache 04:35:46 INFO - Calling ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/macosx64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] with output_timeout 600 04:35:46 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:35:46 INFO - Return code: 0 04:35:46 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 04:35:46 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:35:46 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:35:46 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 04:35:46 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:35:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:35:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Autoland --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:35:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.W2uPWnqkIu/Render', 04:35:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 04:35:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 04:35:46 INFO - 'HOME': '/Users/cltbld', 04:35:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 04:35:46 INFO - 'LOGNAME': 'cltbld', 04:35:46 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:35:46 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 04:35:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:35:46 INFO - 'MOZ_NO_REMOTE': '1', 04:35:46 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:35:46 INFO - 'NO_EM_RESTART': '1', 04:35:46 INFO - 'PAGER': '/bin/cat', 04:35:46 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 04:35:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:35:46 INFO - 'PWD': '/builds/slave/test', 04:35:46 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:35:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 04:35:46 INFO - 'SHELL': '/bin/bash', 04:35:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.QYBq22X9jT/Listeners', 04:35:46 INFO - 'TMPDIR': '/var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/', 04:35:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 04:35:46 INFO - 'USER': 'cltbld', 04:35:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 04:35:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 04:35:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:35:46 INFO - 'XPC_FLAGS': '0x0', 04:35:46 INFO - 'XPC_SERVICE_NAME': '0', 04:35:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 04:35:46 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Autoland', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/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 04:35:47 INFO - mozversion application_buildid: 20161019025422 04:35:47 INFO - mozversion application_changeset: 9efcbf2796dee874ac5754cf6adf1e47b2ff395c 04:35:47 INFO - mozversion application_display_name: Nightly 04:35:47 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:35:47 INFO - mozversion application_name: Firefox 04:35:47 INFO - mozversion application_remotingname: firefox 04:35:47 INFO - mozversion application_repository: https://hg.mozilla.org/integration/autoland 04:35:47 INFO - mozversion application_vendor: Mozilla 04:35:47 INFO - mozversion application_version: 52.0a1 04:35:47 INFO - mozversion platform_buildid: 20161019025422 04:35:47 INFO - mozversion platform_changeset: 9efcbf2796dee874ac5754cf6adf1e47b2ff395c 04:35:47 INFO - mozversion platform_repository: https://hg.mozilla.org/integration/autoland 04:35:47 INFO - mozversion platform_version: 52.0a1 04:35:47 INFO - using testdate: 1476876947 04:35:47 INFO - actual date: 1476876947 04:35:47 INFO - starting webserver on 'localhost:52347' 04:35:47 INFO - SUITE-START | Running 2 tests 04:35:47 INFO - TEST-START | dromaeo_css 04:35:47 INFO - operating with platform_type : mac_ 04:35:47 INFO - Initialising browser for dromaeo_css test... 04:35:47 INFO - TEST-INFO | started process 1947 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpzXSiW2/profile http://localhost:52347/getInfo.html) 04:35:49 INFO - PROCESS | 1947 | 2016-10-19 04:35:49.818 plugin-container[1949:15200] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x494f, name = 'com.apple.tsm.portname' 04:35:49 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:49 INFO - PROCESS | 1947 | 2016-10-19 04:35:49.822 plugin-container[1949:15200] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c0f, name = 'com.apple.CFPasteboardClient' 04:35:49 INFO - PROCESS | 1947 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:49 INFO - PROCESS | 1947 | 2016-10-19 04:35:49.822 plugin-container[1949:15200] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:35:49 INFO - PROCESS | 1947 | __metrics Screen width/height:1600/1200 04:35:49 INFO - PROCESS | 1947 | colorDepth:24 04:35:49 INFO - PROCESS | 1947 | Browser inner width/height: 1024/689 04:35:49 INFO - PROCESS | 1947 | __metrics 04:35:50 ERROR - PROCESS | 1947 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:35:50 INFO - TEST-INFO | 1947: exit 0 04:35:50 INFO - Browser initialized. 04:35:50 INFO - Running cycle 1/1 for dromaeo_css test... 04:35:50 INFO - TEST-INFO | started process 1950 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmpzXSiW2/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:35:53 INFO - PROCESS | 1950 | 2016-10-19 04:35:53.159 plugin-container[1951:15320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 04:35:53 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:53 INFO - PROCESS | 1950 | 2016-10-19 04:35:53.159 plugin-container[1951:15320] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0f, name = 'com.apple.CFPasteboardClient' 04:35:53 INFO - PROCESS | 1950 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:35:53 INFO - PROCESS | 1950 | 2016-10-19 04:35:53.159 plugin-container[1951:15320] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:36:02 INFO - PROCESS | 1950 | RSS: Main: 237355008 04:36:02 INFO - PROCESS | 1950 | 04:36:52 INFO - PROCESS | 1950 | 1476877012462 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] 04:39:06 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-dojo.html (next: http://localhost:52347/tests/dromaeo/cssquery-ext.html) 04:39:08 INFO - PROCESS | 1950 | RSS: Main: 251416576 04:39:08 INFO - PROCESS | 1950 | 04:42:10 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-ext.html (next: http://localhost:52347/tests/dromaeo/cssquery-jquery.html) 04:42:10 INFO - PROCESS | 1950 | RSS: Main: 262324224 04:42:10 INFO - PROCESS | 1950 | 04:45:12 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-jquery.html (next: http://localhost:52347/tests/dromaeo/cssquery-mootools.html) 04:45:13 INFO - PROCESS | 1950 | RSS: Main: 269099008 04:45:13 INFO - PROCESS | 1950 | 04:48:15 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-mootools.html (next: http://localhost:52347/tests/dromaeo/cssquery-prototype.html) 04:48:15 INFO - PROCESS | 1950 | RSS: Main: 269000704 04:48:15 INFO - PROCESS | 1950 | 04:51:18 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-prototype.html (next: http://localhost:52347/tests/dromaeo/cssquery-yui.html) 04:51:19 INFO - PROCESS | 1950 | RSS: Main: 274608128 04:51:19 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | Cycle 1(1): loaded http://localhost:52347/tests/dromaeo/cssquery-yui.html (next: http://localhost:52347/tests/dromaeo/cssquery-dojo.html) 04:54:22 INFO - PROCESS | 1950 | RSS: Main: 276803584 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | __start_tp_report 04:54:22 INFO - PROCESS | 1950 | _x_x_mozilla_page_load 04:54:22 INFO - PROCESS | 1950 | _x_x_mozilla_page_load_details 04:54:22 INFO - PROCESS | 1950 | |i|pagename|runs| 04:54:22 INFO - PROCESS | 1950 | |0;dojo.html;3362.4956256561527;3405.5232267482547;3406.097384193189;3439.6044454887697;3445.397055515284;5643.717814109288;5756;5827;5850.824475265738;5864.354920958691;5742.598018138732;5744.770209191638;5817.912731309023;5863.76544938202;5915.822525324236;5168.689878607286;5204.167333226685;5214.843554693346;5343.679379237258;5361.651492652984;5301.8939621207555;5309.123994540896;5326.92009619856;5373.516383525482;5435.809746658868;5135.897282054357;5146.176611742122;5176.456472070454;5265.210218467222;5418.133098704188;5207.062728708869;5213.296205012312;5227.660202086852;5232.581393488512;5248.239005344204;5235.110031294689;5241.633085684004;5303.787848486056;5312.73436328182;5321.255024296602;5097.617678674114;5677.971610141923;5698.116791897262;5790.884182316372;5791.160281759121;4964.851054468371;5437.673739575638;6227.3772622737815;6241.28225254097;6258.812235632893;6115.757578772925;6241.812745617639;6242.719077641517;6257.812265632038;6279.843003924939;5828.9417105828825;5834.533237341003;5837.124431335249;5866.853328666817;5922.763089476416;6297.937020629826;6305.464035556937;6312.368763123651;6337.4613157882095;6360.109584658151;6183.96908015466;6193.163922870355;6206.875862482815;6213.440790328802;6214.533909956681;6126.212334160686;6167.414095660905;6177.876442471125;6189.84525386869;6271.090691849657;6144.893919094606;6183.721732522047;6210.937890621036;6229.906551401733;6240.563160578807;5344.9732751336;6122.051082082285;6187.752489900354;6205.627662340274;6220.973539366045;4142.751434913915;4423.491298500714;4840.298156767249;4929.359183306147;4939.086269040239;4427.867163985085;4455.108978204372;4494;4526.117407105648;4529.954700452953;4428.7121337112985;5987.670678112746;5989.940100599026;5996.4603185713495;6024.608400453956;6147.954847675816;6189.50483961282;6191.721372538246;6286.088517164985;6297.370262973757;5917.644941303535;5965.761369545169;5997.910031349533;6040.939590604125;6061.515078793686;5435.347758269033;5472.179173124063;5474.233607294982;5482;5501.119820828728;6153.169322141453;6252.687365631791;6257.1240026395435;6288.685565721696;6293.150424692791;6161.353057929032;6184.041473571604;6216.347283535164;6248.562600617913;6267.467265282502;6248.625082495065;6256.343083976299;6260;6274.905876411949;6327.557071004985;4591.4260717410325;4821.831235906727;5113.846584602468;5986.3115741688525;6216.906746398898;5090.720010399464;5247;6136.3046386708;6192.969035154975;6200.937990620217;4803.663743537919;5181.948180518147;6209.441150296314;6233.376662333731;6242.438180563771;5041.924371134509;6131.754729810936;6215.036669316263;6224.688765561613;6238.251409830849;4873.805917550223;5680.602357834912;5844.824655260349;6067.787627433196;6075.027995520695;5747.597668163188;6181.289151747426;6187.628742275478;6191.288001879841;6245.750169993331;5585.609007369445;5953.523718102455;6024.969875150597;6129.601575897731;6146.53900957439 04:54:22 INFO - PROCESS | 1950 | |1;ext.html;431.0344827586204;453.46038214524714;508.1691434504585;512.2546694559417;533.1948757376365;38158.046048848795;39840.40239396411;39870.40194397079;39928.99999999996;40036;41072;41497;41645.375319370236;41686.58313416865;41752.373714394234;9477.526123693806;9642.951785241064;9648;9658.661946831862;9661.082197191256;9546.713598592054;9596.280278979104;9612.471314077759;9617.37487063342;9635.614575416976;9550.665726699566;9585.80828383432;9632.711018669452;9651.420914745138;9655.806883862353;8640;8651.48091114535;9569.186619137381;9640.08419200182;9657.951710241405;9404.858927116098;9470.573824177929;9502.714918552454;9514.714558563253;9528.857067143997;38938;39014;39091.41362879559;39256.60743392558;39337.60662393368;3274.934501309984;3332.450145725952;3355.1108956126645;3513.2797776455764;3759.4360845873066;29777.553336699966;30177.849110754305;30329.393412131856;30645.693543064506;30687.232819179477;2635.499255141517;2843.6303280573393;3017.26077111107;3176.491761318201;3311.6853898879567;440237;442661;446789;449491;449717.5028249708;55943.72028139833;56005.1599226012;56047.719761400935;56115.43884561184;56200.43799561952;57683.71158144182;58149;58187.127193092136;58197;58327;15658.138802365978;15805.051696897994;15805.841941580667;16066.75899861503;16076.678466430605;12509.186902851285;12514.936230420517;12562.685932851751;12567.560135395217;12589;14455.78316325256;14644.487442939446;14747.262636868114;14984.250787460584;15048.849511504965;6383.489320854321;6389.201349831271;6421.137870017736;6511.28375878653;6554.573952693155;866.7226487524058;868.0234735922088;944.3106532231373;1062.3838173859144;1120.506976930148;30261.394772104875;30633.693663063084;30970.53544196794;31656.841715791274;32254.35491290161;954.8684808501947;1027.091024443373;1044.4412239451856;1078.5793540519203;1079.0361541993652;11856.397974203563;14785.63035924089;14882.330295136744;14968.326425310886;14972.891342559398;661140;666208.6689566522;676087;866134;867691.6615417008;55611.27304179109;56235.43764562302;56895.71552142213;56962.43037569654;57658;1274.7832868412393;1305.6474751816957;1338.4579245405625;1387.937542810576;1389.1317926296065;22896.19863304843;29511.704882951755;29875.701242987292;30542.541861872534;30874.147551147;42510.78744606381;42657.57342426536;53992;54076;54236.18645720396;449966;489366;518356;518723;523190;25082.623760643968;25201.369965751004;25470.49059018772;25484.745152548978;25560.849761761136;43323.566764331954;44984.77507612441;45135;45430;46179.76910115428;38047.809760951015;38084;39191.41212881752;39239.41140882946;39256;37561;37713.43429848499;38309;38408.42387364136;39078.023049423995;32531.34937301288;33468.49797252994;33919;34972;35141.47287790736;31344.902928398307;33285.66714332923;33783.49324760081;33828.83085584556;33957.66042339545;20234.494137646678;24572.50854982926;24904.87547562323;25138.749931558214;25209.495810084063 04:54:22 INFO - PROCESS | 1950 | |2;jquery.html;312.9906102816915;367.71134659292443;368.4951616285689;378.8806525944329;394.93088709475836;19026.429207123772;19217.94301313427;19992.700109498368;20721.585568288625;21038.31693464523;17218.569535761613;19863.30478433255;20416.489587760312;20478;20545.075471603777;4959.702417854922;5158.742062896859;5601.99999999999;5922.170896074553;5932.555058370618;5175.275461435402;5414.566834653238;6532.08550802888;6540.607563546178;6552.770653027145;6249;6425.967870160643;6480;6483.157189565373;6502.869942601145;6425.7750978715985;6438.227412710481;6448.258450278231;6523.902141467882;6552.049952756847;4982.750862456899;5225.5035771602015;5283.418823929364;5349.3045904032615;5359.892802143974;9715.36850104748;12971.675708107372;13048.34758262083;23819.642705359434;24311.14910978125;2158.0396723458066;2251.639737641986;2363.166104364028;2386.7016622922133;2678.0894495871303;9493.477858717768;9553.856692149553;9911.058449447362;10010.9499452503;10234.590616375335;1813.102514255452;1838.8712790104698;1867.8232713390478;1881.6424879273031;1891.9621607567908;121039;125087;125130;127270.36364818209;128278;11049.889501105046;11257.380844053492;11417.657470275904;11710.238834475731;11723.824142637868;14130.293485325692;14211.715765684628;14262;14267.85732142686;14291.57125286243;8144.348452123816;8447.619857106445;8460.492370457792;8482.02456717473;8566.27186689126;7809.492382995087;8242.928419305572;8437.620307086196;8461.2807911327;8635.827283454295;4383.473983122046;4452.154090722753;4468.396766436489;4542.432195975503;4556.0432309214775;1896.1088288504382;1939.362467044818;1963.5581994051224;1965.1648049579157;1965.88204707718;834.6160766047686;893.4632432216578;916.2624087609405;929.340168480373;969.3069455339325;20042.59914801725;20154.798452015584;20921.790782091983;21031.894840526005;21104.366868993955;906.7733066733317;937.9249660027182;964.2045312616979;985.7732721474101;992.4739887859446;8654.30765538768;11127.666170014912;11184.832227516594;11198.440077996067;11272.66182014541;419954;448150;451404.9438022508;543927;546917;109065.45467272613;112677.43661281806;140830.2958485201;144208;144820;937.8593211018402;978.4911845840265;998.9800203995879;1000.7448100734266;1001.1690297053428;15586.532404027897;18481.81518184801;18829.717554236973;18854.24583016664;18987.050647467295;42497.93755156146;47440.52559474361;54286.7285663585;54523;55478.16782748181;74098.25901740913;75240.24759752332;75760;76013.4797304062;77323.84014239679;6778.593284402755;7413;7545.50954187976;7882;8060.355171586376;10524.631637892637;12665.620031399072;12839.935800320938;13097.607071787861;13472.9895257858;9885.50572471388;10096.646617368357;11074.501647425725;11444.427778611203;11567.942160289145;10882.245625259273;11107.278026928223;11147.498362573866;11425.828612570653;11521.711957201138;11121.332720036824;11270.380129092979;11695.298282103102;11764.411779411166;11859.110566707704;7323.7436689715605;7343.118825740945;7490.775276741707;7641.2740789624895;7674.884876726742;10582.312149710244;11359.375234361858;11367.976882080653;11577.24747891418;11670.299782013106 04:54:22 INFO - PROCESS | 1950 | |3;mootools.html;1513.4097701896255;1524.3140586735965;1550.751879699248;1850.8982005989665;1860.7394964704936;8051.476654017485;8344.707935222268;8431.957840210798;9027.413218140824;9053.049429809875;7659.004329437179;8420.831583368328;8857.247133993618;8974.147455991686;9003.504807235617;2076.8234700050516;2260.728712554496;2311.5723591135693;2370.2415227127326;2377.976220237793;2210.9447263818374;2323.337848713117;2367.5501654685554;2370.8221883358733;2386.4988352446007;2106.610277098734;2335.346103091137;2352.741198468167;2355.2463211772238;2359.079958816063;2311.63013917774;2326.0346956013236;2331.63859601762;2331.778481044298;2333.6849525314037;2262.8755418452142;2264.399934017487;2265.728112626479;2269.1150451323997;2292.32376448948;8969.551522423853;9032.909670903338;9070.775445314865;9073.13805188506;9108.68119615817;5251.501107394792;5340.798320377923;5382.650127741685;5442.6462279952075;5452.727363631842;3082;3572.4105522588743;3640.2537479816574;3642.7085833133287;3715.925681486382;1046.466302185883;1075.0700643943012;1088.4285749981243;1088.434014312561;1102.5259138570411;11824.940875295655;11888.999999999913;12011.879881201163;12048.698782530419;12050.698732531757;3822.9235415291537;4538.228501154812;4772.689775164603;4800.183968725325;4817.566419022235;8425.70510032146;9757.804843903083;9884.258680598983;9976.401415915068;9986.60053597848;5333.413324534298;5382.892342153135;5389.892202155935;5392.40683524812;5395.244665746799;4432.180046691373;4575.17646823575;4881.267809828483;4909.042736666387;4954.826581069718;3378.3750006248924;3786.8674596388996;3863.594322596087;3906.199229158016;3971.324874771296;1299.772539805549;1308.7566811529086;1309.3387839141174;1313.6387493439381;1319.6370997975441;846.716350022737;853.0616322045701;902.837489251941;916.5921165081608;932.9160375566096;5633.239512665819;5891.204687367236;6036;6059.151718759377;6166.290876549165;944.5548311084052;952.7094236258008;955.3217215776738;956.0964888180603;992.7865508915618;3886.844526218976;4006.338954072604;4043.230387184788;4067.8169482373364;4091.2022155679356;8134.552599607078;10579.94710026445;10784.56861725538;10793.514291856885;10807.945960270148;10769.83845242322;10780.892191078146;10802.621908233174;10809.40548269837;10820.72948176286;7832.316052048723;8118.2693557580105;8327.8334433311;8337.416380853281;8346.66613335472;5902.793402230901;6061.484773794277;6066;6163.459135216196;6233.127362169391;8279.509688256172;9714.417134971924;10031.598736050477;10277.794444110927;10291;9932.900670993198;10156.74608134773;10222.948885255526;10280.845787313046;10296.07335339823;6890.44876409876;6935.445164386736;8239.794005149679;8326.04250511199;8469.407141500036;6997.930020699931;8235.794105147419;8328.83342333162;8394.622241999003;8416.074231834617;6523.347665233438;7439.070116235471;7513.361364284097;7537.736179233701;7562.395008399425;6330.651814150266;7254.492185546961;7397.963010184914;7408.481406301723;7534.924650753642;6898.827529311808;6988.860222795617;7000.439964802702;7076.716931322895;7083.645817708991;4704.917868890106;4870.342503761947;4945.7527123644395;5069.797208111634;5151.124308867426;4957.05570120883;5006.324146240211;5091.923621145612;5193.584513238856;5230.215467679878 04:54:22 INFO - PROCESS | 1950 | |4;prototype.html;288.25054857370833;293.6432234834675;309.3796937141032;312.32849373846244;315.3724089062763;5258.842234732954;5352.671587859284;5452.836414907554;5562.582806289529;5585.52523035542;4610.077984403116;5016.663918494197;5375.784968601261;5419.891602167954;5488.451154884509;3645.4714066460347;4175.540690524048;4215;4217.34631132175;4239.138581457504;3468.5317482139967;3475.391806433877;3656.8537258509627;4241.1093670329265;4245.532991370947;3407.965920340802;3946.7237293389476;4079.6940229482757;4161.271777438951;4192.203481338551;3456.3778519866414;4082.2856000199845;4128.669706423479;4143.3784932260105;4156.91686166278;2961.570572267009;3422.8070613498267;3562.7352289937216;3593.1735700788704;3598.5170232539726;4641.445115886148;5134.948650513485;5378.408375078739;5409.5672346212605;5456.281271399496;3952;4779.330893674888;4799.424069111694;4801.73590452525;4811.6286858245585;5300.231466437345;5589.608727389084;5798.652080875118;5835.416458354173;5887.35239123696;3586.1531311374665;3592.371335016388;3858.787766672832;3900.6294402031763;3953.327934251184;5687.7434821696215;5805.4194580542035;5902.4063502854415;6089.147519347295;6139;2953.2616845788552;3598.586162591283;3677;3925.0579860833195;3938.547067087264;3459.60214575322;3776.15036616759;3927.3716205407;3937.015746063484;3989.1423343981182;3779.7165212609166;4725.929111063337;4731.597814185833;4733.9763301183275;4761.785719642625;4835.661503694778;5792.478676919098;5807.448292412214;5879.706014699248;5901.90814699282;1344.1800501694154;1368.4868174434587;1372.1698372484702;1372.3412761874356;1376.4287820554357;699.192432740186;771.8301973565807;824.8474032304043;828.1759649149135;837.8282452097305;3621.7826930384263;4215.894602634898;4258.615949816322;4464.531224221475;4653.673702994631;4164.625673527728;4490.618297444688;4612.238980568236;4632.374629424984;4980.975095124501;4010.8997275067773;4046.352583586612;4234.428352172479;4607.562281583244;4644.86065418038;2447.608382658766;2465.59317712579;2517.848929064262;2556.693196816394;2601.6097585361968;5039.042581909425;5126.333941469037;5153.2785410042625;5179.741012949337;5179.8446046618665;4070.776107314126;4522.751248681288;5093.8726531836255;5115.411727651293;5164.591369695982;4306.956930430718;4308.999999999937;4531.818727250873;4717.504662010508;5130.330156416182;4445.199864024377;4888.557875426686;5017.924731129109;5041.798328066836;5062.924056139234;4504.481984571817;4732.911430370965;4983.832033065628;4991.675541089818;5034.219695947134;3933.7836418996085;5100.041975654186;5110.71891045996;5131.974340128276;5160.48395160496;3307.074019274607;3434.4676575130893;3437.9828100859336;3443.5351227583956;3491.650834916488;4170.268474149437;5021.347224860745;5026.3810056759075;5058.875272385533;5059.291699162194;4073.4093556434746;4466.798994045341;4588.701734387254;4867.659263851497;4931.97534012342;4309.978450107729;4815.412517674355;4872.780724867319;4888.608911287018;4917.532834380728;4058.7158898876796;4168.103857670615;4213.1251593042025;4224.852130175429;4684.992726563805;3670.5957597772435;3980.960190398059;4864.391951006124;4885.75413269614;4907.705537667752;4085.243345361523;4307.5477074906685;4485.228334807798;4636.704831108623;4733.5266473352385 04:54:22 INFO - PROCESS | 1950 | |5;yui.html;652.7454292825797;695.5756988237174;703.6200451756052;704.9295070492951;749.6813854112002;15208.923955380222;15685.21573921305;15949.963252388601;16057.437989670363;16103.59741006474;14317;14366.569002929902;14580.489682861102;14593.854061459382;14615.12309261445;679.4496457869117;698.1238545625258;851.9148085191463;861.2808305065278;883.244825674049;859.4671303791658;863.8704194370832;890.3366991591273;891.1489527501253;893.1871996483202;942.4486675294961;944.1596978688972;944.2540393089452;944.5560586524357;945.6122989574276;923.7736754495124;934.0892629686069;944.4569372610777;944.5796620503844;945.7588314979671;685.9862802743967;703.2124021096405;703.989440158398;705.1362081450234;707.1337611426013;15348.769768453481;15373.308201130976;15461.304241309168;15482;15523;14311.427542898376;14392.856071439257;14410.495632652912;14522.854771452256;14666.70666586673;11114.4998475068;11903.523859045628;11911.642650720492;12189.756204875854;12488.875111248954;3769.151940813295;3792.728949808816;3838.673712734421;3946.171304026158;3961.0889495415927;106736.46631766869;107313.46343268311;107741;107811;111173;11084.445777711082;11112.722181945353;11150.553977840958;11282.323060616389;11568.942155289169;22212.88893555522;22290.554188916132;22328;22343.66484502734;22358.441038974157;7877.960610196912;7950.23050612209;7977.441579089524;7998.800029999296;8169.387295952827;4314.9634527674;4394.099209662011;4443.511213766483;4453.087117141044;4454.331850222493;2945.23423909785;3010.5183170692585;3048.420800047984;3145.1665308693214;3349.966500335014;589.7257775134583;595.3540408656656;599.8350453625201;613.7882430561449;631.9052142178618;618.848382146377;636.9745210191633;651.6578796132069;658.1279804259375;664.2925284571916;7163.6060016699585;8396.664133434717;9083.636654533744;9222.95388523053;9290.467072933026;637.37537213531;663.9269680335159;671.375620672779;677.8542613338058;689.4243306838846;4489.910201795946;4582.358469814229;4667.509911459316;4670.526633275151;4741.122892264942;99998.50003749819;125042.37478812548;151372;154228;154670;132085;134505;136225;139460;139857;92465.53767231255;115108.99999999833;115820.42089789498;116283;119623.40188299003;7495.500899819949;8900.332475064266;9011.053839347036;9110.271178305587;9131.530018592892;59327;64858;65604.34395656173;66278;66281.67436651337;74449.51100978059;81263.3747325062;90510;99315.50342248242;100905;9941;11259.380734059378;11661.941690291833;11888.881111189123;12596.433160507615;12827.935860320638;12930.870691292967;13075.738485230433;13323.400446979715;13597.592072237849;11942.820857686967;12069.517219311128;12165.391730413621;12180.025597952319;12296.877031229931;11830.704232394259;12012.459439325427;12173.69565760863;12361.32012739272;12589.181703189264;11702.180847340604;12035.097367697283;12066.215695979734;12144.817827732768;12552.058595605184;10148.64479743235;10262.794744105226;10392.58429662805;10496.68509944703;10693.518791654238;7234.927650723425;8742.694005710024;9307.650390693447;9804.264680148875;10314.48427578603 04:54:22 INFO - PROCESS | 1950 | __end_tp_report 04:54:22 INFO - PROCESS | 1950 | __start_cc_report 04:54:22 INFO - PROCESS | 1950 | _x_x_mozilla_cycle_collect,364 04:54:22 INFO - PROCESS | 1950 | __end_cc_report 04:54:22 INFO - PROCESS | 1950 | __startTimestamp1476878062474__endTimestamp 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | ------- Summary: start ------- 04:54:22 INFO - PROCESS | 1950 | Number of tests: 6 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#0] dojo.html Cycles:165 Average:5687.38 Median:5922.76 stddev:667.41 (11.3%) stddev-sans-first:644.05 04:54:22 INFO - PROCESS | 1950 | Values: 3362.5 3405.5 3406.1 3439.6 3445.4 5643.7 5756.0 5827.0 5850.8 5864.4 5742.6 5744.8 5817.9 5863.8 5915.8 5168.7 5204.2 5214.8 5343.7 5361.7 5301.9 5309.1 5326.9 5373.5 5435.8 5135.9 5146.2 5176.5 5265.2 5418.1 5207.1 5213.3 5227.7 5232.6 5248.2 5235.1 5241.6 5303.8 5312.7 5321.3 5097.6 5678.0 5698.1 5790.9 5791.2 4964.9 5437.7 6227.4 6241.3 6258.8 6115.8 6241.8 6242.7 6257.8 6279.8 5828.9 5834.5 5837.1 5866.9 5922.8 6297.9 6305.5 6312.4 6337.5 6360.1 6184.0 6193.2 6206.9 6213.4 6214.5 6126.2 6167.4 6177.9 6189.8 6271.1 6144.9 6183.7 6210.9 6229.9 6240.6 5345.0 6122.1 6187.8 6205.6 6221.0 4142.8 4423.5 4840.3 4929.4 4939.1 4427.9 4455.1 4494.0 4526.1 4530.0 4428.7 5987.7 5989.9 5996.5 6024.6 6148.0 6189.5 6191.7 6286.1 6297.4 5917.6 5965.8 5997.9 6040.9 6061.5 5435.3 5472.2 5474.2 5482.0 5501.1 6153.2 6252.7 6257.1 6288.7 6293.2 6161.4 6184.0 6216.3 6248.6 6267.5 6248.6 6256.3 6260.0 6274.9 6327.6 4591.4 4821.8 5113.8 5986.3 6216.9 5090.7 5247.0 6136.3 6193.0 6200.9 4803.7 5181.9 6209.4 6233.4 6242.4 5041.9 6131.8 6215.0 6224.7 6238.3 4873.8 5680.6 5844.8 6067.8 6075.0 5747.6 6181.3 6187.6 6191.3 6245.8 5585.6 5953.5 6025.0 6129.6 6146.5 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#1] ext.html Cycles:180 Average:69008.99 Median:39297.11 stddev:156408.20 (398.0%) stddev-sans-first:156762.21 04:54:22 INFO - PROCESS | 1950 | Values: 431.0 453.5 508.2 512.3 533.2 38158.0 39840.4 39870.4 39929.0 40036.0 41072.0 41497.0 41645.4 41686.6 41752.4 9477.5 9643.0 9648.0 9658.7 9661.1 9546.7 9596.3 9612.5 9617.4 9635.6 9550.7 9585.8 9632.7 9651.4 9655.8 8640.0 8651.5 9569.2 9640.1 9658.0 9404.9 9470.6 9502.7 9514.7 9528.9 38938.0 39014.0 39091.4 39256.6 39337.6 3274.9 3332.5 3355.1 3513.3 3759.4 29777.6 30177.8 30329.4 30645.7 30687.2 2635.5 2843.6 3017.3 3176.5 3311.7 440237.0 442661.0 446789.0 449491.0 449717.5 55943.7 56005.2 56047.7 56115.4 56200.4 57683.7 58149.0 58187.1 58197.0 58327.0 15658.1 15805.1 15805.8 16066.8 16076.7 12509.2 12514.9 12562.7 12567.6 12589.0 14455.8 14644.5 14747.3 14984.3 15048.8 6383.5 6389.2 6421.1 6511.3 6554.6 866.7 868.0 944.3 1062.4 1120.5 30261.4 30633.7 30970.5 31656.8 32254.4 954.9 1027.1 1044.4 1078.6 1079.0 11856.4 14785.6 14882.3 14968.3 14972.9 661140.0 666208.7 676087.0 866134.0 867691.7 55611.3 56235.4 56895.7 56962.4 57658.0 1274.8 1305.6 1338.5 1387.9 1389.1 22896.2 29511.7 29875.7 30542.5 30874.1 42510.8 42657.6 53992.0 54076.0 54236.2 449966.0 489366.0 518356.0 518723.0 523190.0 25082.6 25201.4 25470.5 25484.7 25560.8 43323.6 44984.8 45135.0 45430.0 46179.8 38047.8 38084.0 39191.4 39239.4 39256.0 37561.0 37713.4 38309.0 38408.4 39078.0 32531.3 33468.5 33919.0 34972.0 35141.5 31344.9 33285.7 33783.5 33828.8 33957.7 20234.5 24572.5 24904.9 25138.7 25209.5 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#2] jquery.html Cycles:180 Average:31624.90 Median:1495.54 stddev:82561.29 (5520.5%) stddev-sans-first:82759.43 04:54:22 INFO - PROCESS | 1950 | Values: 313.0 367.7 368.5 378.9 394.9 19026.4 19217.9 19992.7 20721.6 21038.3 17218.6 19863.3 20416.5 20478.0 20545.1 4959.7 5158.7 5602.0 5922.2 5932.6 5175.3 5414.6 6532.1 6540.6 6552.8 6249.0 6426.0 6480.0 6483.2 6502.9 6425.8 6438.2 6448.3 6523.9 6552.0 4982.8 5225.5 5283.4 5349.3 5359.9 9715.4 12971.7 13048.3 23819.6 24311.1 2158.0 2251.6 2363.2 2386.7 2678.1 9493.5 9553.9 9911.1 10010.9 10234.6 1813.1 1838.9 1867.8 1881.6 1892.0 121039.0 125087.0 125130.0 127270.4 128278.0 11049.9 11257.4 11417.7 11710.2 11723.8 14130.3 14211.7 14262.0 14267.9 14291.6 8144.3 8447.6 8460.5 8482.0 8566.3 7809.5 8242.9 8437.6 8461.3 8635.8 4383.5 4452.2 4468.4 4542.4 4556.0 1896.1 1939.4 1963.6 1965.2 1965.9 834.6 893.5 916.3 929.3 969.3 20042.6 20154.8 20921.8 21031.9 21104.4 906.8 937.9 964.2 985.8 992.5 8654.3 11127.7 11184.8 11198.4 11272.7 419954.0 448150.0 451404.9 543927.0 546917.0 109065.5 112677.4 140830.3 144208.0 144820.0 937.9 978.5 999.0 1000.7 1001.2 15586.5 18481.8 18829.7 18854.2 18987.1 42497.9 47440.5 54286.7 54523.0 55478.2 74098.3 75240.2 75760.0 76013.5 77323.8 6778.6 7413.0 7545.5 7882.0 8060.4 10524.6 12665.6 12839.9 13097.6 13473.0 9885.5 10096.6 11074.5 11444.4 11567.9 10882.2 11107.3 11147.5 11425.8 11521.7 11121.3 11270.4 11695.3 11764.4 11859.1 7323.7 7343.1 7490.8 7641.3 7674.9 10582.3 11359.4 11368.0 11577.2 11670.3 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#3] mootools.html Cycles:180 Average:5698.15 Median:5038.06 stddev:3200.72 (63.5%) stddev-sans-first:3194.25 04:54:22 INFO - PROCESS | 1950 | Values: 1513.4 1524.3 1550.8 1850.9 1860.7 8051.5 8344.7 8432.0 9027.4 9053.0 7659.0 8420.8 8857.2 8974.1 9003.5 2076.8 2260.7 2311.6 2370.2 2378.0 2210.9 2323.3 2367.6 2370.8 2386.5 2106.6 2335.3 2352.7 2355.2 2359.1 2311.6 2326.0 2331.6 2331.8 2333.7 2262.9 2264.4 2265.7 2269.1 2292.3 8969.6 9032.9 9070.8 9073.1 9108.7 5251.5 5340.8 5382.7 5442.6 5452.7 3082.0 3572.4 3640.3 3642.7 3715.9 1046.5 1075.1 1088.4 1088.4 1102.5 11824.9 11889.0 12011.9 12048.7 12050.7 3822.9 4538.2 4772.7 4800.2 4817.6 8425.7 9757.8 9884.3 9976.4 9986.6 5333.4 5382.9 5389.9 5392.4 5395.2 4432.2 4575.2 4881.3 4909.0 4954.8 3378.4 3786.9 3863.6 3906.2 3971.3 1299.8 1308.8 1309.3 1313.6 1319.6 846.7 853.1 902.8 916.6 932.9 5633.2 5891.2 6036.0 6059.2 6166.3 944.6 952.7 955.3 956.1 992.8 3886.8 4006.3 4043.2 4067.8 4091.2 8134.6 10579.9 10784.6 10793.5 10807.9 10769.8 10780.9 10802.6 10809.4 10820.7 7832.3 8118.3 8327.8 8337.4 8346.7 5902.8 6061.5 6066.0 6163.5 6233.1 8279.5 9714.4 10031.6 10277.8 10291.0 9932.9 10156.7 10222.9 10280.8 10296.1 6890.4 6935.4 8239.8 8326.0 8469.4 6997.9 8235.8 8328.8 8394.6 8416.1 6523.3 7439.1 7513.4 7537.7 7562.4 6330.7 7254.5 7398.0 7408.5 7534.9 6898.8 6988.9 7000.4 7076.7 7083.6 4704.9 4870.3 4945.8 5069.8 5151.1 4957.1 5006.3 5091.9 5193.6 5230.2 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#4] prototype.html Cycles:180 Average:4190.20 Median:4527.28 stddev:1268.73 (28.0%) stddev-sans-first:1238.02 04:54:22 INFO - PROCESS | 1950 | Values: 288.3 293.6 309.4 312.3 315.4 5258.8 5352.7 5452.8 5562.6 5585.5 4610.1 5016.7 5375.8 5419.9 5488.5 3645.5 4175.5 4215.0 4217.3 4239.1 3468.5 3475.4 3656.9 4241.1 4245.5 3408.0 3946.7 4079.7 4161.3 4192.2 3456.4 4082.3 4128.7 4143.4 4156.9 2961.6 3422.8 3562.7 3593.2 3598.5 4641.4 5134.9 5378.4 5409.6 5456.3 3952.0 4779.3 4799.4 4801.7 4811.6 5300.2 5589.6 5798.7 5835.4 5887.4 3586.2 3592.4 3858.8 3900.6 3953.3 5687.7 5805.4 5902.4 6089.1 6139.0 2953.3 3598.6 3677.0 3925.1 3938.5 3459.6 3776.2 3927.4 3937.0 3989.1 3779.7 4725.9 4731.6 4734.0 4761.8 4835.7 5792.5 5807.4 5879.7 5901.9 1344.2 1368.5 1372.2 1372.3 1376.4 699.2 771.8 824.8 828.2 837.8 3621.8 4215.9 4258.6 4464.5 4653.7 4164.6 4490.6 4612.2 4632.4 4981.0 4010.9 4046.4 4234.4 4607.6 4644.9 2447.6 2465.6 2517.8 2556.7 2601.6 5039.0 5126.3 5153.3 5179.7 5179.8 4070.8 4522.8 5093.9 5115.4 5164.6 4307.0 4309.0 4531.8 4717.5 5130.3 4445.2 4888.6 5017.9 5041.8 5062.9 4504.5 4732.9 4983.8 4991.7 5034.2 3933.8 5100.0 5110.7 5132.0 5160.5 3307.1 3434.5 3438.0 3443.5 3491.7 4170.3 5021.3 5026.4 5058.9 5059.3 4073.4 4466.8 4588.7 4867.7 4932.0 4310.0 4815.4 4872.8 4888.6 4917.5 4058.7 4168.1 4213.1 4224.9 4685.0 3670.6 3981.0 4864.4 4885.8 4907.7 4085.2 4307.5 4485.2 4636.7 4733.5 04:54:22 INFO - PROCESS | 1950 | 04:54:22 INFO - PROCESS | 1950 | [#5] yui.html Cycles:180 Average:24491.35 Median:3815.70 stddev:39454.20 (1034.0%) stddev-sans-first:39524.28 04:54:22 INFO - PROCESS | 1950 | Values: 652.7 695.6 703.6 704.9 749.7 15208.9 15685.2 15950.0 16057.4 16103.6 14317.0 14366.6 14580.5 14593.9 14615.1 679.4 698.1 851.9 861.3 883.2 859.5 863.9 890.3 891.1 893.2 942.4 944.2 944.3 944.6 945.6 923.8 934.1 944.5 944.6 945.8 686.0 703.2 704.0 705.1 707.1 15348.8 15373.3 15461.3 15482.0 15523.0 14311.4 14392.9 14410.5 14522.9 14666.7 11114.5 11903.5 11911.6 12189.8 12488.9 3769.2 3792.7 3838.7 3946.2 3961.1 106736.5 107313.5 107741.0 107811.0 111173.0 11084.4 11112.7 11150.6 11282.3 11568.9 22212.9 22290.6 22328.0 22343.7 22358.4 7878.0 7950.2 7977.4 7998.8 8169.4 4315.0 4394.1 4443.5 4453.1 4454.3 2945.2 3010.5 3048.4 3145.2 3350.0 589.7 595.4 599.8 613.8 631.9 618.8 637.0 651.7 658.1 664.3 7163.6 8396.7 9083.6 9223.0 9290.5 637.4 663.9 671.4 677.9 689.4 4489.9 4582.4 4667.5 4670.5 4741.1 99998.5 125042.4 151372.0 154228.0 154670.0 132085.0 134505.0 136225.0 139460.0 139857.0 92465.5 115109.0 115820.4 116283.0 119623.4 7495.5 8900.3 9011.1 9110.3 9131.5 59327.0 64858.0 65604.3 66278.0 66281.7 74449.5 81263.4 90510.0 99315.5 100905.0 9941.0 11259.4 11661.9 11888.9 12596.4 12827.9 12930.9 13075.7 13323.4 13597.6 11942.8 12069.5 12165.4 12180.0 12296.9 11830.7 12012.5 12173.7 12361.3 12589.2 11702.2 12035.1 12066.2 12144.8 12552.1 10148.6 10262.8 10392.6 10496.7 10693.5 7234.9 8742.7 9307.7 9804.3 10314.5 04:54:22 INFO - PROCESS | 1950 | -------- Summary: end -------- 04:54:22 INFO - PROCESS | 1950 | 04:54:23 INFO - TEST-INFO | 1950: exit 0 04:54:23 INFO - TEST-OK | dromaeo_css | took 1116094ms 04:54:23 INFO - TEST-START | kraken 04:54:23 INFO - operating with platform_type : mac_ 04:54:23 INFO - Initialising browser for kraken test... 04:54:23 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp0RUhbd/profile http://localhost:52347/getInfo.html) 04:54:25 INFO - PROCESS | 1956 | 2016-10-19 04:54:25.636 plugin-container[1957:17225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x443f, name = 'com.apple.tsm.portname' 04:54:25 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:25 INFO - PROCESS | 1956 | 2016-10-19 04:54:25.638 plugin-container[1957:17225] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 04:54:25 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:25 INFO - PROCESS | 1956 | 2016-10-19 04:54:25.639 plugin-container[1957:17225] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:25 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 04:54:25 INFO - PROCESS | 1956 | colorDepth:24 04:54:25 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 04:54:25 INFO - PROCESS | 1956 | __metrics 04:54:25 ERROR - PROCESS | 1956 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3087: TypeError: this.tabs is undefined 04:54:26 INFO - TEST-INFO | 1956: exit 0 04:54:26 INFO - Browser initialized. 04:54:26 INFO - Running cycle 1/1 for kraken test... 04:54:26 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/9v/qk4jx18140v19wkr9dt7ppy400000w/T/tmp0RUhbd/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:54:29 INFO - PROCESS | 1958 | 2016-10-19 04:54:29.173 plugin-container[1959:17340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x883f, name = 'com.apple.tsm.portname' 04:54:29 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:29 INFO - PROCESS | 1958 | 2016-10-19 04:54:29.173 plugin-container[1959:17340] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 04:54:29 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 04:54:29 INFO - PROCESS | 1958 | 2016-10-19 04:54:29.173 plugin-container[1959:17340] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 04:54:38 INFO - PROCESS | 1958 | RSS: Main: 237260800 04:54:38 INFO - PROCESS | 1958 | 04:54:41 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:52347/tests/kraken/driver.html?testName=audio-beat-detection) 04:54:42 INFO - PROCESS | 1958 | RSS: Main: 249556992 04:54:42 INFO - PROCESS | 1958 | 04:54:45 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:52347/tests/kraken/driver.html?testName=audio-dft) 04:54:45 INFO - PROCESS | 1958 | RSS: Main: 257089536 04:54:45 INFO - PROCESS | 1958 | 04:54:49 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:52347/tests/kraken/driver.html?testName=audio-fft) 04:54:49 INFO - PROCESS | 1958 | RSS: Main: 260022272 04:54:49 INFO - PROCESS | 1958 | 04:54:51 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:52347/tests/kraken/driver.html?testName=audio-oscillator) 04:54:52 INFO - PROCESS | 1958 | RSS: Main: 262488064 04:54:52 INFO - PROCESS | 1958 | 04:54:54 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:52347/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:54:54 INFO - PROCESS | 1958 | RSS: Main: 262516736 04:54:54 INFO - PROCESS | 1958 | 04:54:57 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:52347/tests/kraken/driver.html?testName=imaging-darkroom) 04:54:58 INFO - PROCESS | 1958 | RSS: Main: 263577600 04:54:58 INFO - PROCESS | 1958 | 04:55:01 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:52347/tests/kraken/driver.html?testName=imaging-desaturate) 04:55:01 INFO - PROCESS | 1958 | RSS: Main: 263454720 04:55:01 INFO - PROCESS | 1958 | 04:55:04 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:52347/tests/kraken/driver.html?testName=json-parse-financial) 04:55:05 INFO - PROCESS | 1958 | RSS: Main: 263839744 04:55:05 INFO - PROCESS | 1958 | 04:55:06 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:52347/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:55:07 INFO - PROCESS | 1958 | RSS: Main: 263278592 04:55:07 INFO - PROCESS | 1958 | 04:55:09 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:55:09 INFO - PROCESS | 1958 | RSS: Main: 263135232 04:55:09 INFO - PROCESS | 1958 | 04:55:12 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:55:12 INFO - PROCESS | 1958 | RSS: Main: 263122944 04:55:12 INFO - PROCESS | 1958 | 04:55:16 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:55:16 INFO - PROCESS | 1958 | RSS: Main: 263196672 04:55:16 INFO - PROCESS | 1958 | 04:55:19 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:55:20 INFO - PROCESS | 1958 | RSS: Main: 263790592 04:55:20 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:52347/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:52347/tests/kraken/driver.html?testName=ai-astar) 04:55:22 INFO - PROCESS | 1958 | RSS: Main: 265179136 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | __start_tp_report 04:55:22 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 04:55:22 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 04:55:22 INFO - PROCESS | 1958 | |i|pagename|runs| 04:55:22 INFO - PROCESS | 1958 | |0;ai-astar;108;118;113;118;117;117;118;107;108;152 04:55:22 INFO - PROCESS | 1958 | |1;audio-beat-detection;148;122;146;185;120;119;115;114;167;146 04:55:22 INFO - PROCESS | 1958 | |2;audio-dft;197;205;207;224;200;200;194;193;199;200 04:55:22 INFO - PROCESS | 1958 | |3;audio-fft;84;59;61;97;62;60;61;61;78;61 04:55:22 INFO - PROCESS | 1958 | |4;audio-oscillator;92;70;89;73;70;71;88;69;70;87 04:55:22 INFO - PROCESS | 1958 | |5;imaging-gaussian-blur;102;135;90;87;89;87;92;80;78;104 04:55:22 INFO - PROCESS | 1958 | |6;imaging-darkroom;123;150;153;99;96;95;97;95;96;96 04:55:22 INFO - PROCESS | 1958 | |7;imaging-desaturate;87;93;86;85;95;89;93;91;87;90 04:55:22 INFO - PROCESS | 1958 | |8;json-parse-financial;58;57;57;59;58;59;57;59;57;58 04:55:22 INFO - PROCESS | 1958 | |9;json-stringify-tinderbox;72;82;70;81;83;78;69;72;74;82 04:55:22 INFO - PROCESS | 1958 | |10;stanford-crypto-aes;89;109;104;87;88;93;84;94;113;111 04:55:22 INFO - PROCESS | 1958 | |11;stanford-crypto-ccm;164;168;166;141;149;148;181;167;209;149 04:55:22 INFO - PROCESS | 1958 | |12;stanford-crypto-pbkdf2;179;177;175;195;161;233;229;173;199;230 04:55:22 INFO - PROCESS | 1958 | |13;stanford-crypto-sha256-iterative;71;75;76;69;72;66;86;91;90;72 04:55:22 INFO - PROCESS | 1958 | __end_tp_report 04:55:22 INFO - PROCESS | 1958 | __start_cc_report 04:55:22 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,959 04:55:22 INFO - PROCESS | 1958 | __end_cc_report 04:55:22 INFO - PROCESS | 1958 | __startTimestamp1476878122652__endTimestamp 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | ------- Summary: start ------- 04:55:22 INFO - PROCESS | 1958 | Number of tests: 14 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#0] ai-astar Cycles:10 Average:117.60 Median:117.50 stddev:12.92 (11.0%) stddev-sans-first:13.23 04:55:22 INFO - PROCESS | 1958 | Values: 108.0 118.0 113.0 118.0 117.0 117.0 118.0 107.0 108.0 152.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#1] audio-beat-detection Cycles:10 Average:138.20 Median:146.00 stddev:24.32 (16.7%) stddev-sans-first:25.54 04:55:22 INFO - PROCESS | 1958 | Values: 148.0 122.0 146.0 185.0 120.0 119.0 115.0 114.0 167.0 146.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#2] audio-dft Cycles:10 Average:201.90 Median:200.00 stddev:8.88 (4.4%) stddev-sans-first:9.23 04:55:22 INFO - PROCESS | 1958 | Values: 197.0 205.0 207.0 224.0 200.0 200.0 194.0 193.0 199.0 200.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#3] audio-fft Cycles:10 Average:68.40 Median:61.50 stddev:13.22 (21.5%) stddev-sans-first:12.76 04:55:22 INFO - PROCESS | 1958 | Values: 84.0 59.0 61.0 97.0 62.0 60.0 61.0 61.0 78.0 61.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#4] audio-oscillator Cycles:10 Average:77.90 Median:80.00 stddev:9.69 (12.1%) stddev-sans-first:8.83 04:55:22 INFO - PROCESS | 1958 | Values: 92.0 70.0 89.0 73.0 70.0 71.0 88.0 69.0 70.0 87.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#5] imaging-gaussian-blur Cycles:10 Average:94.40 Median:87.00 stddev:16.46 (18.9%) stddev-sans-first:17.23 04:55:22 INFO - PROCESS | 1958 | Values: 102.0 135.0 90.0 87.0 89.0 87.0 92.0 80.0 78.0 104.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#6] imaging-darkroom Cycles:10 Average:110.00 Median:96.00 stddev:23.44 (24.4%) stddev-sans-first:24.39 04:55:22 INFO - PROCESS | 1958 | Values: 123.0 150.0 153.0 99.0 96.0 95.0 97.0 95.0 96.0 96.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#7] imaging-desaturate Cycles:10 Average:89.60 Median:90.50 stddev:3.37 (3.7%) stddev-sans-first:3.44 04:55:22 INFO - PROCESS | 1958 | Values: 87.0 93.0 86.0 85.0 95.0 89.0 93.0 91.0 87.0 90.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#8] json-parse-financial Cycles:10 Average:57.90 Median:58.00 stddev:0.88 (1.5%) stddev-sans-first:0.93 04:55:22 INFO - PROCESS | 1958 | Values: 58.0 57.0 57.0 59.0 58.0 59.0 57.0 59.0 57.0 58.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#9] json-stringify-tinderbox Cycles:10 Average:76.30 Median:79.50 stddev:5.48 (6.9%) stddev-sans-first:5.59 04:55:22 INFO - PROCESS | 1958 | Values: 72.0 82.0 70.0 81.0 83.0 78.0 69.0 72.0 74.0 82.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#10] stanford-crypto-aes Cycles:10 Average:97.20 Median:87.50 stddev:10.97 (12.5%) stddev-sans-first:11.23 04:55:22 INFO - PROCESS | 1958 | Values: 89.0 109.0 104.0 87.0 88.0 93.0 84.0 94.0 113.0 111.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#11] stanford-crypto-ccm Cycles:10 Average:164.20 Median:166.50 stddev:19.94 (12.0%) stddev-sans-first:21.15 04:55:22 INFO - PROCESS | 1958 | Values: 164.0 168.0 166.0 141.0 149.0 148.0 181.0 167.0 209.0 149.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:195.10 Median:197.00 stddev:26.79 (13.6%) stddev-sans-first:27.78 04:55:22 INFO - PROCESS | 1958 | Values: 179.0 177.0 175.0 195.0 161.0 233.0 229.0 173.0 199.0 230.0 04:55:22 INFO - PROCESS | 1958 | 04:55:22 INFO - PROCESS | 1958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:76.80 Median:75.50 stddev:8.95 (11.9%) stddev-sans-first:9.25 04:55:22 INFO - PROCESS | 1958 | Values: 71.0 75.0 76.0 69.0 72.0 66.0 86.0 91.0 90.0 72.0 04:55:22 INFO - PROCESS | 1958 | -------- Summary: end -------- 04:55:22 INFO - PROCESS | 1958 | 04:55:23 INFO - TEST-INFO | 1958: exit 0 04:55:23 INFO - TEST-OK | kraken | took 60385ms 04:55:23 INFO - SUITE-END | took 1176s 04:55:23 INFO - Completed test suite (00:19:36) 04:55:23 INFO - javascript benchmark 04:55:23 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7163.631951847673, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3362.4956256561527, 3405.5232267482547, 3406.097384193189, 3439.6044454887697, 3445.397055515284, 5643.717814109288, 5756.0, 5827.0, 5850.824475265738, 5864.354920958691, 5742.598018138732, 5744.770209191638, 5817.912731309023, 5863.76544938202, 5915.822525324236, 5168.689878607286, 5204.167333226685, 5214.843554693346, 5343.679379237258, 5361.651492652984, 5301.8939621207555, 5309.123994540896, 5326.92009619856, 5373.516383525482, 5435.809746658868, 5135.897282054357, 5146.176611742122, 5176.456472070454, 5265.210218467222, 5418.133098704188, 5207.062728708869, 5213.296205012312, 5227.660202086852, 5232.581393488512, 5248.239005344204, 5235.110031294689, 5241.633085684004, 5303.787848486056, 5312.73436328182, 5321.255024296602, 5097.617678674114, 5677.971610141923, 5698.116791897262, 5790.884182316372, 5791.160281759121, 4964.851054468371, 5437.673739575638, 6227.3772622737815, 6241.28225254097, 6258.812235632893, 6115.757578772925, 6241.812745617639, 6242.719077641517, 6257.812265632038, 6279.843003924939, 5828.9417105828825, 5834.533237341003, 5837.124431335249, 5866.853328666817, 5922.763089476416, 6297.937020629826, 6305.464035556937, 6312.368763123651, 6337.4613157882095, 6360.109584658151, 6183.96908015466, 6193.163922870355, 6206.875862482815, 6213.440790328802, 6214.533909956681, 6126.212334160686, 6167.414095660905, 6177.876442471125, 6189.84525386869, 6271.090691849657, 6144.893919094606, 6183.721732522047, 6210.937890621036, 6229.906551401733, 6240.563160578807, 5344.9732751336, 6122.051082082285, 6187.752489900354, 6205.627662340274, 6220.973539366045, 4142.751434913915, 4423.491298500714, 4840.298156767249, 4929.359183306147, 4939.086269040239, 4427.867163985085, 4455.108978204372, 4494.0, 4526.117407105648, 4529.954700452953, 4428.7121337112985, 5987.670678112746, 5989.940100599026, 5996.4603185713495, 6024.608400453956, 6147.954847675816, 6189.50483961282, 6191.721372538246, 6286.088517164985, 6297.370262973757, 5917.644941303535, 5965.761369545169, 5997.910031349533, 6040.939590604125, 6061.515078793686, 5435.347758269033, 5472.179173124063, 5474.233607294982, 5482.0, 5501.119820828728, 6153.169322141453, 6252.687365631791, 6257.1240026395435, 6288.685565721696, 6293.150424692791, 6161.353057929032, 6184.041473571604, 6216.347283535164, 6248.562600617913, 6267.467265282502, 6248.625082495065, 6256.343083976299, 6260.0, 6274.905876411949, 6327.557071004985, 4591.4260717410325, 4821.831235906727, 5113.846584602468, 5986.3115741688525, 6216.906746398898, 5090.720010399464, 5247.0, 6136.3046386708, 6192.969035154975, 6200.937990620217, 4803.663743537919, 5181.948180518147, 6209.441150296314, 6233.376662333731, 6242.438180563771, 5041.924371134509, 6131.754729810936, 6215.036669316263, 6224.688765561613, 6238.251409830849, 4873.805917550223, 5680.602357834912, 5844.824655260349, 6067.787627433196, 6075.027995520695, 5747.597668163188, 6181.289151747426, 6187.628742275478, 6191.288001879841, 6245.750169993331, 5585.609007369445, 5953.523718102455, 6024.969875150597, 6129.601575897731, 6146.53900957439], "value": 5649.364286976544, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [431.0344827586204, 453.46038214524714, 508.1691434504585, 512.2546694559417, 533.1948757376365, 38158.046048848795, 39840.40239396411, 39870.40194397079, 39928.99999999996, 40036.0, 41072.0, 41497.0, 41645.375319370236, 41686.58313416865, 41752.373714394234, 9477.526123693806, 9642.951785241064, 9648.0, 9658.661946831862, 9661.082197191256, 9546.713598592054, 9596.280278979104, 9612.471314077759, 9617.37487063342, 9635.614575416976, 9550.665726699566, 9585.80828383432, 9632.711018669452, 9651.420914745138, 9655.806883862353, 8640.0, 8651.48091114535, 9569.186619137381, 9640.08419200182, 9657.951710241405, 9404.858927116098, 9470.573824177929, 9502.714918552454, 9514.714558563253, 9528.857067143997, 38938.0, 39014.0, 39091.41362879559, 39256.60743392558, 39337.60662393368, 3274.934501309984, 3332.450145725952, 3355.1108956126645, 3513.2797776455764, 3759.4360845873066, 29777.553336699966, 30177.849110754305, 30329.393412131856, 30645.693543064506, 30687.232819179477, 2635.499255141517, 2843.6303280573393, 3017.26077111107, 3176.491761318201, 3311.6853898879567, 440237.0, 442661.0, 446789.0, 449491.0, 449717.5028249708, 55943.72028139833, 56005.1599226012, 56047.719761400935, 56115.43884561184, 56200.43799561952, 57683.71158144182, 58149.0, 58187.127193092136, 58197.0, 58327.0, 15658.138802365978, 15805.051696897994, 15805.841941580667, 16066.75899861503, 16076.678466430605, 12509.186902851285, 12514.936230420517, 12562.685932851751, 12567.560135395217, 12589.0, 14455.78316325256, 14644.487442939446, 14747.262636868114, 14984.250787460584, 15048.849511504965, 6383.489320854321, 6389.201349831271, 6421.137870017736, 6511.28375878653, 6554.573952693155, 866.7226487524058, 868.0234735922088, 944.3106532231373, 1062.3838173859144, 1120.506976930148, 30261.394772104875, 30633.693663063084, 30970.53544196794, 31656.841715791274, 32254.35491290161, 954.8684808501947, 1027.091024443373, 1044.4412239451856, 1078.5793540519203, 1079.0361541993652, 11856.397974203563, 14785.63035924089, 14882.330295136744, 14968.326425310886, 14972.891342559398, 661140.0, 666208.6689566522, 676087.0, 866134.0, 867691.6615417008, 55611.27304179109, 56235.43764562302, 56895.71552142213, 56962.43037569654, 57658.0, 1274.7832868412393, 1305.6474751816957, 1338.4579245405625, 1387.937542810576, 1389.1317926296065, 22896.19863304843, 29511.704882951755, 29875.701242987292, 30542.541861872534, 30874.147551147, 42510.78744606381, 42657.57342426536, 53992.0, 54076.0, 54236.18645720396, 449966.0, 489366.0, 518356.0, 518723.0, 523190.0, 25082.623760643968, 25201.369965751004, 25470.49059018772, 25484.745152548978, 25560.849761761136, 43323.566764331954, 44984.77507612441, 45135.0, 45430.0, 46179.76910115428, 38047.809760951015, 38084.0, 39191.41212881752, 39239.41140882946, 39256.0, 37561.0, 37713.43429848499, 38309.0, 38408.42387364136, 39078.023049423995, 32531.34937301288, 33468.49797252994, 33919.0, 34972.0, 35141.47287790736, 31344.902928398307, 33285.66714332923, 33783.49324760081, 33828.83085584556, 33957.66042339545, 20234.494137646678, 24572.50854982926, 24904.87547562323, 25138.749931558214, 25209.495810084063], "value": 19257.382807210244, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [312.9906102816915, 367.71134659292443, 368.4951616285689, 378.8806525944329, 394.93088709475836, 19026.429207123772, 19217.94301313427, 19992.700109498368, 20721.585568288625, 21038.31693464523, 17218.569535761613, 19863.30478433255, 20416.489587760312, 20478.0, 20545.075471603777, 4959.702417854922, 5158.742062896859, 5601.99999999999, 5922.170896074553, 5932.555058370618, 5175.275461435402, 5414.566834653238, 6532.08550802888, 6540.607563546178, 6552.770653027145, 6249.0, 6425.967870160643, 6480.0, 6483.157189565373, 6502.869942601145, 6425.7750978715985, 6438.227412710481, 6448.258450278231, 6523.902141467882, 6552.049952756847, 4982.750862456899, 5225.5035771602015, 5283.418823929364, 5349.3045904032615, 5359.892802143974, 9715.36850104748, 12971.675708107372, 13048.34758262083, 23819.642705359434, 24311.14910978125, 2158.0396723458066, 2251.639737641986, 2363.166104364028, 2386.7016622922133, 2678.0894495871303, 9493.477858717768, 9553.856692149553, 9911.058449447362, 10010.9499452503, 10234.590616375335, 1813.102514255452, 1838.8712790104698, 1867.8232713390478, 1881.6424879273031, 1891.9621607567908, 121039.0, 125087.0, 125130.0, 127270.36364818209, 128278.0, 11049.889501105046, 11257.380844053492, 11417.657470275904, 11710.238834475731, 11723.824142637868, 14130.293485325692, 14211.715765684628, 14262.0, 14267.85732142686, 14291.57125286243, 8144.348452123816, 8447.619857106445, 8460.492370457792, 8482.02456717473, 8566.27186689126, 7809.492382995087, 8242.928419305572, 8437.620307086196, 8461.2807911327, 8635.827283454295, 4383.473983122046, 4452.154090722753, 4468.396766436489, 4542.432195975503, 4556.0432309214775, 1896.1088288504382, 1939.362467044818, 1963.5581994051224, 1965.1648049579157, 1965.88204707718, 834.6160766047686, 893.4632432216578, 916.2624087609405, 929.340168480373, 969.3069455339325, 20042.59914801725, 20154.798452015584, 20921.790782091983, 21031.894840526005, 21104.366868993955, 906.7733066733317, 937.9249660027182, 964.2045312616979, 985.7732721474101, 992.4739887859446, 8654.30765538768, 11127.666170014912, 11184.832227516594, 11198.440077996067, 11272.66182014541, 419954.0, 448150.0, 451404.9438022508, 543927.0, 546917.0, 109065.45467272613, 112677.43661281806, 140830.2958485201, 144208.0, 144820.0, 937.8593211018402, 978.4911845840265, 998.9800203995879, 1000.7448100734266, 1001.1690297053428, 15586.532404027897, 18481.81518184801, 18829.717554236973, 18854.24583016664, 18987.050647467295, 42497.93755156146, 47440.52559474361, 54286.7285663585, 54523.0, 55478.16782748181, 74098.25901740913, 75240.24759752332, 75760.0, 76013.4797304062, 77323.84014239679, 6778.593284402755, 7413.0, 7545.50954187976, 7882.0, 8060.355171586376, 10524.631637892637, 12665.620031399072, 12839.935800320938, 13097.607071787861, 13472.9895257858, 9885.50572471388, 10096.646617368357, 11074.501647425725, 11444.427778611203, 11567.942160289145, 10882.245625259273, 11107.278026928223, 11147.498362573866, 11425.828612570653, 11521.711957201138, 11121.332720036824, 11270.380129092979, 11695.298282103102, 11764.411779411166, 11859.110566707704, 7323.7436689715605, 7343.118825740945, 7490.775276741707, 7641.2740789624895, 7674.884876726742, 10582.312149710244, 11359.375234361858, 11367.976882080653, 11577.24747891418, 11670.299782013106], "value": 9347.87817501002, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1513.4097701896255, 1524.3140586735965, 1550.751879699248, 1850.8982005989665, 1860.7394964704936, 8051.476654017485, 8344.707935222268, 8431.957840210798, 9027.413218140824, 9053.049429809875, 7659.004329437179, 8420.831583368328, 8857.247133993618, 8974.147455991686, 9003.504807235617, 2076.8234700050516, 2260.728712554496, 2311.5723591135693, 2370.2415227127326, 2377.976220237793, 2210.9447263818374, 2323.337848713117, 2367.5501654685554, 2370.8221883358733, 2386.4988352446007, 2106.610277098734, 2335.346103091137, 2352.741198468167, 2355.2463211772238, 2359.079958816063, 2311.63013917774, 2326.0346956013236, 2331.63859601762, 2331.778481044298, 2333.6849525314037, 2262.8755418452142, 2264.399934017487, 2265.728112626479, 2269.1150451323997, 2292.32376448948, 8969.551522423853, 9032.909670903338, 9070.775445314865, 9073.13805188506, 9108.68119615817, 5251.501107394792, 5340.798320377923, 5382.650127741685, 5442.6462279952075, 5452.727363631842, 3082.0, 3572.4105522588743, 3640.2537479816574, 3642.7085833133287, 3715.925681486382, 1046.466302185883, 1075.0700643943012, 1088.4285749981243, 1088.434014312561, 1102.5259138570411, 11824.940875295655, 11888.999999999913, 12011.879881201163, 12048.698782530419, 12050.698732531757, 3822.9235415291537, 4538.228501154812, 4772.689775164603, 4800.183968725325, 4817.566419022235, 8425.70510032146, 9757.804843903083, 9884.258680598983, 9976.401415915068, 9986.60053597848, 5333.413324534298, 5382.892342153135, 5389.892202155935, 5392.40683524812, 5395.244665746799, 4432.180046691373, 4575.17646823575, 4881.267809828483, 4909.042736666387, 4954.826581069718, 3378.3750006248924, 3786.8674596388996, 3863.594322596087, 3906.199229158016, 3971.324874771296, 1299.772539805549, 1308.7566811529086, 1309.3387839141174, 1313.6387493439381, 1319.6370997975441, 846.716350022737, 853.0616322045701, 902.837489251941, 916.5921165081608, 932.9160375566096, 5633.239512665819, 5891.204687367236, 6036.0, 6059.151718759377, 6166.290876549165, 944.5548311084052, 952.7094236258008, 955.3217215776738, 956.0964888180603, 992.7865508915618, 3886.844526218976, 4006.338954072604, 4043.230387184788, 4067.8169482373364, 4091.2022155679356, 8134.552599607078, 10579.94710026445, 10784.56861725538, 10793.514291856885, 10807.945960270148, 10769.83845242322, 10780.892191078146, 10802.621908233174, 10809.40548269837, 10820.72948176286, 7832.316052048723, 8118.2693557580105, 8327.8334433311, 8337.416380853281, 8346.66613335472, 5902.793402230901, 6061.484773794277, 6066.0, 6163.459135216196, 6233.127362169391, 8279.509688256172, 9714.417134971924, 10031.598736050477, 10277.794444110927, 10291.0, 9932.900670993198, 10156.74608134773, 10222.948885255526, 10280.845787313046, 10296.07335339823, 6890.44876409876, 6935.445164386736, 8239.794005149679, 8326.04250511199, 8469.407141500036, 6997.930020699931, 8235.794105147419, 8328.83342333162, 8394.622241999003, 8416.074231834617, 6523.347665233438, 7439.070116235471, 7513.361364284097, 7537.736179233701, 7562.395008399425, 6330.651814150266, 7254.492185546961, 7397.963010184914, 7408.481406301723, 7534.924650753642, 6898.827529311808, 6988.860222795617, 7000.439964802702, 7076.716931322895, 7083.645817708991, 4704.917868890106, 4870.342503761947, 4945.7527123644395, 5069.797208111634, 5151.124308867426, 4957.05570120883, 5006.324146240211, 5091.923621145612, 5193.584513238856, 5230.215467679878], "value": 4582.8862286564145, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [288.25054857370833, 293.6432234834675, 309.3796937141032, 312.32849373846244, 315.3724089062763, 5258.842234732954, 5352.671587859284, 5452.836414907554, 5562.582806289529, 5585.52523035542, 4610.077984403116, 5016.663918494197, 5375.784968601261, 5419.891602167954, 5488.451154884509, 3645.4714066460347, 4175.540690524048, 4215.0, 4217.34631132175, 4239.138581457504, 3468.5317482139967, 3475.391806433877, 3656.8537258509627, 4241.1093670329265, 4245.532991370947, 3407.965920340802, 3946.7237293389476, 4079.6940229482757, 4161.271777438951, 4192.203481338551, 3456.3778519866414, 4082.2856000199845, 4128.669706423479, 4143.3784932260105, 4156.91686166278, 2961.570572267009, 3422.8070613498267, 3562.7352289937216, 3593.1735700788704, 3598.5170232539726, 4641.445115886148, 5134.948650513485, 5378.408375078739, 5409.5672346212605, 5456.281271399496, 3952.0, 4779.330893674888, 4799.424069111694, 4801.73590452525, 4811.6286858245585, 5300.231466437345, 5589.608727389084, 5798.652080875118, 5835.416458354173, 5887.35239123696, 3586.1531311374665, 3592.371335016388, 3858.787766672832, 3900.6294402031763, 3953.327934251184, 5687.7434821696215, 5805.4194580542035, 5902.4063502854415, 6089.147519347295, 6139.0, 2953.2616845788552, 3598.586162591283, 3677.0, 3925.0579860833195, 3938.547067087264, 3459.60214575322, 3776.15036616759, 3927.3716205407, 3937.015746063484, 3989.1423343981182, 3779.7165212609166, 4725.929111063337, 4731.597814185833, 4733.9763301183275, 4761.785719642625, 4835.661503694778, 5792.478676919098, 5807.448292412214, 5879.706014699248, 5901.90814699282, 1344.1800501694154, 1368.4868174434587, 1372.1698372484702, 1372.3412761874356, 1376.4287820554357, 699.192432740186, 771.8301973565807, 824.8474032304043, 828.1759649149135, 837.8282452097305, 3621.7826930384263, 4215.894602634898, 4258.615949816322, 4464.531224221475, 4653.673702994631, 4164.625673527728, 4490.618297444688, 4612.238980568236, 4632.374629424984, 4980.975095124501, 4010.8997275067773, 4046.352583586612, 4234.428352172479, 4607.562281583244, 4644.86065418038, 2447.608382658766, 2465.59317712579, 2517.848929064262, 2556.693196816394, 2601.6097585361968, 5039.042581909425, 5126.333941469037, 5153.2785410042625, 5179.741012949337, 5179.8446046618665, 4070.776107314126, 4522.751248681288, 5093.8726531836255, 5115.411727651293, 5164.591369695982, 4306.956930430718, 4308.999999999937, 4531.818727250873, 4717.504662010508, 5130.330156416182, 4445.199864024377, 4888.557875426686, 5017.924731129109, 5041.798328066836, 5062.924056139234, 4504.481984571817, 4732.911430370965, 4983.832033065628, 4991.675541089818, 5034.219695947134, 3933.7836418996085, 5100.041975654186, 5110.71891045996, 5131.974340128276, 5160.48395160496, 3307.074019274607, 3434.4676575130893, 3437.9828100859336, 3443.5351227583956, 3491.650834916488, 4170.268474149437, 5021.347224860745, 5026.3810056759075, 5058.875272385533, 5059.291699162194, 4073.4093556434746, 4466.798994045341, 4588.701734387254, 4867.659263851497, 4931.97534012342, 4309.978450107729, 4815.412517674355, 4872.780724867319, 4888.608911287018, 4917.532834380728, 4058.7158898876796, 4168.103857670615, 4213.1251593042025, 4224.852130175429, 4684.992726563805, 3670.5957597772435, 3980.960190398059, 4864.391951006124, 4885.75413269614, 4907.705537667752, 4085.243345361523, 4307.5477074906685, 4485.228334807798, 4636.704831108623, 4733.5266473352385], "value": 3797.9432862149515, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [652.7454292825797, 695.5756988237174, 703.6200451756052, 704.9295070492951, 749.6813854112002, 15208.923955380222, 15685.21573921305, 15949.963252388601, 16057.437989670363, 16103.59741006474, 14317.0, 14366.569002929902, 14580.489682861102, 14593.854061459382, 14615.12309261445, 679.4496457869117, 698.1238545625258, 851.9148085191463, 861.2808305065278, 883.244825674049, 859.4671303791658, 863.8704194370832, 890.3366991591273, 891.1489527501253, 893.1871996483202, 942.4486675294961, 944.1596978688972, 944.2540393089452, 944.5560586524357, 945.6122989574276, 923.7736754495124, 934.0892629686069, 944.4569372610777, 944.5796620503844, 945.7588314979671, 685.9862802743967, 703.2124021096405, 703.989440158398, 705.1362081450234, 707.1337611426013, 15348.769768453481, 15373.308201130976, 15461.304241309168, 15482.0, 15523.0, 14311.427542898376, 14392.856071439257, 14410.495632652912, 14522.854771452256, 14666.70666586673, 11114.4998475068, 11903.523859045628, 11911.642650720492, 12189.756204875854, 12488.875111248954, 3769.151940813295, 3792.728949808816, 3838.673712734421, 3946.171304026158, 3961.0889495415927, 106736.46631766869, 107313.46343268311, 107741.0, 107811.0, 111173.0, 11084.445777711082, 11112.722181945353, 11150.553977840958, 11282.323060616389, 11568.942155289169, 22212.88893555522, 22290.554188916132, 22328.0, 22343.66484502734, 22358.441038974157, 7877.960610196912, 7950.23050612209, 7977.441579089524, 7998.800029999296, 8169.387295952827, 4314.9634527674, 4394.099209662011, 4443.511213766483, 4453.087117141044, 4454.331850222493, 2945.23423909785, 3010.5183170692585, 3048.420800047984, 3145.1665308693214, 3349.966500335014, 589.7257775134583, 595.3540408656656, 599.8350453625201, 613.7882430561449, 631.9052142178618, 618.848382146377, 636.9745210191633, 651.6578796132069, 658.1279804259375, 664.2925284571916, 7163.6060016699585, 8396.664133434717, 9083.636654533744, 9222.95388523053, 9290.467072933026, 637.37537213531, 663.9269680335159, 671.375620672779, 677.8542613338058, 689.4243306838846, 4489.910201795946, 4582.358469814229, 4667.509911459316, 4670.526633275151, 4741.122892264942, 99998.50003749819, 125042.37478812548, 151372.0, 154228.0, 154670.0, 132085.0, 134505.0, 136225.0, 139460.0, 139857.0, 92465.53767231255, 115108.99999999833, 115820.42089789498, 116283.0, 119623.40188299003, 7495.500899819949, 8900.332475064266, 9011.053839347036, 9110.271178305587, 9131.530018592892, 59327.0, 64858.0, 65604.34395656173, 66278.0, 66281.67436651337, 74449.51100978059, 81263.3747325062, 90510.0, 99315.50342248242, 100905.0, 9941.0, 11259.380734059378, 11661.941690291833, 11888.881111189123, 12596.433160507615, 12827.935860320638, 12930.870691292967, 13075.738485230433, 13323.400446979715, 13597.592072237849, 11942.820857686967, 12069.517219311128, 12165.391730413621, 12180.025597952319, 12296.877031229931, 11830.704232394259, 12012.459439325427, 12173.69565760863, 12361.32012739272, 12589.181703189264, 11702.180847340604, 12035.097367697283, 12066.215695979734, 12144.817827732768, 12552.058595605184, 10148.64479743235, 10262.794744105226, 10392.58429662805, 10496.68509944703, 10693.518791654238, 7234.927650723425, 8742.694005710024, 9307.650390693447, 9804.264680148875, 10314.48427578603], "value": 7634.032836687989, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1565.5, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [108.0, 118.0, 113.0, 118.0, 117.0, 117.0, 118.0, 107.0, 108.0, 152.0], "value": 117.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 122.0, 146.0, 185.0, 120.0, 119.0, 115.0, 114.0, 167.0, 146.0], "value": 138.2, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [197.0, 205.0, 207.0, 224.0, 200.0, 200.0, 194.0, 193.0, 199.0, 200.0], "value": 201.9, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 59.0, 61.0, 97.0, 62.0, 60.0, 61.0, 61.0, 78.0, 61.0], "value": 68.4, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 70.0, 89.0, 73.0, 70.0, 71.0, 88.0, 69.0, 70.0, 87.0], "value": 77.9, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 135.0, 90.0, 87.0, 89.0, 87.0, 92.0, 80.0, 78.0, 104.0], "value": 94.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [123.0, 150.0, 153.0, 99.0, 96.0, 95.0, 97.0, 95.0, 96.0, 96.0], "value": 110.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 93.0, 86.0, 85.0, 95.0, 89.0, 93.0, 91.0, 87.0, 90.0], "value": 89.6, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 59.0, 58.0, 59.0, 57.0, 59.0, 57.0, 58.0], "value": 57.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 82.0, 70.0, 81.0, 83.0, 78.0, 69.0, 72.0, 74.0, 82.0], "value": 76.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [89.0, 109.0, 104.0, 87.0, 88.0, 93.0, 84.0, 94.0, 113.0, 111.0], "value": 97.2, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [164.0, 168.0, 166.0, 141.0, 149.0, 148.0, 181.0, 167.0, 209.0, 149.0], "value": 164.2, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [179.0, 177.0, 175.0, 195.0, 161.0, 233.0, 229.0, 173.0, 199.0, 230.0], "value": 195.1, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [71.0, 75.0, 76.0, 69.0, 72.0, 66.0, 86.0, 91.0, 90.0, 72.0], "value": 76.8, "unit": "score"}]}]} 04:55:23 INFO - Return code: 0 04:55:23 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:55:23 ERROR - # TBPL SUCCESS # 04:55:23 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 04:55:23 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 04:55:23 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 04:55:23 INFO - 2016-10-19 04:55:23.970 screenresolution[1963:17726] starting screenresolution argv=screenresolution get 04:55:23 INFO - 2016-10-19 04:55:23.973 screenresolution[1963:17726] Display 0: 1600x1200x32@60 04:55:23 INFO - 2016-10-19 04:55:23.979 screenresolution[1964:17729] starting screenresolution argv=screenresolution list 04:55:23 INFO - Available Modes on Display 0 04:55:23 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 04:55:23 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 04:55:23 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 04:55:23 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 04:55:24 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 04:55:24 INFO - Intel Iris: 04:55:24 INFO - Chipset Model: Intel Iris 04:55:24 INFO - Type: GPU 04:55:24 INFO - Bus: Built-In 04:55:24 INFO - VRAM (Dynamic, Max): 1536 MB 04:55:24 INFO - Vendor: Intel (0x8086) 04:55:24 INFO - Device ID: 0x0a2e 04:55:24 INFO - Revision ID: 0x0009 04:55:24 INFO - Displays: 04:55:24 INFO - Display: 04:55:24 INFO - Resolution: 1600 x 1200 @ 60 Hz 04:55:24 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 04:55:24 INFO - Display Serial Number: 1600x1200 60 04:55:24 INFO - Main Display: Yes 04:55:24 INFO - Mirror: Off 04:55:24 INFO - Online: Yes 04:55:24 INFO - Rotation: Supported 04:55:24 INFO - Return code: 0 04:55:24 INFO - Running post-action listener: _resource_record_post_action 04:55:24 INFO - [mozharness: 2016-10-19 11:55:24.213910Z] Finished run-tests step (success) 04:55:24 INFO - Running post-run listener: _resource_record_post_run 04:55:24 INFO - Total resource usage - Wall time: 1212s; CPU: 55.0%; Read bytes: 1545728; Write bytes: 712304640; Read time: 15; Write time: 2061 04:55:24 INFO - TinderboxPrint: CPU usage
54.7% 04:55:24 INFO - TinderboxPrint: I/O read bytes / time
1,545,728 / 15 04:55:24 INFO - TinderboxPrint: I/O write bytes / time
712,304,640 / 2,061 04:55:24 INFO - TinderboxPrint: CPU idle
2,194.7 (45.3%) 04:55:24 INFO - TinderboxPrint: CPU system
985.2 (20.3%) 04:55:24 INFO - TinderboxPrint: CPU user
1,670.0 (34.4%) 04:55:24 INFO - TinderboxPrint: Swap in / out
407,465,984 / 0 04:55:24 INFO - install - Wall time: 35s; CPU: 55.0%; Read bytes: 390949376; Write bytes: 385284096; Read time: 29281; Write time: 712 04:55:24 INFO - run-tests - Wall time: 1178s; CPU: 55.0%; Read bytes: 1545728; Write bytes: 303300608; Read time: 15; Write time: 1297 04:55:24 INFO - Running post-run listener: _upload_blobber_files 04:55:24 INFO - Blob upload gear active. 04:55:24 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:55:24 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 04:55:24 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', 'Autoland', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:55:24 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 Autoland -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:55:25 INFO - (blobuploader) - INFO - Open directory for files ... 04:55:25 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:55:25 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:55:25 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:55:26 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:55:26 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:55:26 INFO - (blobuploader) - INFO - Done attempting. 04:55:26 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:55:26 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:55:26 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:55:27 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:55:27 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:55:27 INFO - (blobuploader) - INFO - Done attempting. 04:55:27 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:55:27 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:55:27 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:55:29 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:55:29 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:55:29 INFO - (blobuploader) - INFO - Done attempting. 04:55:29 INFO - (blobuploader) - INFO - Iteration through files over. 04:55:29 INFO - Return code: 0 04:55:29 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:55:29 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:55:29 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d09eb73a42331585fa358a2f62df02c28e8e6418addafb6617ecddff8f0e00e13b3235fa0f053b3bacf18bbadb256e290b55c886c911226bf6d355220e32111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7efab7efb271773af5cd5d5f3d55c4ef50439865b822fc87d9876d252cfeb472f45f13907c5429d2f9caf9ba168f5496b6e95837a5c507543748b0671b7d7bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e5f14fe2061313b771ca2236ceaf06c45816e9ef1d5876a8e3e9534b6a535f17f2a8dfc58d5e6029326720a16fc9195e8f1691299300202c797f7b4dbf02d34"} 04:55:29 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:55:29 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:55:29 INFO - Contents: 04:55:29 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d09eb73a42331585fa358a2f62df02c28e8e6418addafb6617ecddff8f0e00e13b3235fa0f053b3bacf18bbadb256e290b55c886c911226bf6d355220e32111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7efab7efb271773af5cd5d5f3d55c4ef50439865b822fc87d9876d252cfeb472f45f13907c5429d2f9caf9ba168f5496b6e95837a5c507543748b0671b7d7bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e5f14fe2061313b771ca2236ceaf06c45816e9ef1d5876a8e3e9534b6a535f17f2a8dfc58d5e6029326720a16fc9195e8f1691299300202c797f7b4dbf02d34"} 04:55:29 INFO - Running post-run listener: copy_logs_to_upload_dir 04:55:29 INFO - Copying logs to upload dir... 04:55:29 INFO - mkdir: /builds/slave/test/build/upload/logs 04:55:29 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1274.459134 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 14 secs) (at 2016-10-19 04:55:29.502045) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 04:55:29.505626) ========= 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.W2uPWnqkIu/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.QYBq22X9jT/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/Autoland/sha512/d09eb73a42331585fa358a2f62df02c28e8e6418addafb6617ecddff8f0e00e13b3235fa0f053b3bacf18bbadb256e290b55c886c911226bf6d355220e32111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7efab7efb271773af5cd5d5f3d55c4ef50439865b822fc87d9876d252cfeb472f45f13907c5429d2f9caf9ba168f5496b6e95837a5c507543748b0671b7d7bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e5f14fe2061313b771ca2236ceaf06c45816e9ef1d5876a8e3e9534b6a535f17f2a8dfc58d5e6029326720a16fc9195e8f1691299300202c797f7b4dbf02d34"} build_url:https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011257 build_url: 'https://queue.taskcluster.net/v1/task/U1rgWTQqRYWB37e7koptXg/artifacts/public/build/firefox-52.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/d09eb73a42331585fa358a2f62df02c28e8e6418addafb6617ecddff8f0e00e13b3235fa0f053b3bacf18bbadb256e290b55c886c911226bf6d355220e32111f", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/a7efab7efb271773af5cd5d5f3d55c4ef50439865b822fc87d9876d252cfeb472f45f13907c5429d2f9caf9ba168f5496b6e95837a5c507543748b0671b7d7bb", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Autoland/sha512/0e5f14fe2061313b771ca2236ceaf06c45816e9ef1d5876a8e3e9534b6a535f17f2a8dfc58d5e6029326720a16fc9195e8f1691299300202c797f7b4dbf02d34"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-10-19 04:55:29.538643) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:55:29.538975) ========= 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.W2uPWnqkIu/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.QYBq22X9jT/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.005134 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-10-19 04:55:29.589566) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 04:55:29.590299) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-10-19 04:55:29.590696) ========= ========= Total master_lag: 0.18 =========