builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0154 starttime: 1481857966.06 results: success (0) buildid: 20161215174549 builduid: 7f7b29f30af7493590813cb09a8bbe76 revision: fe17931bfd5f0658a94f40a71b0463cfa1e03c6c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:46.058176) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:46.059195) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:46.059943) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:46.094821) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-15 19:12:46.095596) ========= 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.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-12-15 19:12:46-- 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% 159M=0s 2016-12-15 19:12:46 (159 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.124236 ========= master_lag: 3.42 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 3 secs) (at 2016-12-15 19:12:49.637751) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:49.638208) ========= 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.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.026734 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:49.687200) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 19:12:49.687602) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev fe17931bfd5f0658a94f40a71b0463cfa1e03c6c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo mozilla-central --rev fe17931bfd5f0658a94f40a71b0463cfa1e03c6c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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-12-15 19:12:49,782 truncating revision to first 12 chars 2016-12-15 19:12:49,783 Setting DEBUG logging. 2016-12-15 19:12:49,783 attempt 1/10 2016-12-15 19:12:49,783 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/fe17931bfd5f?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2016-12-15 19:12:50,551 unpacking tar archive at: mozilla-central-fe17931bfd5f/testing/mozharness/ program finished with exit code 0 elapsedTime=1.032964 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2016-12-15 19:12:50.744809) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:50.745195) ========= script_repo_revision: fe17931bfd5f0658a94f40a71b0463cfa1e03c6c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:50.745656) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:50.745982) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2016-12-15 19:12:50.765936) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-15 19:12:50.766244) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:12:50 INFO - MultiFileLogger online at 20161215 19:12:50 in /builds/slave/test 19:12:50 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 19:12:50 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 19:12:50 INFO - {'append_to_log': False, 19:12:50 INFO - 'base_work_dir': '/builds/slave/test', 19:12:50 INFO - 'blob_upload_branch': 'Firefox', 19:12:50 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 19:12:50 INFO - 'branch': 'Firefox', 19:12:50 INFO - 'buildbot_json_path': 'buildprops.json', 19:12:50 INFO - 'config_files': ('talos/mac_config.py',), 19:12:50 INFO - 'default_actions': ('clobber', 19:12:50 INFO - 'read-buildbot-config', 19:12:50 INFO - 'download-and-extract', 19:12:50 INFO - 'populate-webroot', 19:12:50 INFO - 'create-virtualenv', 19:12:50 INFO - 'install', 19:12:50 INFO - 'run-tests'), 19:12:50 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 19:12:50 INFO - 'download_minidump_stackwalk': True, 19:12:50 INFO - 'download_symbols': 'ondemand', 19:12:50 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 19:12:50 INFO - 'tooltool.py': '/tools/tooltool.py', 19:12:50 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 19:12:50 INFO - '/tools/misc-python/virtualenv.py')}, 19:12:50 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 19:12:50 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 19:12:50 INFO - 'installer_path': 'installer.exe', 19:12:50 INFO - 'log_level': 'info', 19:12:50 INFO - 'log_name': 'talos', 19:12:50 INFO - 'log_to_console': True, 19:12:50 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 19:12:50 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 19:12:50 INFO - 'opt_config_files': (), 19:12:50 INFO - 'pip_index': False, 19:12:50 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:12:50 INFO - 'cmd': ('bash', 19:12:50 INFO - '-c', 19:12:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:12:50 INFO - 'enabled': True, 19:12:50 INFO - 'halt_on_failure': False, 19:12:50 INFO - 'name': 'check_screen_resolution'},), 19:12:50 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 19:12:50 INFO - 'cmd': ('bash', 19:12:50 INFO - '-c', 19:12:50 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 19:12:50 INFO - 'enabled': True, 19:12:50 INFO - 'halt_on_failure': False, 19:12:50 INFO - 'name': 'check_screen_resolution'},), 19:12:50 INFO - 'run_cmd_checks_enabled': True, 19:12:50 INFO - 'sps_profile': False, 19:12:50 INFO - 'sps_profile_interval': 0, 19:12:50 INFO - 'suite': 'dromaeojs-e10s', 19:12:50 INFO - 'system_bits': '32', 19:12:50 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 19:12:50 INFO - 'title': 't-yosemite-r7-0154', 19:12:50 INFO - 'tooltool_cache': '/builds/tooltool_cache', 19:12:50 INFO - 'use_talos_json': True, 19:12:50 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 19:12:50 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 19:12:50 INFO - 'work_dir': 'build'} 19:12:50 INFO - [mozharness: 2016-12-16 03:12:50.973963Z] Running clobber step. 19:12:50 INFO - Running pre-action listener: _resource_record_pre_action 19:12:50 INFO - Running main action method: clobber 19:12:50 INFO - rmtree: /builds/slave/test/build 19:12:50 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 19:12:53 INFO - Running post-action listener: _resource_record_post_action 19:12:53 INFO - [mozharness: 2016-12-16 03:12:53.541258Z] Finished clobber step (success) 19:12:53 INFO - [mozharness: 2016-12-16 03:12:53.541448Z] Running read-buildbot-config step. 19:12:53 INFO - Running pre-action listener: _resource_record_pre_action 19:12:53 INFO - Running main action method: read_buildbot_config 19:12:53 INFO - Using buildbot properties: 19:12:53 INFO - { 19:12:53 INFO - "project": "", 19:12:53 INFO - "product": "firefox", 19:12:53 INFO - "script_repo_revision": "production", 19:12:53 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 19:12:53 INFO - "repository": "", 19:12:53 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 19:12:53 INFO - "buildid": "20161215174549", 19:12:53 INFO - "pgo_build": "False", 19:12:53 INFO - "basedir": "/builds/slave/test", 19:12:53 INFO - "buildnumber": 17, 19:12:53 INFO - "slavename": "t-yosemite-r7-0154", 19:12:53 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 19:12:53 INFO - "platform": "yosemite_r7", 19:12:53 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 19:12:53 INFO - "branch": "mozilla-central", 19:12:53 INFO - "repo_path": "mozilla-central", 19:12:53 INFO - "revision": "fe17931bfd5f0658a94f40a71b0463cfa1e03c6c", 19:12:53 INFO - "stage_platform": "macosx64", 19:12:53 INFO - "builduid": "7f7b29f30af7493590813cb09a8bbe76", 19:12:53 INFO - "slavebuilddir": "test" 19:12:53 INFO - } 19:12:53 INFO - Found installer url https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 19:12:53 INFO - Running post-action listener: _resource_record_post_action 19:12:53 INFO - [mozharness: 2016-12-16 03:12:53.545960Z] Finished read-buildbot-config step (success) 19:12:53 INFO - [mozharness: 2016-12-16 03:12:53.546167Z] Running download-and-extract step. 19:12:53 INFO - Running pre-action listener: _resource_record_pre_action 19:12:53 INFO - Running main action method: download_and_extract 19:12:53 INFO - mkdir: /builds/slave/test/build/tests 19:12:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:12:53 INFO - trying https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 19:12:53 INFO - Downloading https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:12:53 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json'}, attempt #1 19:12:54 INFO - Downloaded 1199 bytes. 19:12:54 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 19:12:54 INFO - Using the following test package requirements: 19:12:54 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:12:54 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 19:12:54 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 19:12:54 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'jsshell-mac.zip'], 19:12:54 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 19:12:54 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 19:12:54 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 19:12:54 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 19:12:54 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 19:12:54 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 19:12:54 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 19:12:54 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 19:12:54 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:12:54 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:12:54 INFO - Fetch https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:12:55 INFO - Expected file size: 16277183 19:12:55 INFO - Obtained file size: 16277183 19:13:02 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip', u'firefox-53.0a1.en-US.mac.talos.tests.zip'] for test suite category: talos 19:13:02 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 19:13:02 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 19:13:02 INFO - Fetch https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 19:13:03 INFO - Expected file size: 16277183 19:13:03 INFO - Obtained file size: 16277183 19:13:09 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 19:13:09 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 19:13:09 INFO - Fetch https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 19:13:11 INFO - Expected file size: 14409734 19:13:11 INFO - Obtained file size: 14409734 19:13: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')]} 19:13:11 INFO - trying https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:13:11 INFO - Downloading https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 19:13:11 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg'}, attempt #1 19:13:13 INFO - Downloaded 69595775 bytes. 19:13:13 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:13:13 INFO - mkdir: /builds/slave/test/properties 19:13:13 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 19:13:13 INFO - Writing to file /builds/slave/test/properties/build_url 19:13:13 INFO - Contents: 19:13:13 INFO - build_url:https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 19:13:14 INFO - Running post-action listener: _resource_record_post_action 19:13:14 INFO - Running post-action listener: set_extra_try_arguments 19:13:14 INFO - [mozharness: 2016-12-16 03:13:14.208209Z] Finished download-and-extract step (success) 19:13:14 INFO - [mozharness: 2016-12-16 03:13:14.208305Z] Running populate-webroot step. 19:13:14 INFO - Running pre-action listener: _resource_record_pre_action 19:13:14 INFO - Running main action method: populate_webroot 19:13:14 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 19:13:14 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 19:13:14 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 19:13:14 INFO - '--tppagecycles', 19:13:14 INFO - '7'], 19:13:14 INFO - 'tests': ['tsvgm']}}, 19:13:14 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['tresize', 'tcanvasmark']}, 19:13:14 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 19:13:14 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['dromaeo_css', 'kraken']}, 19:13:14 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 19:13:14 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:13:14 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 19:13:14 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['damp', 'tps']}, 19:13:14 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'tests': ['damp', 'tps']}, 19:13:14 INFO - 'g3': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['dromaeo_dom']}, 19:13:14 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 19:13:14 INFO - 'g4': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['basic_compositor_video']}, 19:13:14 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 19:13:14 INFO - 'other': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'other-e10s': {'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'other-e10s_l64': {'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['a11yr', 19:13:14 INFO - 'ts_paint', 19:13:14 INFO - 'tpaint', 19:13:14 INFO - 'sessionrestore', 19:13:14 INFO - 'sessionrestore_no_auto_restore', 19:13:14 INFO - 'tabpaint']}, 19:13:14 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['tsvgx', 19:13:14 INFO - 'tsvgr_opacity', 19:13:14 INFO - 'tart', 19:13:14 INFO - 'tscrollx', 19:13:14 INFO - 'cart', 19:13:14 INFO - 'tsvg_static']}, 19:13:14 INFO - 'svgr-e10s': {'tests': ['tsvgx', 19:13:14 INFO - 'tsvgr_opacity', 19:13:14 INFO - 'tart', 19:13:14 INFO - 'tscrollx', 19:13:14 INFO - 'cart', 19:13:14 INFO - 'tsvg_static']}, 19:13:14 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'talos_options': ['--disable-e10s'], 19:13:14 INFO - 'tests': ['tp5o']}, 19:13:14 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 19:13:14 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'talos_options': ['--disable-e10s', 19:13:14 INFO - '--xperf_path', 19:13:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:13:14 INFO - 'tests': ['tp5n']}, 19:13:14 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 19:13:14 INFO - 'talos_options': ['--xperf_path', 19:13:14 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 19:13:14 INFO - 'tests': ['tp5n']}}, 19:13:14 INFO - 'talos.zip': {'path': '', 19:13:14 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 19:13:14 INFO - Running post-action listener: _resource_record_post_action 19:13:14 INFO - [mozharness: 2016-12-16 03:13:14.226514Z] Finished populate-webroot step (success) 19:13:14 INFO - [mozharness: 2016-12-16 03:13:14.226650Z] Running create-virtualenv step. 19:13:14 INFO - Running pre-action listener: _resource_record_pre_action 19:13:14 INFO - Running main action method: create_virtualenv 19:13:14 INFO - Creating virtualenv /builds/slave/test/build/venv 19:13:14 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 19:13:14 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 19:13:14 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 19:13:14 INFO - Using real prefix '/tools/python27' 19:13:14 INFO - New python executable in /builds/slave/test/build/venv/bin/python 19:13:15 INFO - Installing distribute.............................................................................................................................................................................................done. 19:13:17 INFO - Installing pip.................done. 19:13:17 INFO - Return code: 0 19:13:17 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 19:13:17 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 19:13:18 INFO - Reading from file tmpfile_stdout 19:13:18 INFO - Output received: 19:13:18 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) 19:13:18 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 19:13:18 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')]} 19:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:18 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')]} 19:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:18 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:18 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 19:13:18 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 19:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:18 INFO - 'HOME': '/Users/cltbld', 19:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:18 INFO - 'LOGNAME': 'cltbld', 19:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:18 INFO - 'MOZ_NO_REMOTE': '1', 19:13:18 INFO - 'NO_EM_RESTART': '1', 19:13:18 INFO - 'PAGER': '/bin/cat', 19:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:18 INFO - 'PWD': '/builds/slave/test', 19:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:18 INFO - 'SHELL': '/bin/bash', 19:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:18 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:18 INFO - 'USER': 'cltbld', 19:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:18 INFO - 'XPC_FLAGS': '0x0', 19:13:18 INFO - 'XPC_SERVICE_NAME': '0', 19:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:18 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 19:13:18 INFO - Cleaning up... 19:13:18 INFO - Return code: 0 19:13:18 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 19:13:18 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')]} 19:13:18 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:18 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:18 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')]} 19:13:18 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:18 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:18 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:18 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 19:13:18 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 19:13:18 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:18 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:18 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:18 INFO - 'HOME': '/Users/cltbld', 19:13:18 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:18 INFO - 'LOGNAME': 'cltbld', 19:13:18 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:18 INFO - 'MOZ_NO_REMOTE': '1', 19:13:18 INFO - 'NO_EM_RESTART': '1', 19:13:18 INFO - 'PAGER': '/bin/cat', 19:13:18 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:18 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:18 INFO - 'PWD': '/builds/slave/test', 19:13:18 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:18 INFO - 'SHELL': '/bin/bash', 19:13:18 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:18 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:18 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:18 INFO - 'USER': 'cltbld', 19:13:18 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:18 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:18 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:18 INFO - 'XPC_FLAGS': '0x0', 19:13:18 INFO - 'XPC_SERVICE_NAME': '0', 19:13:18 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:18 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:18 INFO - Downloading/unpacking psutil>=3.1.1 19:13:18 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 19:13:18 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 19:13:18 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 19:13:18 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 19:13:18 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 19:13:18 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 19:13:21 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 19:13:22 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:13:22 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:13:22 INFO - Installing collected packages: psutil 19:13:22 INFO - Running setup.py install for psutil 19:13:22 INFO - building 'psutil._psutil_osx' extension 19:13:22 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 19:13:22 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 19:13:22 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 19:13:22 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 19:13:23 INFO - building 'psutil._psutil_posix' extension 19:13:23 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 19:13:23 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 19:13:23 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 19:13:23 INFO - ^ 19:13:23 INFO - 1 warning generated. 19:13:23 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 19:13:23 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 19:13:23 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 19:13:23 INFO - Successfully installed psutil 19:13:23 INFO - Cleaning up... 19:13:23 INFO - Return code: 0 19:13:23 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 19:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:23 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:23 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:23 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:23 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 19:13:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 19:13:23 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:23 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:23 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:23 INFO - 'HOME': '/Users/cltbld', 19:13:23 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:23 INFO - 'LOGNAME': 'cltbld', 19:13:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:23 INFO - 'MOZ_NO_REMOTE': '1', 19:13:23 INFO - 'NO_EM_RESTART': '1', 19:13:23 INFO - 'PAGER': '/bin/cat', 19:13:23 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:23 INFO - 'PWD': '/builds/slave/test', 19:13:23 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:23 INFO - 'SHELL': '/bin/bash', 19:13:23 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:23 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:23 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:23 INFO - 'USER': 'cltbld', 19:13:23 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:23 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:23 INFO - 'XPC_FLAGS': '0x0', 19:13:23 INFO - 'XPC_SERVICE_NAME': '0', 19:13:23 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:23 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:23 INFO - Downloading/unpacking mozsystemmonitor==0.3 19:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:23 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:23 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:23 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:23 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:26 INFO - Downloading mozsystemmonitor-0.3.tar.gz 19:13:26 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 19:13:26 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 19:13:26 INFO - Installing collected packages: mozsystemmonitor 19:13:26 INFO - Running setup.py install for mozsystemmonitor 19:13:26 INFO - Successfully installed mozsystemmonitor 19:13:26 INFO - Cleaning up... 19:13:26 INFO - Return code: 0 19:13:26 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 19:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:26 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:26 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:13:26 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:26 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:26 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:26 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 19:13:26 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 19:13:26 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:26 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:26 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:26 INFO - 'HOME': '/Users/cltbld', 19:13:26 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:26 INFO - 'LOGNAME': 'cltbld', 19:13:26 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:26 INFO - 'MOZ_NO_REMOTE': '1', 19:13:26 INFO - 'NO_EM_RESTART': '1', 19:13:26 INFO - 'PAGER': '/bin/cat', 19:13:26 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:26 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:26 INFO - 'PWD': '/builds/slave/test', 19:13:26 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:26 INFO - 'SHELL': '/bin/bash', 19:13:26 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:26 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:26 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:26 INFO - 'USER': 'cltbld', 19:13:26 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:26 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:26 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:26 INFO - 'XPC_FLAGS': '0x0', 19:13:26 INFO - 'XPC_SERVICE_NAME': '0', 19:13:26 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:26 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:26 INFO - Downloading/unpacking jsonschema==2.5.1 19:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:26 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:26 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 19:13:26 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 19:13:26 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 19:13:29 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 19:13:29 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 19:13:29 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 19:13:29 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 19:13:29 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 19:13:29 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 19:13:29 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 19:13:29 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 19:13:29 INFO - Downloading functools32-3.2.3-2.tar.gz 19:13:29 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 19:13:29 INFO - warning: no files found matching '*.txt' 19:13:29 INFO - no previously-included directories found matching 'build' 19:13:29 INFO - no previously-included directories found matching 'dist' 19:13:29 INFO - no previously-included directories found matching '.git*' 19:13:29 INFO - Installing collected packages: jsonschema, functools32 19:13:29 INFO - Running setup.py install for functools32 19:13:29 INFO - warning: no files found matching '*.txt' 19:13:29 INFO - no previously-included directories found matching 'build' 19:13:29 INFO - no previously-included directories found matching 'dist' 19:13:29 INFO - no previously-included directories found matching '.git*' 19:13:29 INFO - Successfully installed jsonschema functools32 19:13:29 INFO - Cleaning up... 19:13:30 INFO - Return code: 0 19:13:30 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 19:13:30 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')]} 19:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:30 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')]} 19:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:30 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:30 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 19:13:30 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 19:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:30 INFO - 'HOME': '/Users/cltbld', 19:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:30 INFO - 'LOGNAME': 'cltbld', 19:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:30 INFO - 'MOZ_NO_REMOTE': '1', 19:13:30 INFO - 'NO_EM_RESTART': '1', 19:13:30 INFO - 'PAGER': '/bin/cat', 19:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:30 INFO - 'PWD': '/builds/slave/test', 19:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:30 INFO - 'SHELL': '/bin/bash', 19:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:30 INFO - 'USER': 'cltbld', 19:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:30 INFO - 'XPC_FLAGS': '0x0', 19:13:30 INFO - 'XPC_SERVICE_NAME': '0', 19:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:30 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 19:13:30 INFO - Cleaning up... 19:13:30 INFO - Return code: 0 19:13:30 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 19:13:30 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')]} 19:13:30 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:30 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:30 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')]} 19:13:30 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:30 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:30 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:30 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 19:13:30 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 19:13:30 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:30 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:30 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:30 INFO - 'HOME': '/Users/cltbld', 19:13:30 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:30 INFO - 'LOGNAME': 'cltbld', 19:13:30 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:30 INFO - 'MOZ_NO_REMOTE': '1', 19:13:30 INFO - 'NO_EM_RESTART': '1', 19:13:30 INFO - 'PAGER': '/bin/cat', 19:13:30 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:30 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:30 INFO - 'PWD': '/builds/slave/test', 19:13:30 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:30 INFO - 'SHELL': '/bin/bash', 19:13:30 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:30 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:30 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:30 INFO - 'USER': 'cltbld', 19:13:30 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:30 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:30 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:30 INFO - 'XPC_FLAGS': '0x0', 19:13:30 INFO - 'XPC_SERVICE_NAME': '0', 19:13:30 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:30 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:30 INFO - Downloading/unpacking blobuploader==1.2.4 19:13:30 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 19:13:30 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 19:13:30 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 19:13:30 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 19:13:30 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 19:13:30 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 19:13:33 INFO - Downloading blobuploader-1.2.4.tar.gz 19:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 19:13:33 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 19:13:33 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:33 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 19:13:34 INFO - Downloading docopt-0.6.1.tar.gz 19:13:34 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 19:13:34 INFO - Installing collected packages: blobuploader, requests, docopt 19:13:34 INFO - Running setup.py install for blobuploader 19:13:34 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 19:13:34 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 19:13:34 INFO - Running setup.py install for requests 19:13:34 INFO - Running setup.py install for docopt 19:13:35 INFO - Successfully installed blobuploader requests docopt 19:13:35 INFO - Cleaning up... 19:13:35 INFO - Return code: 0 19:13:35 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:35 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')]} 19:13:35 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:35 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:35 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')]} 19:13:35 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:35 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:35 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:35 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 19:13:35 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 19:13:35 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:35 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:35 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:35 INFO - 'HOME': '/Users/cltbld', 19:13:35 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:35 INFO - 'LOGNAME': 'cltbld', 19:13:35 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:35 INFO - 'MOZ_NO_REMOTE': '1', 19:13:35 INFO - 'NO_EM_RESTART': '1', 19:13:35 INFO - 'PAGER': '/bin/cat', 19:13:35 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:35 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:35 INFO - 'PWD': '/builds/slave/test', 19:13:35 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:35 INFO - 'SHELL': '/bin/bash', 19:13:35 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:35 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:35 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:35 INFO - 'USER': 'cltbld', 19:13:35 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:35 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:35 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:35 INFO - 'XPC_FLAGS': '0x0', 19:13:35 INFO - 'XPC_SERVICE_NAME': '0', 19:13:35 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:35 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:13:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-J3iyDD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:13:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-RhrcyO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:13:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-aLl6OL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:13:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-CPIrnS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:13:35 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:13:35 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-5Hh8BD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-UF4D7x-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-6cdLXW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-zJVqzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-lbw8lq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-FrSadJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QglKmY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-HDz_Yu-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-9ATZ0O-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Ed9N5Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:13:36 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:36 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-QL1yUb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:13:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-ycv0Sw-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:13:37 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:13:37 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-1qxdTG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:13:37 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 19:13:37 INFO - Running setup.py install for manifestparser 19:13:37 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 19:13:37 INFO - Running setup.py install for mozcrash 19:13:37 INFO - Running setup.py install for mozdebug 19:13:37 INFO - Running setup.py install for mozdevice 19:13:37 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 19:13:37 INFO - Installing dm script to /builds/slave/test/build/venv/bin 19:13:37 INFO - Running setup.py install for mozfile 19:13:37 INFO - Running setup.py install for mozhttpd 19:13:38 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Running setup.py install for mozinfo 19:13:38 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Running setup.py install for mozInstall 19:13:38 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Running setup.py install for mozleak 19:13:38 INFO - Running setup.py install for mozlog 19:13:38 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Running setup.py install for moznetwork 19:13:38 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 19:13:38 INFO - Running setup.py install for mozprocess 19:13:39 INFO - Running setup.py install for mozprofile 19:13:39 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 19:13:39 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 19:13:39 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 19:13:39 INFO - Running setup.py install for mozrunner 19:13:39 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 19:13:39 INFO - Running setup.py install for mozscreenshot 19:13:39 INFO - Running setup.py install for moztest 19:13:39 INFO - Running setup.py install for mozversion 19:13:39 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 19:13:39 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 19:13:39 INFO - Cleaning up... 19:13:39 INFO - Return code: 0 19:13:39 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:39 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')]} 19:13:39 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:39 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:39 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')]} 19:13:39 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:39 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:39 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:39 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 19:13:39 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 19:13:39 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:39 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:39 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:39 INFO - 'HOME': '/Users/cltbld', 19:13:39 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:39 INFO - 'LOGNAME': 'cltbld', 19:13:39 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:39 INFO - 'MOZ_NO_REMOTE': '1', 19:13:39 INFO - 'NO_EM_RESTART': '1', 19:13:39 INFO - 'PAGER': '/bin/cat', 19:13:39 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:39 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:39 INFO - 'PWD': '/builds/slave/test', 19:13:39 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:39 INFO - 'SHELL': '/bin/bash', 19:13:39 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:39 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:39 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:39 INFO - 'USER': 'cltbld', 19:13:39 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:39 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:39 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:39 INFO - 'XPC_FLAGS': '0x0', 19:13:39 INFO - 'XPC_SERVICE_NAME': '0', 19:13:39 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:40 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-g9h4wF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-p8VMgn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 19:13:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 from file:///builds/slave/test/build/tests/mozbase/mozcrash in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-s483cA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-PPNPw0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 19:13:40 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 from file:///builds/slave/test/build/tests/mozbase/mozdevice in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-JedNe0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-qxZSrj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-Yf_Ccs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-D_pyKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 19:13:40 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)) 19:13:40 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 19:13:40 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-iryY9Z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-J6hvQe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-T5UziF-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-7URPVv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-NJQ4jk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rvTQoA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 19:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 from file:///builds/slave/test/build/tests/mozbase/mozrunner in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 14)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-oSEezx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-f5F8jT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 19:13:41 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)) 19:13:41 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 19:13:41 INFO - Running setup.py (path:/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/pip-rbCkss-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 19:13:41 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)) 19:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 19:13:41 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)) 19:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:41 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 19:13:41 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 19:13:41 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 19:13:41 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 19:13:41 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 19:13:41 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 19:13:41 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 19:13:41 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 19:13:44 INFO - Downloading blessings-1.6.tar.gz 19:13:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 19:13:44 INFO - Installing collected packages: blessings 19:13:44 INFO - Running setup.py install for blessings 19:13:45 INFO - Successfully installed blessings 19:13:45 INFO - Cleaning up... 19:13:45 INFO - Return code: 0 19:13:45 INFO - Done creating virtualenv /builds/slave/test/build/venv. 19:13:45 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:13:45 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:13:45 INFO - Reading from file tmpfile_stdout 19:13:45 INFO - Current package versions: 19:13:45 INFO - blessings == 1.6 19:13:45 INFO - blobuploader == 1.2.4 19:13:45 INFO - docopt == 0.6.1 19:13:45 INFO - functools32 == 3.2.3-2 19:13:45 INFO - jsonschema == 2.5.1 19:13:45 INFO - manifestparser == 1.1 19:13:45 INFO - mozInstall == 1.12 19:13:45 INFO - mozcrash == 1.0 19:13:45 INFO - mozdebug == 0.1 19:13:45 INFO - mozdevice == 0.49 19:13:45 INFO - mozfile == 1.2 19:13:45 INFO - mozhttpd == 0.7 19:13:45 INFO - mozinfo == 0.9 19:13:45 INFO - mozleak == 0.1 19:13:45 INFO - mozlog == 3.4 19:13:45 INFO - moznetwork == 0.27 19:13:45 INFO - mozprocess == 0.23 19:13:45 INFO - mozprofile == 0.28 19:13:45 INFO - mozrunner == 6.13 19:13:45 INFO - mozscreenshot == 0.1 19:13:45 INFO - mozsystemmonitor == 0.3 19:13:45 INFO - moztest == 0.7 19:13:45 INFO - mozversion == 1.4 19:13:45 INFO - psutil == 3.1.1 19:13:45 INFO - requests == 1.2.3 19:13:45 INFO - wsgiref == 0.1.2 19:13:45 INFO - Installing None into virtualenv /builds/slave/test/build/venv 19:13:45 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')]} 19:13:45 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:45 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:45 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')]} 19:13:45 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:45 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:45 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 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:45 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 19:13:45 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 19:13:45 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:45 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:45 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:45 INFO - 'HOME': '/Users/cltbld', 19:13:45 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:45 INFO - 'LOGNAME': 'cltbld', 19:13:45 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:45 INFO - 'MOZ_NO_REMOTE': '1', 19:13:45 INFO - 'NO_EM_RESTART': '1', 19:13:45 INFO - 'PAGER': '/bin/cat', 19:13:45 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:45 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:45 INFO - 'PWD': '/builds/slave/test', 19:13:45 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:45 INFO - 'SHELL': '/bin/bash', 19:13:45 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:45 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:45 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:45 INFO - 'USER': 'cltbld', 19:13:45 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:45 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:45 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:45 INFO - 'XPC_FLAGS': '0x0', 19:13:45 INFO - 'XPC_SERVICE_NAME': '0', 19:13:45 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 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)) 19:13:46 INFO - Cleaning up... 19:13:46 INFO - Return code: 0 19:13:46 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 19:13: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')]} 19:13:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 19:13:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13: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')]} 19:13:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 19:13:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 19:13:46 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x10ee963f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ee0c570>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fa3a25c76d0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, '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.dmVxpCJkXp/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.r42C6OtMMb/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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 19:13:46 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 19:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 19:13:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:13:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:13:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:13:46 INFO - 'HOME': '/Users/cltbld', 19:13:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:13:46 INFO - 'LOGNAME': 'cltbld', 19:13:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:13:46 INFO - 'MOZ_NO_REMOTE': '1', 19:13:46 INFO - 'NO_EM_RESTART': '1', 19:13:46 INFO - 'PAGER': '/bin/cat', 19:13:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:13:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:13:46 INFO - 'PWD': '/builds/slave/test', 19:13:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:13:46 INFO - 'SHELL': '/bin/bash', 19:13:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:13:46 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:13:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:13:46 INFO - 'USER': 'cltbld', 19:13:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:13:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:13:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:13:46 INFO - 'XPC_FLAGS': '0x0', 19:13:46 INFO - 'XPC_SERVICE_NAME': '0', 19:13:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:13:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 19:13:46 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 19:13:46 INFO - Cleaning up... 19:13:46 INFO - Return code: 0 19:13:46 INFO - Running post-action listener: _resource_record_post_action 19:13:46 INFO - Running post-action listener: _start_resource_monitoring 19:13:46 INFO - Starting resource monitoring. 19:13:46 INFO - [mozharness: 2016-12-16 03:13:46.373656Z] Finished create-virtualenv step (success) 19:13:46 INFO - [mozharness: 2016-12-16 03:13:46.374604Z] Running install step. 19:13:46 INFO - Running pre-action listener: _resource_record_pre_action 19:13:46 INFO - Running main action method: install 19:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 19:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 19:13:46 INFO - Reading from file tmpfile_stdout 19:13:46 INFO - Detecting whether we're running mozinstall >=1.0... 19:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 19:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 19:13:46 INFO - Reading from file tmpfile_stdout 19:13:46 INFO - Output received: 19:13:46 INFO - Usage: mozinstall [options] installer 19:13:46 INFO - Options: 19:13:46 INFO - -h, --help show this help message and exit 19:13:46 INFO - -d DEST, --destination=DEST 19:13:46 INFO - Directory to install application into. [default: 19:13:46 INFO - "/builds/slave/test"] 19:13:46 INFO - --app=APP Application being installed. [default: firefox] 19:13:46 INFO - mkdir: /builds/slave/test/build/application 19:13:46 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 19:13:46 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg --destination /builds/slave/test/build/application 19:14:08 INFO - Reading from file tmpfile_stdout 19:14:08 INFO - Output received: 19:14:08 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 19:14:08 INFO - Running post-action listener: _resource_record_post_action 19:14:08 INFO - [mozharness: 2016-12-16 03:14:08.797804Z] Finished install step (success) 19:14:08 INFO - [mozharness: 2016-12-16 03:14:08.797929Z] Running run-tests step. 19:14:08 INFO - Running pre-action listener: _resource_record_pre_action 19:14:08 INFO - Running main action method: run_tests 19:14:08 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:14:08 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:14:08 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:14:08 INFO - 2016-12-15 19:14:08.812 screenresolution[1631:7469] starting screenresolution argv=screenresolution get 19:14:08 INFO - 2016-12-15 19:14:08.814 screenresolution[1631:7469] Display 0: 1600x1200x32@60 19:14:08 INFO - 2016-12-15 19:14:08.820 screenresolution[1632:7472] starting screenresolution argv=screenresolution list 19:14:08 INFO - Available Modes on Display 0 19:14:08 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:14:08 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:14:08 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:14:08 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:14:09 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:14:09 INFO - Intel Iris: 19:14:09 INFO - Chipset Model: Intel Iris 19:14:09 INFO - Type: GPU 19:14:09 INFO - Bus: Built-In 19:14:09 INFO - VRAM (Dynamic, Max): 1536 MB 19:14:09 INFO - Vendor: Intel (0x8086) 19:14:09 INFO - Device ID: 0x0a2e 19:14:09 INFO - Revision ID: 0x0009 19:14:09 INFO - Displays: 19:14:09 INFO - Display: 19:14:09 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:14:09 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:14:09 INFO - Display Serial Number: 1600x1200 60 19:14:09 INFO - Main Display: Yes 19:14:09 INFO - Mirror: Off 19:14:09 INFO - Online: Yes 19:14:09 INFO - Rotation: Supported 19:14:09 INFO - Return code: 0 19:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 19:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 19:14:09 INFO - Python 2.7.3 19:14:09 INFO - Return code: 0 19:14:09 INFO - grabbing minidump binary from tooltool 19:14:09 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 19:14:09 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 0x10ecabd30>, '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 0x10eb12db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10eca3b20>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 19:14:09 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 19:14:09 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 19:14:09 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 19:14:09 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 19:14:09 INFO - Return code: 0 19:14:09 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 19:14:09 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 19:14:09 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 19:14:09 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 19:14:09 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 19:14:09 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 19:14:09 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0154 --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 19:14:09 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.dmVxpCJkXp/Render', 19:14:09 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 19:14:09 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 19:14:09 INFO - 'HOME': '/Users/cltbld', 19:14:09 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 19:14:09 INFO - 'LOGNAME': 'cltbld', 19:14:09 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 19:14:09 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 19:14:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 19:14:09 INFO - 'MOZ_NO_REMOTE': '1', 19:14:09 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 19:14:09 INFO - 'NO_EM_RESTART': '1', 19:14:09 INFO - 'PAGER': '/bin/cat', 19:14:09 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 19:14:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 19:14:09 INFO - 'PWD': '/builds/slave/test', 19:14:09 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 19:14:09 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 19:14:09 INFO - 'SHELL': '/bin/bash', 19:14:09 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.r42C6OtMMb/Listeners', 19:14:09 INFO - 'TMPDIR': '/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/', 19:14:09 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 19:14:09 INFO - 'USER': 'cltbld', 19:14:09 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 19:14:09 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 19:14:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 19:14:09 INFO - 'XPC_FLAGS': '0x0', 19:14:09 INFO - 'XPC_SERVICE_NAME': '0', 19:14:09 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 19:14:09 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0154', '--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 19:14:09 INFO - mozversion application_buildid: 20161215174549 19:14:09 INFO - mozversion application_changeset: fe17931bfd5f0658a94f40a71b0463cfa1e03c6c 19:14:09 INFO - mozversion application_display_name: Nightly 19:14:09 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 19:14:09 INFO - mozversion application_name: Firefox 19:14:09 INFO - mozversion application_remotingname: firefox 19:14:09 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 19:14:09 INFO - mozversion application_vendor: Mozilla 19:14:09 INFO - mozversion application_version: 53.0a1 19:14:09 INFO - mozversion platform_buildid: 20161215174549 19:14:09 INFO - mozversion platform_changeset: fe17931bfd5f0658a94f40a71b0463cfa1e03c6c 19:14:09 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 19:14:09 INFO - mozversion platform_version: 53.0a1 19:14:09 INFO - using testdate: 1481858049 19:14:09 INFO - actual date: 1481858049 19:14:09 INFO - starting webserver on 'localhost:49258' 19:14:09 INFO - SUITE-START | Running 2 tests 19:14:09 INFO - TEST-START | dromaeo_css 19:14:09 INFO - operating with platform_type : mac_ 19:14:09 INFO - Initialising browser for dromaeo_css test... 19:14:09 INFO - TEST-INFO | started process 1642 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpKSILBx/profile http://localhost:49258/getInfo.html) 19:14:12 INFO - PROCESS | 1642 | __metrics Screen width/height:1600/1200 19:14:12 INFO - PROCESS | 1642 | colorDepth:24 19:14:12 INFO - PROCESS | 1642 | Browser inner width/height: 1024/689 19:14:12 INFO - PROCESS | 1642 | __metrics 19:14:12 INFO - PROCESS | 1642 | 2016-12-15 19:14:12.596 plugin-container[1644:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e3f, name = 'com.apple.tsm.portname' 19:14:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:14:12 INFO - PROCESS | 1642 | 2016-12-15 19:14:12.601 plugin-container[1644:7635] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x950f, name = 'com.apple.CFPasteboardClient' 19:14:12 INFO - PROCESS | 1642 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:14:12 INFO - PROCESS | 1642 | 2016-12-15 19:14:12.601 plugin-container[1644:7635] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:14:12 ERROR - PROCESS | 1642 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3238: TypeError: this.tabs is undefined 19:14:13 INFO - TEST-INFO | 1642: exit 0 19:14:13 INFO - Browser initialized. 19:14:13 INFO - Running cycle 1/1 for dromaeo_css test... 19:14:13 INFO - TEST-INFO | started process 1645 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmpKSILBx/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:14:15 INFO - PROCESS | 1645 | 2016-12-15 19:14:15.729 plugin-container[1646:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 19:14:15 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:14:15 INFO - PROCESS | 1645 | 2016-12-15 19:14:15.729 plugin-container[1646:7754] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x471b, name = 'com.apple.CFPasteboardClient' 19:14:15 INFO - PROCESS | 1645 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:14:15 INFO - PROCESS | 1645 | 2016-12-15 19:14:15.729 plugin-container[1646:7754] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:14:25 INFO - PROCESS | 1645 | RSS: Main: 236806144 19:14:25 INFO - PROCESS | 1645 | 19:15:15 INFO - PROCESS | 1645 | 1481858115000 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] 19:17:29 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49258/tests/dromaeo/cssquery-ext.html) 19:17:30 INFO - PROCESS | 1645 | RSS: Main: 251723776 19:17:30 INFO - PROCESS | 1645 | 19:20:33 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-ext.html (next: http://localhost:49258/tests/dromaeo/cssquery-jquery.html) 19:20:33 INFO - PROCESS | 1645 | RSS: Main: 263860224 19:20:33 INFO - PROCESS | 1645 | 19:23:35 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49258/tests/dromaeo/cssquery-mootools.html) 19:23:36 INFO - PROCESS | 1645 | RSS: Main: 269680640 19:23:36 INFO - PROCESS | 1645 | 19:26:38 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49258/tests/dromaeo/cssquery-prototype.html) 19:26:38 INFO - PROCESS | 1645 | RSS: Main: 268447744 19:26:38 INFO - PROCESS | 1645 | 19:29:41 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49258/tests/dromaeo/cssquery-yui.html) 19:29:42 INFO - PROCESS | 1645 | RSS: Main: 273096704 19:29:42 INFO - PROCESS | 1645 | 19:32:44 INFO - PROCESS | 1645 | Cycle 1(1): loaded http://localhost:49258/tests/dromaeo/cssquery-yui.html (next: http://localhost:49258/tests/dromaeo/cssquery-dojo.html) 19:32:45 INFO - PROCESS | 1645 | RSS: Main: 275730432 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | __start_tp_report 19:32:45 INFO - PROCESS | 1645 | _x_x_mozilla_page_load 19:32:45 INFO - PROCESS | 1645 | _x_x_mozilla_page_load_details 19:32:45 INFO - PROCESS | 1645 | |i|pagename|runs| 19:32:45 INFO - PROCESS | 1645 | |0;dojo.html;2790.9952417129844;3355.9093294679205;3374.52756614074;3412;3420.0936751760787;4461.420015397994;5566.721663916798;5624.493795558399;5640.971795141024;5708.628939118965;5623.775048998035;5681.034224181889;5684.261046064006;5700.258966334371;5786.652800831943;5118.718470484122;5218.373795144588;5239.711815850127;5307.52232299093;5324.733763311839;4758.762061896909;4984.75076246188;5252.317198764155;5334.546563542084;5348.545373643245;5109.41241757199;5251.083354575571;5303.36359636846;5318.973405132988;5325.973370133144;5144.717040562768;5199.870003249911;5200.141976573861;5220.895582088337;5235;4441.200583894898;5112.437631860493;5236.842894713165;5243.397009343936;5252.474752524755;5525.475079867406;5564.803567233064;5733.684647344395;5754.625949313281;5828.9417105828825;5481.123020316731;5547.8890422191735;5830.533557315447;6097.3292937776805;6105.702364394958;6195.3185149633955;6253.1558239637525;6255.218097737783;6258.687065646699;6267.811965641038;5856.472917437451;5912.260967379078;5926.259217597801;5928.992071347838;5951.107333899907;6322.810315690536;6339.334369891188;6341.112244285804;6345.904811427832;6353.682315884187;6068.4538391544975;6123.571350005546;6131.938680613226;6155.353687862799;6238.812835614939;6114.753609057711;6189.412005859436;6206.472449841814;6259.18630578031;6307;5586.413426590149;6109.6475967989745;6146.231721034871;6183.258009038944;6212.875742485125;5613.943860561342;6242.500599951993;6274.058891166362;6279.403456671609;6301.117843501914;4816.783244753994;4849.4908034656555;4899.240617704261;4904.215325547895;4939.87650308738;4452.799624016927;4460.442444694413;4461.598456138963;4475.5748203920575;4508.368828364031;5824.912626310609;5882.470577647947;5943.078822782476;5953.1070339449425;5987.371326010793;6141.056449558148;6194.5973511721595;6228.439440450291;6301.180846489927;6307.085472606446;5878.617889837233;5882.499987501024;5889.234399528034;5945.16767652529;5980.102984552352;5450.046241907729;5470.070088085035;5470.781168753285;5474.808381706621;5488.423715509894;6205.875882482415;6340.080688300079;6365.840853978595;6426.4216220540375;6440.098386225932;6171.197744293213;6210.130581718473;6227.96886015567;6267.717952692231;6320.747170113144;6400.07198956158;6406.423421892052;6418.03729440569;6425.710843011982;6430.549861509649;6079.3008803988105;6243;6253.96873015632;6256.124142620128;6262.686865656608;6091.604045737013;6141.393954534099;6144.709610981744;6164.982777841606;6187.969060154671;6041.365656606169;6174.567780255518;6176.104464852481;6182.876342473215;6204.906926396017;5992.850178745566;6083.543734220039;6154.046122850965;6156.384361563987;6159.72281247336;4826.444958829779;5869.972754767984;6040.456358927542;6077.392260774064;6127.785527506516;6137.214633442868;6141.416565426277;6150.415710507495;6174.845628859135;6205.317415084247;5732.369439361751;6004.0693692477735;6033.577649564664;6053.848653783514;6078.270607527125 19:32:45 INFO - PROCESS | 1645 | |1;ext.html;483.80164132705596;491.5330436085719;492.82012065596047;501.90584525733885;502.4749137151677;39488.80255598722;41774;41894.581054189446;41906.37140442892;41952.160956780885;42374.57625423745;42575.78712106435;42860.571394286046;42936;42981.14037719244;8674.219320261176;8766.34252431067;9411.576479058442;9637.036296370343;9643.80712385752;9288.814223715523;9408.77685900832;10012.39925604466;10036.698899033041;11831.290122592673;11585.246958947642;11621.12841536884;11679.766404671902;11689.473973671164;11730.589429369973;11436.19946603738;11448.71378215543;11517.712057198552;11528.59649912249;11547.133964952662;11458.197926145172;11534.307941523535;11549.826752598718;11551.306921584732;11573.479193436315;41259;41300.586994129975;41310;41398;41696.583034169875;3362.6132994705677;3577.606463289036;3764.435334699817;3945.2504024235304;4043.1913617276664;25573.48853022948;30579.388412231852;31191.532127018112;31431.585578649017;31442.056738297888;3240.270939038721;3273.4107860585086;3318.7510936679846;3362.512435696835;3368.1747971747077;467416;473837.4156904492;477537.6123119397;479105;480564.5971770154;60206.69896650489;60294.69852650708;60533.39466605365;60742.392576073675;60832.695836521416;63875;64393;64449.67775161094;64503.35496645067;64655.353446464935;15611.609709757347;16145.919270403572;16233.457821506938;16238.188090595422;16245.918770406071;9771.853422198672;11453.312801231952;12198.817017744741;12519.624411267676;12567.497300108076;18060.819391806177;18109;18122.90938545325;18125.546861328574;18194.727079093824;6902.758403455855;6983.615901125385;7046.61243631595;7070.52531613931;7077.079979602721;878.639757699353;945.664289177346;995.2883688162854;1034.043509753475;1054.086832399193;28762.424751504852;30034.549481757793;30734.077977660705;30884.53673194904;31128.910488132806;1043.9112675422523;1046;1095.0199571383566;1176.9352685602373;1190.3215167354524;15966.521004369888;15981;15988.600284992735;16265.837341626433;16382.508524744277;603233;604829;607707;609748;611008;60540.091898621555;60697.39302607006;60705;60843;61038.69480652568;1357.83705955286;1379.4620098161724;1391.193107997359;1404.7120340330207;1406.9788953165505;29671.25821854471;30460.84769576138;30732.231694207825;30799.692003079683;31248.687513124576;52631.73684131555;53104.73447632737;53220.935581287384;53278.73360633172;53292.73353633207;553823;556997;557492.3379214182;557498;559065;24745.62881556742;25461.872690636428;25738.485230294915;26137.869310653325;41742.79128604338;42449.36325955051;42740.71777846669;42985.57014429816;43347.566524334354;43834;35810.64189358073;36111.63888361083;36163.81918090393;37147.81426092852;37571;36206.63793362138;36277;36706.8164659175;37156.814215928745;37184;35731.46402803908;36169.81915090408;36767.448488273236;36805.815970919975;36889;31317.217069573442;32629.18427039343;32709.672903270664;32721;33412.33175336528;22165;24053.879730601235;24606;25036.62445063289;25058 19:32:45 INFO - PROCESS | 1645 | |2;jquery.html;331.7627896054321;346.5771758454685;371.1833965276391;378.71596302772923;389.28954657749614;18870.433886983395;18947.242110315587;19143.90428047858;19698.212071517122;19908.303209387675;18728.906355468236;19111.808881911176;19678.704819427683;19688.91710955897;19831.5042123947;4893.779779909894;5477.917831232535;5762.3373312069225;5763.9711801440935;5774.624649397776;4667.5332466753225;5712.457316554921;5767.1349297605275;5768.04827203513;5770.480656740892;4856.660033797635;5104.719240441774;5449.891002179954;5645.520130788888;5698.6865722385255;4726.787294571753;4740.620750339965;4873.43955445123;5712.771489140471;5740.368559458456;4101.5488296287385;4290.76400797956;5022.372203474566;5035.521625445577;5052.267421223902;12627.873721262853;12923.67690807728;13004.934975325157;13048.412821423059;13062.477500899953;2013.5670830771455;2365.8698771567556;2489.9751002489925;2562.5387430262545;2879.568064790298;9723.90276097237;9743;9882.901170988342;10220.335678180894;10305.12406445453;1749.6063385738235;1813.5012871460322;1836.4031689700766;1862.5622978600018;1900.8669393142486;111067;119408.40295798553;122918.38540807329;123534;123758.38120809429;9246.676366327147;9620.518974051269;9892.109710125978;10030.64892728751;11481.196316257781;12039.63881083569;12355.196912200678;12395.566155184526;12405.75188496225;12417.75164496705;6916.654167291716;7710.845783084419;7930.0880398753725;8019.719309824128;8032.437729359006;7040;8199.877001844978;8204.958975205085;8214;8370.874436883452;3295.3244584860045;3477.6174620791694;3770.340190466712;3825.713071519647;3920.2159568086495;1642.5565097423628;1728.9827101729072;1853.5366158460386;1891.4136617648574;1946.2506934830192;818.6193420059701;916.4237511480243;959.8128364968903;985.1527686189871;1014.2241185493103;19990.000499974944;20847;20962.266320678704;21041.789582104288;21134.894325528276;846.191886748154;920.9677661281823;938.7465384346189;950.4249928793118;952.5570609666538;10386.948065259625;10878.075363594184;10953.34279943206;11192.936671016241;11233.943830280796;569406;577218;579486;582472;582863;96840.51579742056;125905;126494;131722;135881.3205933964;969.9757506062264;1003.8644782954353;1019.0980981831133;1020.1125021231576;1055.145332280855;14788.260586970824;17978.280868765625;19432.70850937209;19474.707879381538;19496;38346.42480362741;41185.58814411818;43520.3887766735;44252.459803215024;45970.391036313566;54415;59682.70158649179;70732.64633677005;79999;80723.19276807392;5575.972120139373;5896.7936122235515;7467.402607791472;8033.718819841278;8296.502209867427;11512.769744605228;12132.272063676208;12568.30874301922;12658.493660253855;12666;8749.606267718094;10497.632582859564;10935.070519005656;11071.169662275523;11119.05488033494;5312.229726689686;7577.772666820004;10070.647527336509;10330.638427654996;11023.61417350389;10745.570177192825;10947.781044379228;11067.612633557786;11079.889201107886;11495.54017839277;6598.406143447113;6910.689018994258;7272.745453909088;7470.215627359266;8003.4797738146835;8906.376553641183;10974.835377469184;11088.279261847954;11278.887211127783;11367.829482557934 19:32:45 INFO - PROCESS | 1645 | |3;mootools.html;1467.5230550071226;1486.7621180611104;1708.9316427342908;1808.9819101808994;1831.4139475367888;7540.17058123606;7808.258215469536;7965.283124518792;8415.242628163463;8435.70475033374;7968.3625309975205;8287.337013038958;8303.045149807775;8412.327013838894;8434.578271086451;2306.746257911628;2344.6717459555675;2366.372911178539;2370.277065495032;2372.525494901018;2360.5042940982416;2369.9644505332435;2383.511380167062;2390.9760902391013;2393.0427828868505;2320.96518552221;2345.4605440748637;2363.5863723848256;2366.5976783946685;2371.063429945171;2297.0811675329833;2312.9306120816404;2313.548857972696;2331.125827814561;2348.459854233519;2336.7312759032757;2355.128602417117;2358.752331005237;2378.9524209515885;2380.7500212477785;8331;8368.748937531884;8439.493630382136;8446.704365347183;8451.53516556589;5026.773795179226;5163.457836927144;5209.062368773619;5213.426523082458;5221.242919776649;3455.1362159460136;3465.9653403465895;3506.5977970991567;3516.613172551017;3518.3842827505086;1028.7890982348601;1033.7777377863795;1049.2969710294121;1065.7761870007307;1066.7599790047254;10650.946745266301;10741.731456713645;10779.461026948542;10797.622083227128;10867.021968022837;4429.911401771947;4623.815047398067;4631.166390049823;4739.3601863748645;4747.359106520576;7789.415793815373;9265.72202833916;9335.299852511089;9355.672551460666;9358.204552613104;5143.614228932845;5204.479552044841;5208.635395522277;5208.765605547759;5212.739363031833;4260.957390426118;4284.464441944757;4663.067386522709;4769.475357710649;4778.450478194983;3326.6507016763376;3753.6997040236715;3802.5817160112365;3892.1242720388254;3915.1778126593163;1231.408923716621;1265.4621785741024;1276.6297773645538;1279.9232046077266;1280.231860883481;603.856379036003;723.3706675192616;837.874318852177;851.9957400212959;867.1025488619249;5458.890822183614;5461.644993075517;5566.610337276433;5675.460831221028;5743.167240750068;924.5007695844168;952.8332541805295;972.2270794718216;972.9173020293213;990.3909095905966;3317.4194515960094;3713.535808023997;3758.285925674113;3770.472133901256;3858.9228215436096;9838;10023;10151.289409741396;10158.034986676254;10169.338992965582;9330.533473326306;9909.355891867006;10099.899001010042;10105.898941010495;10108.595656173671;7492.288232617892;7746;7865.685372585147;7875.724349647735;7875.763727088197;5329.813456529003;5406.972965135149;5620.4098569649595;5634.492895639412;5652.943470565242;8589.871151932852;9084.273258139465;9145.222656074307;9226;9364.719058428258;9300.860487092834;9362.157405833508;9371.437713737198;9469.47917864524;9506.95246523763;7124.857502850017;7362.484626076338;7559.357454616199;7709.7301594443925;7863.685452581833;7737.0715514138665;8006.879896801669;8013.038435387791;8018.799530011796;8021.197880211932;5796.391378905154;7038.859222815413;7150.67821948024;7187.8562428752175;7273.418126549757;7009.1939426966555;7054.858902821812;7058.063233247248;7111.684338397413;7162.104736907887;6597.80206593803;6631.867362652816;6702.430293425114;6711.530192886451;6713.865722685617;4785.497522760199;4820.4456487504385;4865.756712164449;4880.682755620874;4909.288153217834;4757.119932812441;4853.927191092066;4879.512048795092;4934.235193545006;5006.69959802413 19:32:45 INFO - PROCESS | 1645 | |4;prototype.html;346.50276852716354;350.8403676327271;353.86199382240915;362.4617443097001;370.2687192794232;4642.721436713795;4682.625389968803;4746.6440016998695;5119.411267704215;5483.945160548394;4357.1264356326765;5218.739063046852;5263.289455923449;5381.650192737479;5416.295881535405;3974.8075577326686;4007.499062617173;4248.3627455881715;4256.87229383119;4271.444712187426;3702.074481379655;3718.981405092971;3763.2285381496727;4295.377170310303;4305.4833419989645;3401.472771720375;4060.7157498975084;4068.328725760246;4068.4304197412384;4072.5316588592245;4043.7978101094973;4079.2861249281405;4100.118474527991;4100.2414553307435;4122.814473348706;3328.118048717092;3552.982235088808;3560.91097722559;3562.002639261011;3578.3916734155255;4593.196190666654;4991.550760431579;5246.816361427332;5364.5398717827175;5366.329208848894;3797.468354430382;3934.603215858386;4665.206914824454;4671.369365135697;4678.929816052762;5731.770729170828;5759.9968022224575;5773.88452230953;5775.653460792366;5778.711064446761;2861.585070164835;3479.443289073761;3496.8776092836883;3547.467879818022;3873.573906870242;5950.9702451487465;5954.851128721816;5960.125918153191;5970.850728731773;5980.5646644805365;3514.5606799150105;3929.25344184604;3929.4302326162547;3933.6066393360434;3955.3078211312904;3840.539135303781;3971.2851686696094;4000.4799376080923;4025.879223623296;4029.8589549365634;4613.6089973869175;4621.5378462154185;4641.632767288707;4652.674312798072;4669.789859456333;5589.832305030855;5740.799072032542;5780.653160810365;5788.82633520995;5799.652020878676;1176.6234804862534;1212.1333246728632;1221.4487600747002;1259.603224984127;1285.7685616589104;718.3606590134784;766.9041369828772;809.10997902307;869.3566760597113;876.1632640827994;4324.524302326741;4341;5070.873228169252;5131.948680513222;5221.738913054332;4736.726371341706;4838.443578988461;4910.508949105061;5107.565856902205;5221.973890130525;5115.18157094863;5137.280780690707;5140.553421290557;5160.767765450564;5190.896182076337;2887.5235586128474;2941.8970336038137;2946.484365236118;2946.57274695168;2975.642922849272;5262.342207224097;5379.838604841861;5788.27646544182;5793.24687790593;5829.504492118217;4764.546813221931;4789.880252993633;5468.972655136698;5719.828405147852;5770.596058275881;5526.58550608714;5815.592908496449;5820.933568583177;5830.679312637845;5846.473817356458;4482.520768146439;5464.18594423864;5520.006398848085;5535.6955367455175;5537.972310138423;5183.663061900965;5258.007828101341;5441.2654291670115;5467.890642187054;5517.806876759294;5185.403678577012;5663.886722265449;5774.9133762994425;5830.4461076197695;5911.861696812162;3637.5453068366455;3813.7521061130938;3896.3181443247886;3906.8437262509183;3957.9010524737114;5565.526930210977;5910.822675319747;5941.465268125717;5956.70666793279;6016.4885984690045;5087.796488140433;5378.784848606168;5722.62802917809;5781.855453613525;5836.854078647898;4863.440704319049;5671.102053863935;5685.403032681675;5720.427957204246;5751.971240143773;4143.668506519411;5256.816011439581;5392.164214546751;5435.130379139318;5663.32040155184;4411.338299255138;4665.55677210678;5714.457126572969;5725.0248664210785;5727.599068065196;4995.825146119869;5327.9467205329;5465.6447330923365;5473.616846820843;5667.659940403589 19:32:45 INFO - PROCESS | 1645 | |5;yui.html;651.4169818012879;664.9867002659947;710.9715611375545;792.1721800718249;833.4415941319315;14907.031042982198;15197.772033419507;15300.464483743071;15603.141827199499;15618.843811561881;13925.93037034816;14060.226687532182;14078.929605351985;14305.213213273266;14362.784558231635;843.9324854011664;864.3690106222462;866.1511718515858;866.3026263857593;867.7179916527153;677.8136012596551;700.310194458458;844.6452489954203;861.840559496492;863.7797361672796;918.4626993208981;926.6802952981211;926.7683079230193;927.7077720518049;928.0255731481951;919.7608621758358;920.6179435534245;921.7280902133887;921.8156368726214;922.7877588154762;656.2780940964948;656.6815094679076;680.4148432348226;681.2438193605094;681.8806708825936;15013;15260.160691161982;15334.923325383414;15347.386104555804;15424.76862847047;14211.857881421156;14543.927280363636;14561.854381456156;14576.562703118923;14595.562133136023;11796.410179490991;11804.704882377924;11853.822192667118;11885.227460215145;11905.107116966175;3129.3741251749743;3299.142223022008;3432.6567343265724;3678.08047988003;3702.9814850925573;102823;107846.46076769567;108487.45756271247;109507;109863;10918;10931.344119352865;10969.725756856142;10977.506012229469;11246.606368777053;22492.437689057577;22606.886965565067;22729;22914.65628015581;22955;7577.772666819894;7615.162332143571;7644.847103058019;7666.731664391719;7785.18255583167;4076.8573099941955;4161.604647558477;4219.852305169305;4220.11377610699;4222.535521092677;3375.645557216457;3438.8108654023767;3459.3773120837986;3460.2733425980323;3464.2378676691087;677.881370760115;699.8320403103219;707.8088915992653;714.7176865138346;725.2855936902147;613.1997742945409;653.1345966594187;662.4270006444391;673.1316601584;694.3924066441863;8743;8761.649534018696;9264.351495395258;9469.715908522756;9523.809523809485;548.0381929713407;598.3209057719432;607.781198768443;608.969551522431;675.6993138053617;4480.305552639346;4569.337446070368;4572.908541829145;4580;4597.333386658983;105355;110151;110479;110727.44636276927;112037.43981280041;82128.58935705283;95573;99583;100178;103044;69514.65242673755;71046.9342959856;82596.58701706573;88426;90020;8392.496450213006;8526;10005.649802256887;10045.799084018425;10166.847497287694;41288.58711412847;49634.27011514401;51958;52244;53104.20343694925;65425.67287163724;72565.27434725796;74350;74447.62776186084;76542;13348.332583370986;13739.24434156131;14047.297635118408;14361.21013344276;14486.422727249874;14520.273986300432;14662.193579353238;14736.852561937445;14845.40618375253;15426.305816238071;12156.75686486283;13335.06615548435;13730.656733581342;13909.64122986914;13959.441622334993;13727.725445490834;13728.588142355746;13763;13899.235542045284;14334.856651433352;13313.068085233941;13568.525101621395;13613.455461781814;13640.86359136396;14127.788083178555;9229.35394522377;11038.944805275922;11057.668269951915;11232.438378081226;11768.293902365886;8591.527465989431;9513.286503512127;11252.043576296106;11588.710282243012;11631.825522616999 19:32:45 INFO - PROCESS | 1645 | __end_tp_report 19:32:45 INFO - PROCESS | 1645 | __start_cc_report 19:32:45 INFO - PROCESS | 1645 | _x_x_mozilla_cycle_collect,369 19:32:45 INFO - PROCESS | 1645 | __end_cc_report 19:32:45 INFO - PROCESS | 1645 | __startTimestamp1481859165222__endTimestamp 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | ------- Summary: start ------- 19:32:45 INFO - PROCESS | 1645 | Number of tests: 6 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#0] dojo.html Cycles:165 Average:5735.53 Median:5987.37 stddev:672.18 (11.2%) stddev-sans-first:633.31 19:32:45 INFO - PROCESS | 1645 | Values: 2791.0 3355.9 3374.5 3412.0 3420.1 4461.4 5566.7 5624.5 5641.0 5708.6 5623.8 5681.0 5684.3 5700.3 5786.7 5118.7 5218.4 5239.7 5307.5 5324.7 4758.8 4984.8 5252.3 5334.5 5348.5 5109.4 5251.1 5303.4 5319.0 5326.0 5144.7 5199.9 5200.1 5220.9 5235.0 4441.2 5112.4 5236.8 5243.4 5252.5 5525.5 5564.8 5733.7 5754.6 5828.9 5481.1 5547.9 5830.5 6097.3 6105.7 6195.3 6253.2 6255.2 6258.7 6267.8 5856.5 5912.3 5926.3 5929.0 5951.1 6322.8 6339.3 6341.1 6345.9 6353.7 6068.5 6123.6 6131.9 6155.4 6238.8 6114.8 6189.4 6206.5 6259.2 6307.0 5586.4 6109.6 6146.2 6183.3 6212.9 5613.9 6242.5 6274.1 6279.4 6301.1 4816.8 4849.5 4899.2 4904.2 4939.9 4452.8 4460.4 4461.6 4475.6 4508.4 5824.9 5882.5 5943.1 5953.1 5987.4 6141.1 6194.6 6228.4 6301.2 6307.1 5878.6 5882.5 5889.2 5945.2 5980.1 5450.0 5470.1 5470.8 5474.8 5488.4 6205.9 6340.1 6365.8 6426.4 6440.1 6171.2 6210.1 6228.0 6267.7 6320.7 6400.1 6406.4 6418.0 6425.7 6430.5 6079.3 6243.0 6254.0 6256.1 6262.7 6091.6 6141.4 6144.7 6165.0 6188.0 6041.4 6174.6 6176.1 6182.9 6204.9 5992.9 6083.5 6154.0 6156.4 6159.7 4826.4 5870.0 6040.5 6077.4 6127.8 6137.2 6141.4 6150.4 6174.8 6205.3 5732.4 6004.1 6033.6 6053.8 6078.3 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#1] ext.html Cycles:180 Average:68545.73 Median:36241.82 stddev:146522.95 (404.3%) stddev-sans-first:146844.87 19:32:45 INFO - PROCESS | 1645 | Values: 483.8 491.5 492.8 501.9 502.5 39488.8 41774.0 41894.6 41906.4 41952.2 42374.6 42575.8 42860.6 42936.0 42981.1 8674.2 8766.3 9411.6 9637.0 9643.8 9288.8 9408.8 10012.4 10036.7 11831.3 11585.2 11621.1 11679.8 11689.5 11730.6 11436.2 11448.7 11517.7 11528.6 11547.1 11458.2 11534.3 11549.8 11551.3 11573.5 41259.0 41300.6 41310.0 41398.0 41696.6 3362.6 3577.6 3764.4 3945.3 4043.2 25573.5 30579.4 31191.5 31431.6 31442.1 3240.3 3273.4 3318.8 3362.5 3368.2 467416.0 473837.4 477537.6 479105.0 480564.6 60206.7 60294.7 60533.4 60742.4 60832.7 63875.0 64393.0 64449.7 64503.4 64655.4 15611.6 16145.9 16233.5 16238.2 16245.9 9771.9 11453.3 12198.8 12519.6 12567.5 18060.8 18109.0 18122.9 18125.5 18194.7 6902.8 6983.6 7046.6 7070.5 7077.1 878.6 945.7 995.3 1034.0 1054.1 28762.4 30034.5 30734.1 30884.5 31128.9 1043.9 1046.0 1095.0 1176.9 1190.3 15966.5 15981.0 15988.6 16265.8 16382.5 603233.0 604829.0 607707.0 609748.0 611008.0 60540.1 60697.4 60705.0 60843.0 61038.7 1357.8 1379.5 1391.2 1404.7 1407.0 29671.3 30460.8 30732.2 30799.7 31248.7 52631.7 53104.7 53220.9 53278.7 53292.7 553823.0 556997.0 557492.3 557498.0 559065.0 24745.6 25461.9 25738.5 26137.9 41742.8 42449.4 42740.7 42985.6 43347.6 43834.0 35810.6 36111.6 36163.8 37147.8 37571.0 36206.6 36277.0 36706.8 37156.8 37184.0 35731.5 36169.8 36767.4 36805.8 36889.0 31317.2 32629.2 32709.7 32721.0 33412.3 22165.0 24053.9 24606.0 25036.6 25058.0 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#2] jquery.html Cycles:180 Average:33063.32 Median:339.17 stddev:96706.37 (28512.7%) stddev-sans-first:96946.43 19:32:45 INFO - PROCESS | 1645 | Values: 331.8 346.6 371.2 378.7 389.3 18870.4 18947.2 19143.9 19698.2 19908.3 18728.9 19111.8 19678.7 19688.9 19831.5 4893.8 5477.9 5762.3 5764.0 5774.6 4667.5 5712.5 5767.1 5768.0 5770.5 4856.7 5104.7 5449.9 5645.5 5698.7 4726.8 4740.6 4873.4 5712.8 5740.4 4101.5 4290.8 5022.4 5035.5 5052.3 12627.9 12923.7 13004.9 13048.4 13062.5 2013.6 2365.9 2490.0 2562.5 2879.6 9723.9 9743.0 9882.9 10220.3 10305.1 1749.6 1813.5 1836.4 1862.6 1900.9 111067.0 119408.4 122918.4 123534.0 123758.4 9246.7 9620.5 9892.1 10030.6 11481.2 12039.6 12355.2 12395.6 12405.8 12417.8 6916.7 7710.8 7930.1 8019.7 8032.4 7040.0 8199.9 8205.0 8214.0 8370.9 3295.3 3477.6 3770.3 3825.7 3920.2 1642.6 1729.0 1853.5 1891.4 1946.3 818.6 916.4 959.8 985.2 1014.2 19990.0 20847.0 20962.3 21041.8 21134.9 846.2 921.0 938.7 950.4 952.6 10386.9 10878.1 10953.3 11192.9 11233.9 569406.0 577218.0 579486.0 582472.0 582863.0 96840.5 125905.0 126494.0 131722.0 135881.3 970.0 1003.9 1019.1 1020.1 1055.1 14788.3 17978.3 19432.7 19474.7 19496.0 38346.4 41185.6 43520.4 44252.5 45970.4 54415.0 59682.7 70732.6 79999.0 80723.2 5576.0 5896.8 7467.4 8033.7 8296.5 11512.8 12132.3 12568.3 12658.5 12666.0 8749.6 10497.6 10935.1 11071.2 11119.1 5312.2 7577.8 10070.6 10330.6 11023.6 10745.6 10947.8 11067.6 11079.9 11495.5 6598.4 6910.7 7272.7 7470.2 8003.5 8906.4 10974.8 11088.3 11278.9 11367.8 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#3] mootools.html Cycles:180 Average:5435.06 Median:5206.56 stddev:2949.26 (56.6%) stddev-sans-first:2942.46 19:32:45 INFO - PROCESS | 1645 | Values: 1467.5 1486.8 1708.9 1809.0 1831.4 7540.2 7808.3 7965.3 8415.2 8435.7 7968.4 8287.3 8303.0 8412.3 8434.6 2306.7 2344.7 2366.4 2370.3 2372.5 2360.5 2370.0 2383.5 2391.0 2393.0 2321.0 2345.5 2363.6 2366.6 2371.1 2297.1 2312.9 2313.5 2331.1 2348.5 2336.7 2355.1 2358.8 2379.0 2380.8 8331.0 8368.7 8439.5 8446.7 8451.5 5026.8 5163.5 5209.1 5213.4 5221.2 3455.1 3466.0 3506.6 3516.6 3518.4 1028.8 1033.8 1049.3 1065.8 1066.8 10650.9 10741.7 10779.5 10797.6 10867.0 4429.9 4623.8 4631.2 4739.4 4747.4 7789.4 9265.7 9335.3 9355.7 9358.2 5143.6 5204.5 5208.6 5208.8 5212.7 4261.0 4284.5 4663.1 4769.5 4778.5 3326.7 3753.7 3802.6 3892.1 3915.2 1231.4 1265.5 1276.6 1279.9 1280.2 603.9 723.4 837.9 852.0 867.1 5458.9 5461.6 5566.6 5675.5 5743.2 924.5 952.8 972.2 972.9 990.4 3317.4 3713.5 3758.3 3770.5 3858.9 9838.0 10023.0 10151.3 10158.0 10169.3 9330.5 9909.4 10099.9 10105.9 10108.6 7492.3 7746.0 7865.7 7875.7 7875.8 5329.8 5407.0 5620.4 5634.5 5652.9 8589.9 9084.3 9145.2 9226.0 9364.7 9300.9 9362.2 9371.4 9469.5 9507.0 7124.9 7362.5 7559.4 7709.7 7863.7 7737.1 8006.9 8013.0 8018.8 8021.2 5796.4 7038.9 7150.7 7187.9 7273.4 7009.2 7054.9 7058.1 7111.7 7162.1 6597.8 6631.9 6702.4 6711.5 6713.9 4785.5 4820.4 4865.8 4880.7 4909.3 4757.1 4853.9 4879.5 4934.2 5006.7 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#4] prototype.html Cycles:180 Average:4513.73 Median:5097.68 stddev:1395.03 (27.4%) stddev-sans-first:1363.43 19:32:45 INFO - PROCESS | 1645 | Values: 346.5 350.8 353.9 362.5 370.3 4642.7 4682.6 4746.6 5119.4 5483.9 4357.1 5218.7 5263.3 5381.7 5416.3 3974.8 4007.5 4248.4 4256.9 4271.4 3702.1 3719.0 3763.2 4295.4 4305.5 3401.5 4060.7 4068.3 4068.4 4072.5 4043.8 4079.3 4100.1 4100.2 4122.8 3328.1 3553.0 3560.9 3562.0 3578.4 4593.2 4991.6 5246.8 5364.5 5366.3 3797.5 3934.6 4665.2 4671.4 4678.9 5731.8 5760.0 5773.9 5775.7 5778.7 2861.6 3479.4 3496.9 3547.5 3873.6 5951.0 5954.9 5960.1 5970.9 5980.6 3514.6 3929.3 3929.4 3933.6 3955.3 3840.5 3971.3 4000.5 4025.9 4029.9 4613.6 4621.5 4641.6 4652.7 4669.8 5589.8 5740.8 5780.7 5788.8 5799.7 1176.6 1212.1 1221.4 1259.6 1285.8 718.4 766.9 809.1 869.4 876.2 4324.5 4341.0 5070.9 5131.9 5221.7 4736.7 4838.4 4910.5 5107.6 5222.0 5115.2 5137.3 5140.6 5160.8 5190.9 2887.5 2941.9 2946.5 2946.6 2975.6 5262.3 5379.8 5788.3 5793.2 5829.5 4764.5 4789.9 5469.0 5719.8 5770.6 5526.6 5815.6 5820.9 5830.7 5846.5 4482.5 5464.2 5520.0 5535.7 5538.0 5183.7 5258.0 5441.3 5467.9 5517.8 5185.4 5663.9 5774.9 5830.4 5911.9 3637.5 3813.8 3896.3 3906.8 3957.9 5565.5 5910.8 5941.5 5956.7 6016.5 5087.8 5378.8 5722.6 5781.9 5836.9 4863.4 5671.1 5685.4 5720.4 5752.0 4143.7 5256.8 5392.2 5435.1 5663.3 4411.3 4665.6 5714.5 5725.0 5727.6 4995.8 5327.9 5465.6 5473.6 5667.7 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - PROCESS | 1645 | [#5] yui.html Cycles:180 Average:21137.46 Median:3690.53 stddev:30966.85 (839.1%) stddev-sans-first:31015.52 19:32:45 INFO - PROCESS | 1645 | Values: 651.4 665.0 711.0 792.2 833.4 14907.0 15197.8 15300.5 15603.1 15618.8 13925.9 14060.2 14078.9 14305.2 14362.8 843.9 864.4 866.2 866.3 867.7 677.8 700.3 844.6 861.8 863.8 918.5 926.7 926.8 927.7 928.0 919.8 920.6 921.7 921.8 922.8 656.3 656.7 680.4 681.2 681.9 15013.0 15260.2 15334.9 15347.4 15424.8 14211.9 14543.9 14561.9 14576.6 14595.6 11796.4 11804.7 11853.8 11885.2 11905.1 3129.4 3299.1 3432.7 3678.1 3703.0 102823.0 107846.5 108487.5 109507.0 109863.0 10918.0 10931.3 10969.7 10977.5 11246.6 22492.4 22606.9 22729.0 22914.7 22955.0 7577.8 7615.2 7644.8 7666.7 7785.2 4076.9 4161.6 4219.9 4220.1 4222.5 3375.6 3438.8 3459.4 3460.3 3464.2 677.9 699.8 707.8 714.7 725.3 613.2 653.1 662.4 673.1 694.4 8743.0 8761.6 9264.4 9469.7 9523.8 548.0 598.3 607.8 609.0 675.7 4480.3 4569.3 4572.9 4580.0 4597.3 105355.0 110151.0 110479.0 110727.4 112037.4 82128.6 95573.0 99583.0 100178.0 103044.0 69514.7 71046.9 82596.6 88426.0 90020.0 8392.5 8526.0 10005.6 10045.8 10166.8 41288.6 49634.3 51958.0 52244.0 53104.2 65425.7 72565.3 74350.0 74447.6 76542.0 13348.3 13739.2 14047.3 14361.2 14486.4 14520.3 14662.2 14736.9 14845.4 15426.3 12156.8 13335.1 13730.7 13909.6 13959.4 13727.7 13728.6 13763.0 13899.2 14334.9 13313.1 13568.5 13613.5 13640.9 14127.8 9229.4 11038.9 11057.7 11232.4 11768.3 8591.5 9513.3 11252.0 11588.7 11631.8 19:32:45 INFO - PROCESS | 1645 | -------- Summary: end -------- 19:32:45 INFO - PROCESS | 1645 | 19:32:45 INFO - TEST-INFO | 1645: exit 0 19:32:45 INFO - TEST-OK | dromaeo_css | took 1116465ms 19:32:45 INFO - TEST-START | kraken 19:32:45 INFO - operating with platform_type : mac_ 19:32:45 INFO - Initialising browser for kraken test... 19:32:45 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp50XcAS/profile http://localhost:49258/getInfo.html) 19:32:48 INFO - PROCESS | 1658 | 2016-12-15 19:32:48.719 plugin-container[1659:10150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 19:32:48 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:32:48 INFO - PROCESS | 1658 | 2016-12-15 19:32:48.722 plugin-container[1659:10150] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c17, name = 'com.apple.CFPasteboardClient' 19:32:48 INFO - PROCESS | 1658 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:32:48 INFO - PROCESS | 1658 | 2016-12-15 19:32:48.722 plugin-container[1659:10150] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:32:48 INFO - PROCESS | 1658 | __metrics Screen width/height:1600/1200 19:32:48 INFO - PROCESS | 1658 | colorDepth:24 19:32:48 INFO - PROCESS | 1658 | Browser inner width/height: 1024/689 19:32:48 INFO - PROCESS | 1658 | __metrics 19:32:48 ERROR - PROCESS | 1658 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3238: TypeError: this.tabs is undefined 19:32:49 INFO - TEST-INFO | 1658: exit 0 19:32:49 INFO - Browser initialized. 19:32:49 INFO - Running cycle 1/1 for kraken test... 19:32:49 INFO - TEST-INFO | started process 1660 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/tmp50XcAS/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 19:32:52 INFO - PROCESS | 1660 | 2016-12-15 19:32:52.111 plugin-container[1661:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4347, name = 'com.apple.tsm.portname' 19:32:52 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:32:52 INFO - PROCESS | 1660 | 2016-12-15 19:32:52.112 plugin-container[1661:10268] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e0b, name = 'com.apple.CFPasteboardClient' 19:32:52 INFO - PROCESS | 1660 | See /usr/include/servers/bootstrap_defs.h for the error codes. 19:32:52 INFO - PROCESS | 1660 | 2016-12-15 19:32:52.112 plugin-container[1661:10268] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 19:33:01 INFO - PROCESS | 1660 | RSS: Main: 237051904 19:33:01 INFO - PROCESS | 1660 | 19:33:05 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-beat-detection) 19:33:06 INFO - PROCESS | 1660 | RSS: Main: 249790464 19:33:06 INFO - PROCESS | 1660 | 19:33:08 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-dft) 19:33:09 INFO - PROCESS | 1660 | RSS: Main: 257290240 19:33:09 INFO - PROCESS | 1660 | 19:33:13 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-fft) 19:33:13 INFO - PROCESS | 1660 | RSS: Main: 259989504 19:33:13 INFO - PROCESS | 1660 | 19:33:15 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49258/tests/kraken/driver.html?testName=audio-oscillator) 19:33:16 INFO - PROCESS | 1660 | RSS: Main: 262086656 19:33:16 INFO - PROCESS | 1660 | 19:33:18 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-gaussian-blur) 19:33:19 INFO - PROCESS | 1660 | RSS: Main: 262762496 19:33:19 INFO - PROCESS | 1660 | 19:33:22 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-darkroom) 19:33:22 INFO - PROCESS | 1660 | RSS: Main: 269049856 19:33:22 INFO - PROCESS | 1660 | 19:33:26 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49258/tests/kraken/driver.html?testName=imaging-desaturate) 19:33:26 INFO - PROCESS | 1660 | RSS: Main: 267591680 19:33:26 INFO - PROCESS | 1660 | 19:33:29 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49258/tests/kraken/driver.html?testName=json-parse-financial) 19:33:30 INFO - PROCESS | 1660 | RSS: Main: 267116544 19:33:30 INFO - PROCESS | 1660 | 19:33:32 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49258/tests/kraken/driver.html?testName=json-stringify-tinderbox) 19:33:32 INFO - PROCESS | 1660 | RSS: Main: 267243520 19:33:32 INFO - PROCESS | 1660 | 19:33:34 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-aes) 19:33:35 INFO - PROCESS | 1660 | RSS: Main: 267264000 19:33:35 INFO - PROCESS | 1660 | 19:33:37 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-ccm) 19:33:38 INFO - PROCESS | 1660 | RSS: Main: 267235328 19:33:38 INFO - PROCESS | 1660 | 19:33:41 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 19:33:42 INFO - PROCESS | 1660 | RSS: Main: 267567104 19:33:42 INFO - PROCESS | 1660 | 19:33:45 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 19:33:46 INFO - PROCESS | 1660 | RSS: Main: 267362304 19:33:46 INFO - PROCESS | 1660 | 19:33:47 INFO - PROCESS | 1660 | Cycle 1(1): loaded http://localhost:49258/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49258/tests/kraken/driver.html?testName=ai-astar) 19:33:48 INFO - PROCESS | 1660 | RSS: Main: 269852672 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | __start_tp_report 19:33:48 INFO - PROCESS | 1660 | _x_x_mozilla_page_load 19:33:48 INFO - PROCESS | 1660 | _x_x_mozilla_page_load_details 19:33:48 INFO - PROCESS | 1660 | |i|pagename|runs| 19:33:48 INFO - PROCESS | 1660 | |0;ai-astar;105;118;120;122;170;177;177;121;155;167 19:33:48 INFO - PROCESS | 1660 | |1;audio-beat-detection;149;134;148;140;136;127;107;103;118;112 19:33:48 INFO - PROCESS | 1660 | |2;audio-dft;225;196;193;228;280;279;193;244;275;221 19:33:48 INFO - PROCESS | 1660 | |3;audio-fft;97;77;59;72;87;51;60;80;120;80 19:33:48 INFO - PROCESS | 1660 | |4;audio-oscillator;91;70;83;72;72;70;87;95;100;114 19:33:48 INFO - PROCESS | 1660 | |5;imaging-gaussian-blur;153;115;115;117;114;116;114;117;116;116 19:33:48 INFO - PROCESS | 1660 | |6;imaging-darkroom;102;103;133;175;171;171;101;103;172;104 19:33:48 INFO - PROCESS | 1660 | |7;imaging-desaturate;146;92;93;94;91;91;83;144;150;109 19:33:48 INFO - PROCESS | 1660 | |8;json-parse-financial;58;57;56;63;80;85;85;57;58;59 19:33:48 INFO - PROCESS | 1660 | |9;json-stringify-tinderbox;70;74;87;77;77;69;98;102;104;85 19:33:48 INFO - PROCESS | 1660 | |10;stanford-crypto-aes;94;88;91;86;92;91;88;87;91;100 19:33:48 INFO - PROCESS | 1660 | |11;stanford-crypto-ccm;139;176;157;162;157;168;161;159;223;169 19:33:48 INFO - PROCESS | 1660 | |12;stanford-crypto-pbkdf2;177;179;179;184;197;235;182;180;219;185 19:33:48 INFO - PROCESS | 1660 | |13;stanford-crypto-sha256-iterative;73;87;88;72;70;67;68;67;68;71 19:33:48 INFO - PROCESS | 1660 | __end_tp_report 19:33:48 INFO - PROCESS | 1660 | __start_cc_report 19:33:48 INFO - PROCESS | 1660 | _x_x_mozilla_cycle_collect,964 19:33:48 INFO - PROCESS | 1660 | __end_cc_report 19:33:48 INFO - PROCESS | 1660 | __startTimestamp1481859228071__endTimestamp 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | ------- Summary: start ------- 19:33:48 INFO - PROCESS | 1660 | Number of tests: 14 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#0] ai-astar Cycles:10 Average:143.20 Median:161.00 stddev:28.45 (17.7%) stddev-sans-first:26.60 19:33:48 INFO - PROCESS | 1660 | Values: 105.0 118.0 120.0 122.0 170.0 177.0 177.0 121.0 155.0 167.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#1] audio-beat-detection Cycles:10 Average:127.40 Median:135.00 stddev:16.68 (12.4%) stddev-sans-first:15.76 19:33:48 INFO - PROCESS | 1660 | Values: 149.0 134.0 148.0 140.0 136.0 127.0 107.0 103.0 118.0 112.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#2] audio-dft Cycles:10 Average:233.40 Median:236.00 stddev:34.98 (14.8%) stddev-sans-first:36.97 19:33:48 INFO - PROCESS | 1660 | Values: 225.0 196.0 193.0 228.0 280.0 279.0 193.0 244.0 275.0 221.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#3] audio-fft Cycles:10 Average:78.30 Median:78.50 stddev:20.18 (25.7%) stddev-sans-first:20.23 19:33:48 INFO - PROCESS | 1660 | Values: 97.0 77.0 59.0 72.0 87.0 51.0 60.0 80.0 120.0 80.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#4] audio-oscillator Cycles:10 Average:85.40 Median:77.50 stddev:14.89 (19.2%) stddev-sans-first:15.66 19:33:48 INFO - PROCESS | 1660 | Values: 91.0 70.0 83.0 72.0 72.0 70.0 87.0 95.0 100.0 114.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#5] imaging-gaussian-blur Cycles:10 Average:119.30 Median:116.00 stddev:11.89 (10.2%) stddev-sans-first:1.13 19:33:48 INFO - PROCESS | 1660 | Values: 153.0 115.0 115.0 117.0 114.0 116.0 114.0 117.0 116.0 116.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#6] imaging-darkroom Cycles:10 Average:133.50 Median:152.00 stddev:34.64 (22.8%) stddev-sans-first:34.81 19:33:48 INFO - PROCESS | 1660 | Values: 102.0 103.0 133.0 175.0 171.0 171.0 101.0 103.0 172.0 104.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#7] imaging-desaturate Cycles:10 Average:109.30 Median:91.00 stddev:26.60 (29.2%) stddev-sans-first:24.68 19:33:48 INFO - PROCESS | 1660 | Values: 146.0 92.0 93.0 94.0 91.0 91.0 83.0 144.0 150.0 109.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#8] json-parse-financial Cycles:10 Average:65.80 Median:61.00 stddev:12.32 (20.2%) stddev-sans-first:12.74 19:33:48 INFO - PROCESS | 1660 | Values: 58.0 57.0 56.0 63.0 80.0 85.0 85.0 57.0 58.0 59.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#9] json-stringify-tinderbox Cycles:10 Average:84.30 Median:77.00 stddev:13.12 (17.0%) stddev-sans-first:12.85 19:33:48 INFO - PROCESS | 1660 | Values: 70.0 74.0 87.0 77.0 77.0 69.0 98.0 102.0 104.0 85.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#10] stanford-crypto-aes Cycles:10 Average:90.80 Median:91.00 stddev:4.08 (4.5%) stddev-sans-first:4.16 19:33:48 INFO - PROCESS | 1660 | Values: 94.0 88.0 91.0 86.0 92.0 91.0 88.0 87.0 91.0 100.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#11] stanford-crypto-ccm Cycles:10 Average:167.10 Median:165.00 stddev:21.94 (13.3%) stddev-sans-first:20.78 19:33:48 INFO - PROCESS | 1660 | Values: 139.0 176.0 157.0 162.0 157.0 168.0 161.0 159.0 223.0 169.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:191.70 Median:184.50 stddev:19.78 (10.7%) stddev-sans-first:20.25 19:33:48 INFO - PROCESS | 1660 | Values: 177.0 179.0 179.0 184.0 197.0 235.0 182.0 180.0 219.0 185.0 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - PROCESS | 1660 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.10 Median:71.50 stddev:7.87 (11.0%) stddev-sans-first:8.34 19:33:48 INFO - PROCESS | 1660 | Values: 73.0 87.0 88.0 72.0 70.0 67.0 68.0 67.0 68.0 71.0 19:33:48 INFO - PROCESS | 1660 | -------- Summary: end -------- 19:33:48 INFO - PROCESS | 1660 | 19:33:48 INFO - TEST-INFO | 1660: exit 0 19:33:48 INFO - TEST-OK | kraken | took 63052ms 19:33:48 INFO - SUITE-END | took 1179s 19:33:49 INFO - Completed test suite (00:19:39) 19:33:49 INFO - javascript benchmark 19:33:49 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7180.797338102491, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2790.9952417129844, 3355.9093294679205, 3374.52756614074, 3412.0, 3420.0936751760787, 4461.420015397994, 5566.721663916798, 5624.493795558399, 5640.971795141024, 5708.628939118965, 5623.775048998035, 5681.034224181889, 5684.261046064006, 5700.258966334371, 5786.652800831943, 5118.718470484122, 5218.373795144588, 5239.711815850127, 5307.52232299093, 5324.733763311839, 4758.762061896909, 4984.75076246188, 5252.317198764155, 5334.546563542084, 5348.545373643245, 5109.41241757199, 5251.083354575571, 5303.36359636846, 5318.973405132988, 5325.973370133144, 5144.717040562768, 5199.870003249911, 5200.141976573861, 5220.895582088337, 5235.0, 4441.200583894898, 5112.437631860493, 5236.842894713165, 5243.397009343936, 5252.474752524755, 5525.475079867406, 5564.803567233064, 5733.684647344395, 5754.625949313281, 5828.9417105828825, 5481.123020316731, 5547.8890422191735, 5830.533557315447, 6097.3292937776805, 6105.702364394958, 6195.3185149633955, 6253.1558239637525, 6255.218097737783, 6258.687065646699, 6267.811965641038, 5856.472917437451, 5912.260967379078, 5926.259217597801, 5928.992071347838, 5951.107333899907, 6322.810315690536, 6339.334369891188, 6341.112244285804, 6345.904811427832, 6353.682315884187, 6068.4538391544975, 6123.571350005546, 6131.938680613226, 6155.353687862799, 6238.812835614939, 6114.753609057711, 6189.412005859436, 6206.472449841814, 6259.18630578031, 6307.0, 5586.413426590149, 6109.6475967989745, 6146.231721034871, 6183.258009038944, 6212.875742485125, 5613.943860561342, 6242.500599951993, 6274.058891166362, 6279.403456671609, 6301.117843501914, 4816.783244753994, 4849.4908034656555, 4899.240617704261, 4904.215325547895, 4939.87650308738, 4452.799624016927, 4460.442444694413, 4461.598456138963, 4475.5748203920575, 4508.368828364031, 5824.912626310609, 5882.470577647947, 5943.078822782476, 5953.1070339449425, 5987.371326010793, 6141.056449558148, 6194.5973511721595, 6228.439440450291, 6301.180846489927, 6307.085472606446, 5878.617889837233, 5882.499987501024, 5889.234399528034, 5945.16767652529, 5980.102984552352, 5450.046241907729, 5470.070088085035, 5470.781168753285, 5474.808381706621, 5488.423715509894, 6205.875882482415, 6340.080688300079, 6365.840853978595, 6426.4216220540375, 6440.098386225932, 6171.197744293213, 6210.130581718473, 6227.96886015567, 6267.717952692231, 6320.747170113144, 6400.07198956158, 6406.423421892052, 6418.03729440569, 6425.710843011982, 6430.549861509649, 6079.3008803988105, 6243.0, 6253.96873015632, 6256.124142620128, 6262.686865656608, 6091.604045737013, 6141.393954534099, 6144.709610981744, 6164.982777841606, 6187.969060154671, 6041.365656606169, 6174.567780255518, 6176.104464852481, 6182.876342473215, 6204.906926396017, 5992.850178745566, 6083.543734220039, 6154.046122850965, 6156.384361563987, 6159.72281247336, 4826.444958829779, 5869.972754767984, 6040.456358927542, 6077.392260774064, 6127.785527506516, 6137.214633442868, 6141.416565426277, 6150.415710507495, 6174.845628859135, 6205.317415084247, 5732.369439361751, 6004.0693692477735, 6033.577649564664, 6053.848653783514, 6078.270607527125], "value": 5691.448306704017, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [483.80164132705596, 491.5330436085719, 492.82012065596047, 501.90584525733885, 502.4749137151677, 39488.80255598722, 41774.0, 41894.581054189446, 41906.37140442892, 41952.160956780885, 42374.57625423745, 42575.78712106435, 42860.571394286046, 42936.0, 42981.14037719244, 8674.219320261176, 8766.34252431067, 9411.576479058442, 9637.036296370343, 9643.80712385752, 9288.814223715523, 9408.77685900832, 10012.39925604466, 10036.698899033041, 11831.290122592673, 11585.246958947642, 11621.12841536884, 11679.766404671902, 11689.473973671164, 11730.589429369973, 11436.19946603738, 11448.71378215543, 11517.712057198552, 11528.59649912249, 11547.133964952662, 11458.197926145172, 11534.307941523535, 11549.826752598718, 11551.306921584732, 11573.479193436315, 41259.0, 41300.586994129975, 41310.0, 41398.0, 41696.583034169875, 3362.6132994705677, 3577.606463289036, 3764.435334699817, 3945.2504024235304, 4043.1913617276664, 25573.48853022948, 30579.388412231852, 31191.532127018112, 31431.585578649017, 31442.056738297888, 3240.270939038721, 3273.4107860585086, 3318.7510936679846, 3362.512435696835, 3368.1747971747077, 467416.0, 473837.4156904492, 477537.6123119397, 479105.0, 480564.5971770154, 60206.69896650489, 60294.69852650708, 60533.39466605365, 60742.392576073675, 60832.695836521416, 63875.0, 64393.0, 64449.67775161094, 64503.35496645067, 64655.353446464935, 15611.609709757347, 16145.919270403572, 16233.457821506938, 16238.188090595422, 16245.918770406071, 9771.853422198672, 11453.312801231952, 12198.817017744741, 12519.624411267676, 12567.497300108076, 18060.819391806177, 18109.0, 18122.90938545325, 18125.546861328574, 18194.727079093824, 6902.758403455855, 6983.615901125385, 7046.61243631595, 7070.52531613931, 7077.079979602721, 878.639757699353, 945.664289177346, 995.2883688162854, 1034.043509753475, 1054.086832399193, 28762.424751504852, 30034.549481757793, 30734.077977660705, 30884.53673194904, 31128.910488132806, 1043.9112675422523, 1046.0, 1095.0199571383566, 1176.9352685602373, 1190.3215167354524, 15966.521004369888, 15981.0, 15988.600284992735, 16265.837341626433, 16382.508524744277, 603233.0, 604829.0, 607707.0, 609748.0, 611008.0, 60540.091898621555, 60697.39302607006, 60705.0, 60843.0, 61038.69480652568, 1357.83705955286, 1379.4620098161724, 1391.193107997359, 1404.7120340330207, 1406.9788953165505, 29671.25821854471, 30460.84769576138, 30732.231694207825, 30799.692003079683, 31248.687513124576, 52631.73684131555, 53104.73447632737, 53220.935581287384, 53278.73360633172, 53292.73353633207, 553823.0, 556997.0, 557492.3379214182, 557498.0, 559065.0, 24745.62881556742, 25461.872690636428, 25738.485230294915, 26137.869310653325, 41742.79128604338, 42449.36325955051, 42740.71777846669, 42985.57014429816, 43347.566524334354, 43834.0, 35810.64189358073, 36111.63888361083, 36163.81918090393, 37147.81426092852, 37571.0, 36206.63793362138, 36277.0, 36706.8164659175, 37156.814215928745, 37184.0, 35731.46402803908, 36169.81915090408, 36767.448488273236, 36805.815970919975, 36889.0, 31317.217069573442, 32629.18427039343, 32709.672903270664, 32721.0, 33412.33175336528, 22165.0, 24053.879730601235, 24606.0, 25036.62445063289, 25058.0], "value": 20221.618921717163, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [331.7627896054321, 346.5771758454685, 371.1833965276391, 378.71596302772923, 389.28954657749614, 18870.433886983395, 18947.242110315587, 19143.90428047858, 19698.212071517122, 19908.303209387675, 18728.906355468236, 19111.808881911176, 19678.704819427683, 19688.91710955897, 19831.5042123947, 4893.779779909894, 5477.917831232535, 5762.3373312069225, 5763.9711801440935, 5774.624649397776, 4667.5332466753225, 5712.457316554921, 5767.1349297605275, 5768.04827203513, 5770.480656740892, 4856.660033797635, 5104.719240441774, 5449.891002179954, 5645.520130788888, 5698.6865722385255, 4726.787294571753, 4740.620750339965, 4873.43955445123, 5712.771489140471, 5740.368559458456, 4101.5488296287385, 4290.76400797956, 5022.372203474566, 5035.521625445577, 5052.267421223902, 12627.873721262853, 12923.67690807728, 13004.934975325157, 13048.412821423059, 13062.477500899953, 2013.5670830771455, 2365.8698771567556, 2489.9751002489925, 2562.5387430262545, 2879.568064790298, 9723.90276097237, 9743.0, 9882.901170988342, 10220.335678180894, 10305.12406445453, 1749.6063385738235, 1813.5012871460322, 1836.4031689700766, 1862.5622978600018, 1900.8669393142486, 111067.0, 119408.40295798553, 122918.38540807329, 123534.0, 123758.38120809429, 9246.676366327147, 9620.518974051269, 9892.109710125978, 10030.64892728751, 11481.196316257781, 12039.63881083569, 12355.196912200678, 12395.566155184526, 12405.75188496225, 12417.75164496705, 6916.654167291716, 7710.845783084419, 7930.0880398753725, 8019.719309824128, 8032.437729359006, 7040.0, 8199.877001844978, 8204.958975205085, 8214.0, 8370.874436883452, 3295.3244584860045, 3477.6174620791694, 3770.340190466712, 3825.713071519647, 3920.2159568086495, 1642.5565097423628, 1728.9827101729072, 1853.5366158460386, 1891.4136617648574, 1946.2506934830192, 818.6193420059701, 916.4237511480243, 959.8128364968903, 985.1527686189871, 1014.2241185493103, 19990.000499974944, 20847.0, 20962.266320678704, 21041.789582104288, 21134.894325528276, 846.191886748154, 920.9677661281823, 938.7465384346189, 950.4249928793118, 952.5570609666538, 10386.948065259625, 10878.075363594184, 10953.34279943206, 11192.936671016241, 11233.943830280796, 569406.0, 577218.0, 579486.0, 582472.0, 582863.0, 96840.51579742056, 125905.0, 126494.0, 131722.0, 135881.3205933964, 969.9757506062264, 1003.8644782954353, 1019.0980981831133, 1020.1125021231576, 1055.145332280855, 14788.260586970824, 17978.280868765625, 19432.70850937209, 19474.707879381538, 19496.0, 38346.42480362741, 41185.58814411818, 43520.3887766735, 44252.459803215024, 45970.391036313566, 54415.0, 59682.70158649179, 70732.64633677005, 79999.0, 80723.19276807392, 5575.972120139373, 5896.7936122235515, 7467.402607791472, 8033.718819841278, 8296.502209867427, 11512.769744605228, 12132.272063676208, 12568.30874301922, 12658.493660253855, 12666.0, 8749.606267718094, 10497.632582859564, 10935.070519005656, 11071.169662275523, 11119.05488033494, 5312.229726689686, 7577.772666820004, 10070.647527336509, 10330.638427654996, 11023.61417350389, 10745.570177192825, 10947.781044379228, 11067.612633557786, 11079.889201107886, 11495.54017839277, 6598.406143447113, 6910.689018994258, 7272.745453909088, 7470.215627359266, 8003.4797738146835, 8906.376553641183, 10974.835377469184, 11088.279261847954, 11278.887211127783, 11367.829482557934], "value": 8805.691776343352, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1467.5230550071226, 1486.7621180611104, 1708.9316427342908, 1808.9819101808994, 1831.4139475367888, 7540.17058123606, 7808.258215469536, 7965.283124518792, 8415.242628163463, 8435.70475033374, 7968.3625309975205, 8287.337013038958, 8303.045149807775, 8412.327013838894, 8434.578271086451, 2306.746257911628, 2344.6717459555675, 2366.372911178539, 2370.277065495032, 2372.525494901018, 2360.5042940982416, 2369.9644505332435, 2383.511380167062, 2390.9760902391013, 2393.0427828868505, 2320.96518552221, 2345.4605440748637, 2363.5863723848256, 2366.5976783946685, 2371.063429945171, 2297.0811675329833, 2312.9306120816404, 2313.548857972696, 2331.125827814561, 2348.459854233519, 2336.7312759032757, 2355.128602417117, 2358.752331005237, 2378.9524209515885, 2380.7500212477785, 8331.0, 8368.748937531884, 8439.493630382136, 8446.704365347183, 8451.53516556589, 5026.773795179226, 5163.457836927144, 5209.062368773619, 5213.426523082458, 5221.242919776649, 3455.1362159460136, 3465.9653403465895, 3506.5977970991567, 3516.613172551017, 3518.3842827505086, 1028.7890982348601, 1033.7777377863795, 1049.2969710294121, 1065.7761870007307, 1066.7599790047254, 10650.946745266301, 10741.731456713645, 10779.461026948542, 10797.622083227128, 10867.021968022837, 4429.911401771947, 4623.815047398067, 4631.166390049823, 4739.3601863748645, 4747.359106520576, 7789.415793815373, 9265.72202833916, 9335.299852511089, 9355.672551460666, 9358.204552613104, 5143.614228932845, 5204.479552044841, 5208.635395522277, 5208.765605547759, 5212.739363031833, 4260.957390426118, 4284.464441944757, 4663.067386522709, 4769.475357710649, 4778.450478194983, 3326.6507016763376, 3753.6997040236715, 3802.5817160112365, 3892.1242720388254, 3915.1778126593163, 1231.408923716621, 1265.4621785741024, 1276.6297773645538, 1279.9232046077266, 1280.231860883481, 603.856379036003, 723.3706675192616, 837.874318852177, 851.9957400212959, 867.1025488619249, 5458.890822183614, 5461.644993075517, 5566.610337276433, 5675.460831221028, 5743.167240750068, 924.5007695844168, 952.8332541805295, 972.2270794718216, 972.9173020293213, 990.3909095905966, 3317.4194515960094, 3713.535808023997, 3758.285925674113, 3770.472133901256, 3858.9228215436096, 9838.0, 10023.0, 10151.289409741396, 10158.034986676254, 10169.338992965582, 9330.533473326306, 9909.355891867006, 10099.899001010042, 10105.898941010495, 10108.595656173671, 7492.288232617892, 7746.0, 7865.685372585147, 7875.724349647735, 7875.763727088197, 5329.813456529003, 5406.972965135149, 5620.4098569649595, 5634.492895639412, 5652.943470565242, 8589.871151932852, 9084.273258139465, 9145.222656074307, 9226.0, 9364.719058428258, 9300.860487092834, 9362.157405833508, 9371.437713737198, 9469.47917864524, 9506.95246523763, 7124.857502850017, 7362.484626076338, 7559.357454616199, 7709.7301594443925, 7863.685452581833, 7737.0715514138665, 8006.879896801669, 8013.038435387791, 8018.799530011796, 8021.197880211932, 5796.391378905154, 7038.859222815413, 7150.67821948024, 7187.8562428752175, 7273.418126549757, 7009.1939426966555, 7054.858902821812, 7058.063233247248, 7111.684338397413, 7162.104736907887, 6597.80206593803, 6631.867362652816, 6702.430293425114, 6711.530192886451, 6713.865722685617, 4785.497522760199, 4820.4456487504385, 4865.756712164449, 4880.682755620874, 4909.288153217834, 4757.119932812441, 4853.927191092066, 4879.512048795092, 4934.235193545006, 5006.69959802413], "value": 4416.354630494833, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [346.50276852716354, 350.8403676327271, 353.86199382240915, 362.4617443097001, 370.2687192794232, 4642.721436713795, 4682.625389968803, 4746.6440016998695, 5119.411267704215, 5483.945160548394, 4357.1264356326765, 5218.739063046852, 5263.289455923449, 5381.650192737479, 5416.295881535405, 3974.8075577326686, 4007.499062617173, 4248.3627455881715, 4256.87229383119, 4271.444712187426, 3702.074481379655, 3718.981405092971, 3763.2285381496727, 4295.377170310303, 4305.4833419989645, 3401.472771720375, 4060.7157498975084, 4068.328725760246, 4068.4304197412384, 4072.5316588592245, 4043.7978101094973, 4079.2861249281405, 4100.118474527991, 4100.2414553307435, 4122.814473348706, 3328.118048717092, 3552.982235088808, 3560.91097722559, 3562.002639261011, 3578.3916734155255, 4593.196190666654, 4991.550760431579, 5246.816361427332, 5364.5398717827175, 5366.329208848894, 3797.468354430382, 3934.603215858386, 4665.206914824454, 4671.369365135697, 4678.929816052762, 5731.770729170828, 5759.9968022224575, 5773.88452230953, 5775.653460792366, 5778.711064446761, 2861.585070164835, 3479.443289073761, 3496.8776092836883, 3547.467879818022, 3873.573906870242, 5950.9702451487465, 5954.851128721816, 5960.125918153191, 5970.850728731773, 5980.5646644805365, 3514.5606799150105, 3929.25344184604, 3929.4302326162547, 3933.6066393360434, 3955.3078211312904, 3840.539135303781, 3971.2851686696094, 4000.4799376080923, 4025.879223623296, 4029.8589549365634, 4613.6089973869175, 4621.5378462154185, 4641.632767288707, 4652.674312798072, 4669.789859456333, 5589.832305030855, 5740.799072032542, 5780.653160810365, 5788.82633520995, 5799.652020878676, 1176.6234804862534, 1212.1333246728632, 1221.4487600747002, 1259.603224984127, 1285.7685616589104, 718.3606590134784, 766.9041369828772, 809.10997902307, 869.3566760597113, 876.1632640827994, 4324.524302326741, 4341.0, 5070.873228169252, 5131.948680513222, 5221.738913054332, 4736.726371341706, 4838.443578988461, 4910.508949105061, 5107.565856902205, 5221.973890130525, 5115.18157094863, 5137.280780690707, 5140.553421290557, 5160.767765450564, 5190.896182076337, 2887.5235586128474, 2941.8970336038137, 2946.484365236118, 2946.57274695168, 2975.642922849272, 5262.342207224097, 5379.838604841861, 5788.27646544182, 5793.24687790593, 5829.504492118217, 4764.546813221931, 4789.880252993633, 5468.972655136698, 5719.828405147852, 5770.596058275881, 5526.58550608714, 5815.592908496449, 5820.933568583177, 5830.679312637845, 5846.473817356458, 4482.520768146439, 5464.18594423864, 5520.006398848085, 5535.6955367455175, 5537.972310138423, 5183.663061900965, 5258.007828101341, 5441.2654291670115, 5467.890642187054, 5517.806876759294, 5185.403678577012, 5663.886722265449, 5774.9133762994425, 5830.4461076197695, 5911.861696812162, 3637.5453068366455, 3813.7521061130938, 3896.3181443247886, 3906.8437262509183, 3957.9010524737114, 5565.526930210977, 5910.822675319747, 5941.465268125717, 5956.70666793279, 6016.4885984690045, 5087.796488140433, 5378.784848606168, 5722.62802917809, 5781.855453613525, 5836.854078647898, 4863.440704319049, 5671.102053863935, 5685.403032681675, 5720.427957204246, 5751.971240143773, 4143.668506519411, 5256.816011439581, 5392.164214546751, 5435.130379139318, 5663.32040155184, 4411.338299255138, 4665.55677210678, 5714.457126572969, 5725.0248664210785, 5727.599068065196, 4995.825146119869, 5327.9467205329, 5465.6447330923365, 5473.616846820843, 5667.659940403589], "value": 4075.0269467102657, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [651.4169818012879, 664.9867002659947, 710.9715611375545, 792.1721800718249, 833.4415941319315, 14907.031042982198, 15197.772033419507, 15300.464483743071, 15603.141827199499, 15618.843811561881, 13925.93037034816, 14060.226687532182, 14078.929605351985, 14305.213213273266, 14362.784558231635, 843.9324854011664, 864.3690106222462, 866.1511718515858, 866.3026263857593, 867.7179916527153, 677.8136012596551, 700.310194458458, 844.6452489954203, 861.840559496492, 863.7797361672796, 918.4626993208981, 926.6802952981211, 926.7683079230193, 927.7077720518049, 928.0255731481951, 919.7608621758358, 920.6179435534245, 921.7280902133887, 921.8156368726214, 922.7877588154762, 656.2780940964948, 656.6815094679076, 680.4148432348226, 681.2438193605094, 681.8806708825936, 15013.0, 15260.160691161982, 15334.923325383414, 15347.386104555804, 15424.76862847047, 14211.857881421156, 14543.927280363636, 14561.854381456156, 14576.562703118923, 14595.562133136023, 11796.410179490991, 11804.704882377924, 11853.822192667118, 11885.227460215145, 11905.107116966175, 3129.3741251749743, 3299.142223022008, 3432.6567343265724, 3678.08047988003, 3702.9814850925573, 102823.0, 107846.46076769567, 108487.45756271247, 109507.0, 109863.0, 10918.0, 10931.344119352865, 10969.725756856142, 10977.506012229469, 11246.606368777053, 22492.437689057577, 22606.886965565067, 22729.0, 22914.65628015581, 22955.0, 7577.772666819894, 7615.162332143571, 7644.847103058019, 7666.731664391719, 7785.18255583167, 4076.8573099941955, 4161.604647558477, 4219.852305169305, 4220.11377610699, 4222.535521092677, 3375.645557216457, 3438.8108654023767, 3459.3773120837986, 3460.2733425980323, 3464.2378676691087, 677.881370760115, 699.8320403103219, 707.8088915992653, 714.7176865138346, 725.2855936902147, 613.1997742945409, 653.1345966594187, 662.4270006444391, 673.1316601584, 694.3924066441863, 8743.0, 8761.649534018696, 9264.351495395258, 9469.715908522756, 9523.809523809485, 548.0381929713407, 598.3209057719432, 607.781198768443, 608.969551522431, 675.6993138053617, 4480.305552639346, 4569.337446070368, 4572.908541829145, 4580.0, 4597.333386658983, 105355.0, 110151.0, 110479.0, 110727.44636276927, 112037.43981280041, 82128.58935705283, 95573.0, 99583.0, 100178.0, 103044.0, 69514.65242673755, 71046.9342959856, 82596.58701706573, 88426.0, 90020.0, 8392.496450213006, 8526.0, 10005.649802256887, 10045.799084018425, 10166.847497287694, 41288.58711412847, 49634.27011514401, 51958.0, 52244.0, 53104.20343694925, 65425.67287163724, 72565.27434725796, 74350.0, 74447.62776186084, 76542.0, 13348.332583370986, 13739.24434156131, 14047.297635118408, 14361.21013344276, 14486.422727249874, 14520.273986300432, 14662.193579353238, 14736.852561937445, 14845.40618375253, 15426.305816238071, 12156.75686486283, 13335.06615548435, 13730.656733581342, 13909.64122986914, 13959.441622334993, 13727.725445490834, 13728.588142355746, 13763.0, 13899.235542045284, 14334.856651433352, 13313.068085233941, 13568.525101621395, 13613.455461781814, 13640.86359136396, 14127.788083178555, 9229.35394522377, 11038.944805275922, 11057.668269951915, 11232.438378081226, 11768.293902365886, 8591.527465989431, 9513.286503512127, 11252.043576296106, 11588.710282243012, 11631.825522616999], "value": 7516.099018049789, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1702.5999999999997, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [105.0, 118.0, 120.0, 122.0, 170.0, 177.0, 177.0, 121.0, 155.0, 167.0], "value": 143.2, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [149.0, 134.0, 148.0, 140.0, 136.0, 127.0, 107.0, 103.0, 118.0, 112.0], "value": 127.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [225.0, 196.0, 193.0, 228.0, 280.0, 279.0, 193.0, 244.0, 275.0, 221.0], "value": 233.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [97.0, 77.0, 59.0, 72.0, 87.0, 51.0, 60.0, 80.0, 120.0, 80.0], "value": 78.3, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 70.0, 83.0, 72.0, 72.0, 70.0, 87.0, 95.0, 100.0, 114.0], "value": 85.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [153.0, 115.0, 115.0, 117.0, 114.0, 116.0, 114.0, 117.0, 116.0, 116.0], "value": 119.3, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [102.0, 103.0, 133.0, 175.0, 171.0, 171.0, 101.0, 103.0, 172.0, 104.0], "value": 133.5, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 92.0, 93.0, 94.0, 91.0, 91.0, 83.0, 144.0, 150.0, 109.0], "value": 109.3, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 56.0, 63.0, 80.0, 85.0, 85.0, 57.0, 58.0, 59.0], "value": 65.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [70.0, 74.0, 87.0, 77.0, 77.0, 69.0, 98.0, 102.0, 104.0, 85.0], "value": 84.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 88.0, 91.0, 86.0, 92.0, 91.0, 88.0, 87.0, 91.0, 100.0], "value": 90.8, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [139.0, 176.0, 157.0, 162.0, 157.0, 168.0, 161.0, 159.0, 223.0, 169.0], "value": 167.1, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [177.0, 179.0, 179.0, 184.0, 197.0, 235.0, 182.0, 180.0, 219.0, 185.0], "value": 191.7, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 87.0, 88.0, 72.0, 70.0, 67.0, 68.0, 67.0, 68.0, 71.0], "value": 73.1, "unit": "score"}]}]} 19:33:49 INFO - Return code: 0 19:33:49 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 19:33:49 ERROR - # TBPL SUCCESS # 19:33:49 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 19:33:49 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 19:33:49 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 19:33:49 INFO - 2016-12-15 19:33:49.437 screenresolution[1665:10665] starting screenresolution argv=screenresolution get 19:33:49 INFO - 2016-12-15 19:33:49.439 screenresolution[1665:10665] Display 0: 1600x1200x32@60 19:33:49 INFO - 2016-12-15 19:33:49.445 screenresolution[1666:10668] starting screenresolution argv=screenresolution list 19:33:49 INFO - Available Modes on Display 0 19:33:49 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 19:33:49 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 19:33:49 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 19:33:49 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 19:33:49 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 19:33:49 INFO - Intel Iris: 19:33:49 INFO - Chipset Model: Intel Iris 19:33:49 INFO - Type: GPU 19:33:49 INFO - Bus: Built-In 19:33:49 INFO - VRAM (Dynamic, Max): 1536 MB 19:33:49 INFO - Vendor: Intel (0x8086) 19:33:49 INFO - Device ID: 0x0a2e 19:33:49 INFO - Revision ID: 0x0009 19:33:49 INFO - Displays: 19:33:49 INFO - Display: 19:33:49 INFO - Resolution: 1600 x 1200 @ 60 Hz 19:33:49 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 19:33:49 INFO - Display Serial Number: 1600x1200 60 19:33:49 INFO - Main Display: Yes 19:33:49 INFO - Mirror: Off 19:33:49 INFO - Online: Yes 19:33:49 INFO - Rotation: Supported 19:33:49 INFO - Return code: 0 19:33:49 INFO - Running post-action listener: _resource_record_post_action 19:33:49 INFO - [mozharness: 2016-12-16 03:33:49.680248Z] Finished run-tests step (success) 19:33:49 INFO - Running post-run listener: _resource_record_post_run 19:33:49 INFO - Total resource usage - Wall time: 1202s; CPU: 55.0%; Read bytes: 39553536; Write bytes: 554053632; Read time: 387; Write time: 1764 19:33:49 INFO - TinderboxPrint: CPU usage
54.5% 19:33:49 INFO - TinderboxPrint: I/O read bytes / time
39,553,536 / 387 19:33:49 INFO - TinderboxPrint: I/O write bytes / time
554,053,632 / 1,764 19:33:49 INFO - TinderboxPrint: CPU idle
2,186.2 (45.5%) 19:33:49 INFO - TinderboxPrint: CPU system
971.1 (20.2%) 19:33:49 INFO - TinderboxPrint: CPU user
1,652.4 (34.4%) 19:33:49 INFO - TinderboxPrint: Swap in / out
289,050,624 / 0 19:33:49 INFO - install - Wall time: 22s; CPU: 54.0%; Read bytes: 243225088; Write bytes: 238110720; Read time: 17971; Write time: 465 19:33:49 INFO - run-tests - Wall time: 1181s; CPU: 55.0%; Read bytes: 37497344; Write bytes: 300275712; Read time: 364; Write time: 1245 19:33:50 INFO - Running post-run listener: _upload_blobber_files 19:33:50 INFO - Blob upload gear active. 19:33:50 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 19:33:50 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 19:33:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Firefox', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 19:33:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Firefox -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 19:33:50 INFO - (blobuploader) - INFO - Open directory for files ... 19:33:50 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 19:33:50 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:50 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:51 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 19:33:51 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:51 INFO - (blobuploader) - INFO - Done attempting. 19:33:51 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 19:33:51 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:51 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:52 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 19:33:52 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:52 INFO - (blobuploader) - INFO - Done attempting. 19:33:52 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 19:33:52 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 19:33:52 INFO - (blobuploader) - INFO - Uploading, attempt #1. 19:33:55 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 19:33:55 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 19:33:55 INFO - (blobuploader) - INFO - Done attempting. 19:33:55 INFO - (blobuploader) - INFO - Iteration through files over. 19:33:55 INFO - Return code: 0 19:33:55 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 19:33:55 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 19:33:55 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8301c00f2694146a8d5c04e3377aeaedfaa12373093b05e18f4a17499befed40d06592e74630a414b007b7eb93e55580c302105f060bcf7469215b97aa9204d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11f720c6273fd49dc8ca5740da3448e3474296f8a31d83890bc6f10e5dd44915e718d8c421acd0ef31a3b480186f1b82726f39d6af55a77aedf191647371cf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/162a0a67a6d99139f4a26ecc6193d933172e6ffa78a61f30303023a75c81c0db3c2504dc1266252a47484601d3d7a7af866cd7c819a6cde54e5a3b63c6a5d246"} 19:33:55 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 19:33:55 INFO - Writing to file /builds/slave/test/properties/blobber_files 19:33:55 INFO - Contents: 19:33:55 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8301c00f2694146a8d5c04e3377aeaedfaa12373093b05e18f4a17499befed40d06592e74630a414b007b7eb93e55580c302105f060bcf7469215b97aa9204d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11f720c6273fd49dc8ca5740da3448e3474296f8a31d83890bc6f10e5dd44915e718d8c421acd0ef31a3b480186f1b82726f39d6af55a77aedf191647371cf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/162a0a67a6d99139f4a26ecc6193d933172e6ffa78a61f30303023a75c81c0db3c2504dc1266252a47484601d3d7a7af866cd7c819a6cde54e5a3b63c6a5d246"} 19:33:55 INFO - Running post-run listener: copy_logs_to_upload_dir 19:33:55 INFO - Copying logs to upload dir... 19:33:55 INFO - mkdir: /builds/slave/test/build/upload/logs 19:33:55 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1264.662426 ========= master_lag: 0.04 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 4 secs) (at 2016-12-15 19:33:55.466496) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 19:33:55.470364) ========= 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.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8301c00f2694146a8d5c04e3377aeaedfaa12373093b05e18f4a17499befed40d06592e74630a414b007b7eb93e55580c302105f060bcf7469215b97aa9204d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11f720c6273fd49dc8ca5740da3448e3474296f8a31d83890bc6f10e5dd44915e718d8c421acd0ef31a3b480186f1b82726f39d6af55a77aedf191647371cf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/162a0a67a6d99139f4a26ecc6193d933172e6ffa78a61f30303023a75c81c0db3c2504dc1266252a47484601d3d7a7af866cd7c819a6cde54e5a3b63c6a5d246"} build_url:https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011225 build_url: 'https://queue.taskcluster.net/v1/task/Y_FUtKn3RZuHvRqYxmMtVw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f8301c00f2694146a8d5c04e3377aeaedfaa12373093b05e18f4a17499befed40d06592e74630a414b007b7eb93e55580c302105f060bcf7469215b97aa9204d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/11f720c6273fd49dc8ca5740da3448e3474296f8a31d83890bc6f10e5dd44915e718d8c421acd0ef31a3b480186f1b82726f39d6af55a77aedf191647371cf1a", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/162a0a67a6d99139f4a26ecc6193d933172e6ffa78a61f30303023a75c81c0db3c2504dc1266252a47484601d3d7a7af866cd7c819a6cde54e5a3b63c6a5d246"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2016-12-15 19:33:55.504402) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 19:33:55.504729) ========= 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.dmVxpCJkXp/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.r42C6OtMMb/Listeners TMPDIR=/var/folders/rv/24r_1zwd7p5g3k569h6ts1cw00000w/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.005207 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2016-12-15 19:33:55.553767) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 19:33:55.554103) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2016-12-15 19:33:55.554418) ========= ========= Total master_lag: 3.57 =========