builder: mozilla-central_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0090 starttime: 1484513160.63 results: success (0) buildid: 20170115115223 builduid: 0259f9f96586428db9c6a3080b4daf4f revision: 50bd08fad34963be3ab52cb25a1c3e08e056f2cc ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:00.632590) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:00.633085) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:00.633416) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:00.660107) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:00.660599) ========= 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.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False --2017-01-15 12:46:00-- 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% 36.9M=0s 2017-01-15 12:46:01 (36.9 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.565501 ========= master_lag: 0.04 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:01.265540) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:01.265875) ========= 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.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.019678 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:01.317919) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-15 12:46:01.318293) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 50bd08fad34963be3ab52cb25a1c3e08e056f2cc --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 50bd08fad34963be3ab52cb25a1c3e08e056f2cc --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False 2017-01-15 12:46:01,396 truncating revision to first 12 chars 2017-01-15 12:46:01,396 Setting DEBUG logging. 2017-01-15 12:46:01,396 attempt 1/10 2017-01-15 12:46:01,396 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/50bd08fad349?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-01-15 12:46:02,518 unpacking tar archive at: mozilla-central-50bd08fad349/testing/mozharness/ program finished with exit code 0 elapsedTime=1.382454 ========= master_lag: 0.03 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-01-15 12:46:02.727149) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:02.727638) ========= script_repo_revision: 50bd08fad34963be3ab52cb25a1c3e08e056f2cc ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:02.728172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:02.728500) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-01-15 12:46:02.752050) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-15 12:46:02.752406) ========= /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.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:02 INFO - MultiFileLogger online at 20170115 12:46:02 in /builds/slave/test 12:46:02 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 12:46:02 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 12:46:02 INFO - {'append_to_log': False, 12:46:02 INFO - 'base_work_dir': '/builds/slave/test', 12:46:02 INFO - 'blob_upload_branch': 'Firefox', 12:46:02 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 12:46:02 INFO - 'branch': 'Firefox', 12:46:02 INFO - 'buildbot_json_path': 'buildprops.json', 12:46:02 INFO - 'config_files': ('talos/mac_config.py',), 12:46:02 INFO - 'default_actions': ('clobber', 12:46:02 INFO - 'read-buildbot-config', 12:46:02 INFO - 'download-and-extract', 12:46:02 INFO - 'populate-webroot', 12:46:02 INFO - 'create-virtualenv', 12:46:02 INFO - 'install', 12:46:02 INFO - 'run-tests'), 12:46:02 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 12:46:02 INFO - 'download_minidump_stackwalk': True, 12:46:02 INFO - 'download_symbols': 'ondemand', 12:46:02 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 12:46:02 INFO - 'tooltool.py': '/tools/tooltool.py', 12:46:02 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 12:46:02 INFO - '/tools/misc-python/virtualenv.py')}, 12:46:02 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 12:46:02 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 12:46:02 INFO - 'installer_path': 'installer.exe', 12:46:02 INFO - 'log_level': 'info', 12:46:02 INFO - 'log_name': 'talos', 12:46:02 INFO - 'log_to_console': True, 12:46:02 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 12:46:02 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 12:46:02 INFO - 'opt_config_files': (), 12:46:02 INFO - 'pip_index': False, 12:46:02 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:46:02 INFO - 'cmd': ('bash', 12:46:02 INFO - '-c', 12:46:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:46:02 INFO - 'enabled': True, 12:46:02 INFO - 'halt_on_failure': False, 12:46:02 INFO - 'name': 'check_screen_resolution'},), 12:46:02 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 12:46:02 INFO - 'cmd': ('bash', 12:46:02 INFO - '-c', 12:46:02 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 12:46:02 INFO - 'enabled': True, 12:46:02 INFO - 'halt_on_failure': False, 12:46:02 INFO - 'name': 'check_screen_resolution'},), 12:46:02 INFO - 'run_cmd_checks_enabled': True, 12:46:02 INFO - 'sps_profile': False, 12:46:02 INFO - 'sps_profile_interval': 0, 12:46:02 INFO - 'suite': 'dromaeojs-e10s', 12:46:02 INFO - 'system_bits': '32', 12:46:02 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 12:46:02 INFO - 'title': 't-yosemite-r7-0090', 12:46:02 INFO - 'tooltool_cache': '/builds/tooltool_cache', 12:46:02 INFO - 'use_talos_json': True, 12:46:02 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 12:46:02 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 12:46:02 INFO - 'work_dir': 'build'} 12:46:02 INFO - [mozharness: 2017-01-15 20:46:02.994669Z] Running clobber step. 12:46:02 INFO - Running pre-action listener: _resource_record_pre_action 12:46:02 INFO - Running main action method: clobber 12:46:02 INFO - rmtree: /builds/slave/test/build 12:46:02 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 12:46:04 INFO - Running post-action listener: _resource_record_post_action 12:46:04 INFO - [mozharness: 2017-01-15 20:46:04.526372Z] Finished clobber step (success) 12:46:04 INFO - [mozharness: 2017-01-15 20:46:04.526502Z] Running read-buildbot-config step. 12:46:04 INFO - Running pre-action listener: _resource_record_pre_action 12:46:04 INFO - Running main action method: read_buildbot_config 12:46:04 INFO - Using buildbot properties: 12:46:04 INFO - { 12:46:04 INFO - "project": "", 12:46:04 INFO - "product": "firefox", 12:46:04 INFO - "script_repo_revision": "production", 12:46:04 INFO - "builddir": "mozilla-central_yosemite_r7_test-dromaeojs-e10s", 12:46:04 INFO - "repository": "", 12:46:04 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-central talos dromaeojs-e10s", 12:46:04 INFO - "buildid": "20170115115223", 12:46:04 INFO - "pgo_build": "False", 12:46:04 INFO - "basedir": "/builds/slave/test", 12:46:04 INFO - "buildnumber": 38, 12:46:04 INFO - "slavename": "t-yosemite-r7-0090", 12:46:04 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 12:46:04 INFO - "platform": "yosemite_r7", 12:46:04 INFO - "scheduler": "tests-mozilla-central-macosx64-talos", 12:46:04 INFO - "branch": "mozilla-central", 12:46:04 INFO - "repo_path": "mozilla-central", 12:46:04 INFO - "revision": "50bd08fad34963be3ab52cb25a1c3e08e056f2cc", 12:46:04 INFO - "stage_platform": "macosx64", 12:46:04 INFO - "builduid": "0259f9f96586428db9c6a3080b4daf4f", 12:46:04 INFO - "slavebuilddir": "test" 12:46:04 INFO - } 12:46:04 INFO - Found installer url https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg. 12:46:04 INFO - Running post-action listener: _resource_record_post_action 12:46:04 INFO - [mozharness: 2017-01-15 20:46:04.529842Z] Finished read-buildbot-config step (success) 12:46:04 INFO - [mozharness: 2017-01-15 20:46:04.529949Z] Running download-and-extract step. 12:46:04 INFO - Running pre-action listener: _resource_record_pre_action 12:46:04 INFO - Running main action method: download_and_extract 12:46:04 INFO - mkdir: /builds/slave/test/build/tests 12:46:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:04 INFO - trying https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.test_packages.json 12:46:04 INFO - Downloading https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/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 12:46:04 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/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 12:46:05 INFO - Downloaded 1199 bytes. 12:46:05 INFO - Reading from file /builds/slave/test/build/firefox-53.0a1.en-US.mac.test_packages.json 12:46:05 INFO - Using the following test package requirements: 12:46:05 INFO - {u'common': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:46:05 INFO - u'cppunittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.cppunittest.tests.zip'], 12:46:05 INFO - u'gtest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.gtest.tests.zip'], 12:46:05 INFO - u'jittest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'jsshell-mac.zip'], 12:46:05 INFO - u'mochitest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.mochitest.tests.zip'], 12:46:05 INFO - u'mozbase': [u'firefox-53.0a1.en-US.mac.common.tests.zip'], 12:46:05 INFO - u'reftest': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.reftest.tests.zip'], 12:46:05 INFO - u'talos': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.talos.tests.zip'], 12:46:05 INFO - u'web-platform': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.web-platform.tests.zip'], 12:46:05 INFO - u'xpcshell': [u'firefox-53.0a1.en-US.mac.common.tests.zip', 12:46:05 INFO - u'firefox-53.0a1.en-US.mac.xpcshell.tests.zip']} 12:46:05 INFO - Downloading packages: [u'firefox-53.0a1.en-US.mac.common.tests.zip'] for test suite category: common 12:46:05 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:46:05 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:46:05 INFO - Fetch https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:46:06 INFO - Expected file size: 16318969 12:46:06 INFO - Obtained file size: 16318969 12:46:12 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 12:46:12 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip 12:46:12 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip'}, attempt #1 12:46:12 INFO - Fetch https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.common.tests.zip into memory 12:46:13 INFO - Expected file size: 16318969 12:46:13 INFO - Obtained file size: 16318969 12:46:20 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip 12:46:20 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip'}, attempt #1 12:46:20 INFO - Fetch https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.talos.tests.zip into memory 12:46:21 INFO - Expected file size: 14403463 12:46:21 INFO - Obtained file size: 14403463 12:46:21 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')]} 12:46:21 INFO - trying https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:46:21 INFO - Downloading https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0a1.en-US.mac.dmg 12:46:21 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/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 12:46:24 INFO - Downloaded 70066799 bytes. 12:46:24 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:46:24 INFO - mkdir: /builds/slave/test/properties 12:46:24 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 12:46:24 INFO - Writing to file /builds/slave/test/properties/build_url 12:46:24 INFO - Contents: 12:46:24 INFO - build_url:https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg 12:46:24 INFO - Running post-action listener: _resource_record_post_action 12:46:24 INFO - Running post-action listener: set_extra_try_arguments 12:46:24 INFO - [mozharness: 2017-01-15 20:46:24.762694Z] Finished download-and-extract step (success) 12:46:24 INFO - [mozharness: 2017-01-15 20:46:24.762836Z] Running populate-webroot step. 12:46:24 INFO - Running pre-action listener: _resource_record_pre_action 12:46:24 INFO - Running main action method: populate_webroot 12:46:24 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 12:46:24 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 12:46:24 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 12:46:24 INFO - '--tppagecycles', 12:46:24 INFO - '7'], 12:46:24 INFO - 'tests': ['tsvgm']}}, 12:46:24 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['tresize', 'tcanvasmark']}, 12:46:24 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 12:46:24 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['dromaeo_css', 'kraken']}, 12:46:24 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 12:46:24 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:24 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 12:46:24 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['damp', 'tps']}, 12:46:24 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'tests': ['damp', 'tps']}, 12:46:24 INFO - 'g3': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['dromaeo_dom']}, 12:46:24 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 12:46:24 INFO - 'g4': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['basic_compositor_video']}, 12:46:24 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 12:46:24 INFO - 'other': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'other-e10s': {'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'other-e10s_l64': {'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['a11yr', 12:46:24 INFO - 'ts_paint', 12:46:24 INFO - 'tpaint', 12:46:24 INFO - 'sessionrestore', 12:46:24 INFO - 'sessionrestore_no_auto_restore', 12:46:24 INFO - 'tabpaint']}, 12:46:24 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['tsvgx', 12:46:24 INFO - 'tsvgr_opacity', 12:46:24 INFO - 'tart', 12:46:24 INFO - 'tscrollx', 12:46:24 INFO - 'cart', 12:46:24 INFO - 'tsvg_static']}, 12:46:24 INFO - 'svgr-e10s': {'tests': ['tsvgx', 12:46:24 INFO - 'tsvgr_opacity', 12:46:24 INFO - 'tart', 12:46:24 INFO - 'tscrollx', 12:46:24 INFO - 'cart', 12:46:24 INFO - 'tsvg_static']}, 12:46:24 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'talos_options': ['--disable-e10s'], 12:46:24 INFO - 'tests': ['tp5o']}, 12:46:24 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 12:46:24 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'talos_options': ['--disable-e10s', 12:46:24 INFO - '--xperf_path', 12:46:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:24 INFO - 'tests': ['tp5n']}, 12:46:24 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 12:46:24 INFO - 'talos_options': ['--xperf_path', 12:46:24 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 12:46:24 INFO - 'tests': ['tp5n']}}, 12:46:24 INFO - 'talos.zip': {'path': '', 12:46:24 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 12:46:24 INFO - Running post-action listener: _resource_record_post_action 12:46:24 INFO - [mozharness: 2017-01-15 20:46:24.781644Z] Finished populate-webroot step (success) 12:46:24 INFO - [mozharness: 2017-01-15 20:46:24.781781Z] Running create-virtualenv step. 12:46:24 INFO - Running pre-action listener: _resource_record_pre_action 12:46:24 INFO - Running main action method: create_virtualenv 12:46:24 INFO - Creating virtualenv /builds/slave/test/build/venv 12:46:24 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 12:46:24 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 12:46:24 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 12:46:24 INFO - Using real prefix '/tools/python27' 12:46:24 INFO - New python executable in /builds/slave/test/build/venv/bin/python 12:46:25 INFO - Installing distribute.............................................................................................................................................................................................done. 12:46:28 INFO - Installing pip.................done. 12:46:28 INFO - Return code: 0 12:46:28 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 12:46:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 12:46:28 INFO - Reading from file tmpfile_stdout 12:46:28 INFO - Output received: 12:46:28 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) 12:46:28 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 12:46:28 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')]} 12:46:28 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:28 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:28 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')]} 12:46:28 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:28 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:28 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:28 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 12:46:28 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 12:46:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:28 INFO - 'HOME': '/Users/cltbld', 12:46:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:28 INFO - 'LOGNAME': 'cltbld', 12:46:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:28 INFO - 'MOZ_NO_REMOTE': '1', 12:46:28 INFO - 'NO_EM_RESTART': '1', 12:46:28 INFO - 'PAGER': '/bin/cat', 12:46:28 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:28 INFO - 'PWD': '/builds/slave/test', 12:46:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:28 INFO - 'SHELL': '/bin/bash', 12:46:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:28 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:28 INFO - 'USER': 'cltbld', 12:46:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:28 INFO - 'XPC_FLAGS': '0x0', 12:46:28 INFO - 'XPC_SERVICE_NAME': '0', 12:46:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:29 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 12:46:29 INFO - Cleaning up... 12:46:29 INFO - Return code: 0 12:46:29 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 12:46:29 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')]} 12:46:29 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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')]} 12:46:29 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:29 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:29 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:29 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 12:46:29 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 12:46:29 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:29 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:29 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:29 INFO - 'HOME': '/Users/cltbld', 12:46:29 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:29 INFO - 'LOGNAME': 'cltbld', 12:46:29 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:29 INFO - 'MOZ_NO_REMOTE': '1', 12:46:29 INFO - 'NO_EM_RESTART': '1', 12:46:29 INFO - 'PAGER': '/bin/cat', 12:46:29 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:29 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:29 INFO - 'PWD': '/builds/slave/test', 12:46:29 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:29 INFO - 'SHELL': '/bin/bash', 12:46:29 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:29 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:29 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:29 INFO - 'USER': 'cltbld', 12:46:29 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:29 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:29 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:29 INFO - 'XPC_FLAGS': '0x0', 12:46:29 INFO - 'XPC_SERVICE_NAME': '0', 12:46:29 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:29 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:29 INFO - Downloading/unpacking psutil>=3.1.1 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46:33 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 12:46:33 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:33 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:33 INFO - Installing collected packages: psutil 12:46:33 INFO - Running setup.py install for psutil 12:46:33 INFO - building 'psutil._psutil_osx' extension 12:46:33 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 12:46:33 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 12:46:33 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 12:46:33 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 12:46:33 INFO - building 'psutil._psutil_posix' extension 12:46:33 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 12:46:33 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 12:46:33 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 12:46:33 INFO - ^ 12:46:33 INFO - 1 warning generated. 12:46:33 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 12:46:34 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 12:46:34 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 12:46:34 INFO - Successfully installed psutil 12:46:34 INFO - Cleaning up... 12:46:34 INFO - Return code: 0 12:46:34 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 12:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:34 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:34 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:34 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:34 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:34 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:34 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:34 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 12:46:34 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 12:46:34 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:34 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:34 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:34 INFO - 'HOME': '/Users/cltbld', 12:46:34 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:34 INFO - 'LOGNAME': 'cltbld', 12:46:34 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:34 INFO - 'MOZ_NO_REMOTE': '1', 12:46:34 INFO - 'NO_EM_RESTART': '1', 12:46:34 INFO - 'PAGER': '/bin/cat', 12:46:34 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:34 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:34 INFO - 'PWD': '/builds/slave/test', 12:46:34 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:34 INFO - 'SHELL': '/bin/bash', 12:46:34 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:34 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:34 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:34 INFO - 'USER': 'cltbld', 12:46:34 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:34 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:34 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:34 INFO - 'XPC_FLAGS': '0x0', 12:46:34 INFO - 'XPC_SERVICE_NAME': '0', 12:46:34 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:34 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:34 INFO - Downloading/unpacking mozsystemmonitor==0.3 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:34 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 12:46:37 INFO - Downloading mozsystemmonitor-0.3.tar.gz 12:46:37 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 12:46:37 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 12:46:37 INFO - Installing collected packages: mozsystemmonitor 12:46:37 INFO - Running setup.py install for mozsystemmonitor 12:46:37 INFO - Successfully installed mozsystemmonitor 12:46:37 INFO - Cleaning up... 12:46:37 INFO - Return code: 0 12:46:37 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 12:46:37 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')]} 12:46:37 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:37 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:37 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')]} 12:46:37 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:37 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:37 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:37 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 12:46:37 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 12:46:37 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:37 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:37 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:37 INFO - 'HOME': '/Users/cltbld', 12:46:37 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:37 INFO - 'LOGNAME': 'cltbld', 12:46:37 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:37 INFO - 'MOZ_NO_REMOTE': '1', 12:46:37 INFO - 'NO_EM_RESTART': '1', 12:46:37 INFO - 'PAGER': '/bin/cat', 12:46:37 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:37 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:37 INFO - 'PWD': '/builds/slave/test', 12:46:37 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:37 INFO - 'SHELL': '/bin/bash', 12:46:37 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:37 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:37 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:37 INFO - 'USER': 'cltbld', 12:46:37 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:37 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:37 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:37 INFO - 'XPC_FLAGS': '0x0', 12:46:37 INFO - 'XPC_SERVICE_NAME': '0', 12:46:37 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:37 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:37 INFO - Downloading/unpacking jsonschema==2.5.1 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:37 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 12:46:40 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 12:46:40 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 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 12:46:40 INFO - Downloading functools32-3.2.3-2.tar.gz 12:46:40 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 12:46:40 INFO - warning: no files found matching '*.txt' 12:46:40 INFO - no previously-included directories found matching 'build' 12:46:40 INFO - no previously-included directories found matching 'dist' 12:46:40 INFO - no previously-included directories found matching '.git*' 12:46:40 INFO - Installing collected packages: jsonschema, functools32 12:46:40 INFO - Running setup.py install for functools32 12:46:40 INFO - warning: no files found matching '*.txt' 12:46:40 INFO - no previously-included directories found matching 'build' 12:46:40 INFO - no previously-included directories found matching 'dist' 12:46:40 INFO - no previously-included directories found matching '.git*' 12:46:40 INFO - Successfully installed jsonschema functools32 12:46:40 INFO - Cleaning up... 12:46:40 INFO - Return code: 0 12:46:40 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 12:46:40 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')]} 12:46:40 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:40 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:40 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')]} 12:46:40 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:40 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:40 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:40 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 12:46:40 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 12:46:40 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:40 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:40 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:40 INFO - 'HOME': '/Users/cltbld', 12:46:40 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:40 INFO - 'LOGNAME': 'cltbld', 12:46:40 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:40 INFO - 'MOZ_NO_REMOTE': '1', 12:46:40 INFO - 'NO_EM_RESTART': '1', 12:46:40 INFO - 'PAGER': '/bin/cat', 12:46:40 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:40 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:40 INFO - 'PWD': '/builds/slave/test', 12:46:40 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:40 INFO - 'SHELL': '/bin/bash', 12:46:40 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:40 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:40 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:40 INFO - 'USER': 'cltbld', 12:46:40 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:40 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:40 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:40 INFO - 'XPC_FLAGS': '0x0', 12:46:40 INFO - 'XPC_SERVICE_NAME': '0', 12:46:40 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:41 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 12:46:41 INFO - Cleaning up... 12:46:41 INFO - Return code: 0 12:46:41 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 12:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:41 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:41 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:41 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:41 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:41 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:41 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:41 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 12:46:41 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 12:46:41 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:41 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:41 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:41 INFO - 'HOME': '/Users/cltbld', 12:46:41 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:41 INFO - 'LOGNAME': 'cltbld', 12:46:41 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:41 INFO - 'MOZ_NO_REMOTE': '1', 12:46:41 INFO - 'NO_EM_RESTART': '1', 12:46:41 INFO - 'PAGER': '/bin/cat', 12:46:41 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:41 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:41 INFO - 'PWD': '/builds/slave/test', 12:46:41 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:41 INFO - 'SHELL': '/bin/bash', 12:46:41 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:41 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:41 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:41 INFO - 'USER': 'cltbld', 12:46:41 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:41 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:41 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:41 INFO - 'XPC_FLAGS': '0x0', 12:46:41 INFO - 'XPC_SERVICE_NAME': '0', 12:46:41 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:41 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:41 INFO - Downloading/unpacking blobuploader==1.2.4 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46: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 12:46:44 INFO - Downloading blobuploader-1.2.4.tar.gz 12:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 12:46:44 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 12:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:44 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 12:46:44 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 12:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:44 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:44 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:45 INFO - Downloading docopt-0.6.1.tar.gz 12:46:45 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 12:46:45 INFO - Installing collected packages: blobuploader, requests, docopt 12:46:45 INFO - Running setup.py install for blobuploader 12:46:45 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 12:46:45 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 12:46:45 INFO - Running setup.py install for requests 12:46:46 INFO - Running setup.py install for docopt 12:46:46 INFO - Successfully installed blobuploader requests docopt 12:46:46 INFO - Cleaning up... 12:46:46 INFO - Return code: 0 12:46:46 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46: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')]} 12:46:46 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:46 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46: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')]} 12:46:46 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:46 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:46 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:46 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 12:46:46 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 12:46:46 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:46 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:46 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:46 INFO - 'HOME': '/Users/cltbld', 12:46:46 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:46 INFO - 'LOGNAME': 'cltbld', 12:46:46 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:46 INFO - 'MOZ_NO_REMOTE': '1', 12:46:46 INFO - 'NO_EM_RESTART': '1', 12:46:46 INFO - 'PAGER': '/bin/cat', 12:46:46 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:46 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:46 INFO - 'PWD': '/builds/slave/test', 12:46:46 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:46 INFO - 'SHELL': '/bin/bash', 12:46:46 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:46 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:46 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:46 INFO - 'USER': 'cltbld', 12:46:46 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:46 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:46 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:46 INFO - 'XPC_FLAGS': '0x0', 12:46:46 INFO - 'XPC_SERVICE_NAME': '0', 12:46:46 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:46 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rrnRiQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-31aylr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MRVQhp-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:46 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:46 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-a__WxK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-x0yodQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-M41PnB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-eTEVxC-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-eeuqlq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-K5LALA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-QScq6z-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-0Q9h46-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-_N0M3Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-titTal-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:47 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:47 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-OBAQ9N-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-rGFqBA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-MQ1ETs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:48 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:48 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-mt4jes-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:48 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 12:46:48 INFO - Running setup.py install for manifestparser 12:46:48 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Running setup.py install for mozcrash 12:46:48 INFO - Running setup.py install for mozdebug 12:46:48 INFO - Running setup.py install for mozdevice 12:46:48 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Installing dm script to /builds/slave/test/build/venv/bin 12:46:48 INFO - Running setup.py install for mozfile 12:46:49 INFO - Running setup.py install for mozhttpd 12:46:49 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozinfo 12:46:49 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozInstall 12:46:49 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozleak 12:46:49 INFO - Running setup.py install for mozlog 12:46:49 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for moznetwork 12:46:49 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 12:46:49 INFO - Running setup.py install for mozprocess 12:46:50 INFO - Running setup.py install for mozprofile 12:46:50 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Running setup.py install for mozrunner 12:46:50 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Running setup.py install for mozscreenshot 12:46:50 INFO - Running setup.py install for moztest 12:46:50 INFO - Running setup.py install for mozversion 12:46:50 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 12:46:50 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 12:46:50 INFO - Cleaning up... 12:46:50 INFO - Return code: 0 12:46:50 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:50 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:50 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 12:46:50 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 12:46:50 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:50 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:50 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:50 INFO - 'HOME': '/Users/cltbld', 12:46:50 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:50 INFO - 'LOGNAME': 'cltbld', 12:46:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:50 INFO - 'MOZ_NO_REMOTE': '1', 12:46:50 INFO - 'NO_EM_RESTART': '1', 12:46:50 INFO - 'PAGER': '/bin/cat', 12:46:50 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:50 INFO - 'PWD': '/builds/slave/test', 12:46:50 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:50 INFO - 'SHELL': '/bin/bash', 12:46:50 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:50 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:50 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:50 INFO - 'USER': 'cltbld', 12:46:50 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:50 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:50 INFO - 'XPC_FLAGS': '0x0', 12:46:50 INFO - 'XPC_SERVICE_NAME': '0', 12:46:50 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:51 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-iXFPWP-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-wjfTFf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-KU3rTA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-T11IUq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-hqiIlj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-bAC69F-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-SsbXKU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 12:46:51 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)) 12:46:51 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 12:46:51 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-ZRmiGx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-VXmEjY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-gqf34i-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-eRNHiO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-j6FGW0-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 12:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.24 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-Tp8UbA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-4Qh2uV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-kLqhLh-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 12:46:52 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)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-GtcaNt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 12:46:52 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 from file:///builds/slave/test/build/tests/mozbase/moztest in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 16)) 12:46:52 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 12:46:52 INFO - Running setup.py (path:/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/pip-AoPYVf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 12:46:52 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)) 12:46:52 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)) 12:46:52 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)) 12:46:52 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)) 12:46:52 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)) 12:46:52 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)) 12:46:52 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 12:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:52 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:52 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 12:46:52 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 12:46:52 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 12:46:55 INFO - Downloading blessings-1.6.tar.gz 12:46:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 12:46:55 INFO - Installing collected packages: blessings 12:46:55 INFO - Running setup.py install for blessings 12:46:55 INFO - Successfully installed blessings 12:46:55 INFO - Cleaning up... 12:46:55 INFO - Return code: 0 12:46:55 INFO - Done creating virtualenv /builds/slave/test/build/venv. 12:46:55 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:56 INFO - Reading from file tmpfile_stdout 12:46:56 INFO - Current package versions: 12:46:56 INFO - blessings == 1.6 12:46:56 INFO - blobuploader == 1.2.4 12:46:56 INFO - docopt == 0.6.1 12:46:56 INFO - functools32 == 3.2.3-2 12:46:56 INFO - jsonschema == 2.5.1 12:46:56 INFO - manifestparser == 1.1 12:46:56 INFO - mozInstall == 1.12 12:46:56 INFO - mozcrash == 1.0 12:46:56 INFO - mozdebug == 0.1 12:46:56 INFO - mozdevice == 0.49 12:46:56 INFO - mozfile == 1.2 12:46:56 INFO - mozhttpd == 0.7 12:46:56 INFO - mozinfo == 0.9 12:46:56 INFO - mozleak == 0.1 12:46:56 INFO - mozlog == 3.4 12:46:56 INFO - moznetwork == 0.27 12:46:56 INFO - mozprocess == 0.24 12:46:56 INFO - mozprofile == 0.28 12:46:56 INFO - mozrunner == 6.13 12:46:56 INFO - mozscreenshot == 0.1 12:46:56 INFO - mozsystemmonitor == 0.3 12:46:56 INFO - moztest == 0.8 12:46:56 INFO - mozversion == 1.4 12:46:56 INFO - psutil == 3.1.1 12:46:56 INFO - requests == 1.2.3 12:46:56 INFO - wsgiref == 0.1.2 12:46:56 INFO - Installing None into virtualenv /builds/slave/test/build/venv 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 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 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:56 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 12:46:56 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 12:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:56 INFO - 'HOME': '/Users/cltbld', 12:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:56 INFO - 'LOGNAME': 'cltbld', 12:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:56 INFO - 'MOZ_NO_REMOTE': '1', 12:46:56 INFO - 'NO_EM_RESTART': '1', 12:46:56 INFO - 'PAGER': '/bin/cat', 12:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:56 INFO - 'PWD': '/builds/slave/test', 12:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:56 INFO - 'SHELL': '/bin/bash', 12:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:56 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:56 INFO - 'USER': 'cltbld', 12:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:56 INFO - 'XPC_FLAGS': '0x0', 12:46:56 INFO - 'XPC_SERVICE_NAME': '0', 12:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 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)) 12:46:56 INFO - Cleaning up... 12:46:56 INFO - Return code: 0 12:46:56 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 12:46:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 12:46:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 12:46:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1069c8b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1069cb768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fe1e1cdde20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, '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.EICaXUUvJH/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.hokW0lq4Rr/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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 12:46:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema'] in /builds/slave/test/build 12:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema 12:46:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:46:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:46:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:46:56 INFO - 'HOME': '/Users/cltbld', 12:46:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:46:56 INFO - 'LOGNAME': 'cltbld', 12:46:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:46:56 INFO - 'MOZ_NO_REMOTE': '1', 12:46:56 INFO - 'NO_EM_RESTART': '1', 12:46:56 INFO - 'PAGER': '/bin/cat', 12:46:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:46:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:46:56 INFO - 'PWD': '/builds/slave/test', 12:46:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:46:56 INFO - 'SHELL': '/bin/bash', 12:46:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:46:56 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:46:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:46:56 INFO - 'USER': 'cltbld', 12:46:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:46:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:46:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:46:56 INFO - 'XPC_FLAGS': '0x0', 12:46:56 INFO - 'XPC_SERVICE_NAME': '0', 12:46:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:46:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 12:46:56 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 12:46:56 INFO - Cleaning up... 12:46:56 INFO - Return code: 0 12:46:56 INFO - Running post-action listener: _resource_record_post_action 12:46:56 INFO - Running post-action listener: _start_resource_monitoring 12:46:56 INFO - Starting resource monitoring. 12:46:56 INFO - [mozharness: 2017-01-15 20:46:56.783515Z] Finished create-virtualenv step (success) 12:46:56 INFO - [mozharness: 2017-01-15 20:46:56.784471Z] Running install step. 12:46:56 INFO - Running pre-action listener: _resource_record_pre_action 12:46:56 INFO - Running main action method: install 12:46:56 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 12:46:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 12:46:57 INFO - Reading from file tmpfile_stdout 12:46:57 INFO - Detecting whether we're running mozinstall >=1.0... 12:46:57 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 12:46:57 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 12:46:57 INFO - Reading from file tmpfile_stdout 12:46:57 INFO - Output received: 12:46:57 INFO - Usage: mozinstall [options] installer 12:46:57 INFO - Options: 12:46:57 INFO - -h, --help show this help message and exit 12:46:57 INFO - -d DEST, --destination=DEST 12:46:57 INFO - Directory to install application into. [default: 12:46:57 INFO - "/builds/slave/test"] 12:46:57 INFO - --app=APP Application being installed. [default: firefox] 12:46:57 INFO - mkdir: /builds/slave/test/build/application 12:46:57 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'] 12:46:57 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 12:47:19 INFO - Reading from file tmpfile_stdout 12:47:19 INFO - Output received: 12:47:19 INFO - /builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox 12:47:19 INFO - Running post-action listener: _resource_record_post_action 12:47:19 INFO - [mozharness: 2017-01-15 20:47:19.303051Z] Finished install step (success) 12:47:19 INFO - [mozharness: 2017-01-15 20:47:19.303192Z] Running run-tests step. 12:47:19 INFO - Running pre-action listener: _resource_record_pre_action 12:47:19 INFO - Running main action method: run_tests 12:47:19 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 12:47:19 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 12:47:19 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 12:47:19 INFO - 2017-01-15 12:47:19.317 screenresolution[1911:20317] starting screenresolution argv=screenresolution get 12:47:19 INFO - 2017-01-15 12:47:19.320 screenresolution[1911:20317] Display 0: 1600x1200x32@60 12:47:19 INFO - 2017-01-15 12:47:19.326 screenresolution[1912:20319] starting screenresolution argv=screenresolution list 12:47:19 INFO - Available Modes on Display 0 12:47:19 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 12:47:19 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 12:47:19 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 12:47:19 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 12:47:19 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 12:47:19 INFO - Intel Iris: 12:47:19 INFO - Chipset Model: Intel Iris 12:47:19 INFO - Type: GPU 12:47:19 INFO - Bus: Built-In 12:47:19 INFO - VRAM (Dynamic, Max): 1536 MB 12:47:19 INFO - Vendor: Intel (0x8086) 12:47:19 INFO - Device ID: 0x0a2e 12:47:19 INFO - Revision ID: 0x0009 12:47:19 INFO - Displays: 12:47:19 INFO - Display: 12:47:19 INFO - Resolution: 1600 x 1200 @ 60 Hz 12:47:19 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 12:47:19 INFO - Display Serial Number: 1600x1200 60 12:47:19 INFO - Main Display: Yes 12:47:19 INFO - Mirror: Off 12:47:19 INFO - Online: Yes 12:47:19 INFO - Rotation: Supported 12:47:19 INFO - Return code: 0 12:47:19 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 12:47:19 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 12:47:19 INFO - Python 2.7.3 12:47:19 INFO - Return code: 0 12:47:19 INFO - grabbing minidump binary from tooltool 12:47:19 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')]} 12:47:19 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 0x1067a6d30>, '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 0x1066cddb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10679bbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 12:47:19 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 12:47:19 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 12:47:19 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 12:47:19 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 12:47:19 INFO - Return code: 0 12:47:19 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 12:47:19 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 12:47:19 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 12:47:19 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 12:47:19 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 12:47:19 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/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 12:47:19 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/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0090 --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 12:47:19 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.EICaXUUvJH/Render', 12:47:19 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 12:47:19 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 12:47:19 INFO - 'HOME': '/Users/cltbld', 12:47:19 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 12:47:19 INFO - 'LOGNAME': 'cltbld', 12:47:19 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 12:47:19 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 12:47:19 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 12:47:19 INFO - 'MOZ_NO_REMOTE': '1', 12:47:19 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 12:47:19 INFO - 'NO_EM_RESTART': '1', 12:47:19 INFO - 'PAGER': '/bin/cat', 12:47:19 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 12:47:19 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 12:47:19 INFO - 'PWD': '/builds/slave/test', 12:47:19 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 12:47:19 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 12:47:19 INFO - 'SHELL': '/bin/bash', 12:47:19 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.hokW0lq4Rr/Listeners', 12:47:19 INFO - 'TMPDIR': '/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/', 12:47:19 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 12:47:19 INFO - 'USER': 'cltbld', 12:47:19 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 12:47:19 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 12:47:19 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 12:47:19 INFO - 'XPC_FLAGS': '0x0', 12:47:19 INFO - 'XPC_SERVICE_NAME': '0', 12:47:19 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 12:47:19 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/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0090', '--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 12:47:19 INFO - mozversion application_buildid: 20170115115223 12:47:19 INFO - mozversion application_changeset: 50bd08fad34963be3ab52cb25a1c3e08e056f2cc 12:47:19 INFO - mozversion application_display_name: Nightly 12:47:19 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 12:47:19 INFO - mozversion application_name: Firefox 12:47:19 INFO - mozversion application_remotingname: firefox 12:47:19 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 12:47:19 INFO - mozversion application_vendor: Mozilla 12:47:19 INFO - mozversion application_version: 53.0a1 12:47:19 INFO - mozversion platform_buildid: 20170115115223 12:47:19 INFO - mozversion platform_changeset: 50bd08fad34963be3ab52cb25a1c3e08e056f2cc 12:47:19 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 12:47:19 INFO - mozversion platform_version: 53.0a1 12:47:19 INFO - using testdate: 1484513239 12:47:19 INFO - actual date: 1484513239 12:47:19 INFO - starting webserver on 'localhost:50869' 12:47:19 INFO - SUITE-START | Running 2 tests 12:47:19 INFO - TEST-START | dromaeo_css 12:47:19 INFO - operating with platform_type : mac_ 12:47:19 INFO - Initialising browser for dromaeo_css test... 12:47:19 INFO - TEST-INFO | started process 1922 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp5pccjP/profile http://localhost:50869/getInfo.html) 12:47:22 INFO - PROCESS | 1922 | 2017-01-15 12:47:22.709 plugin-container[1924:20473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4a47, name = 'com.apple.tsm.portname' 12:47:22 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:47:22 INFO - PROCESS | 1922 | 2017-01-15 12:47:22.712 plugin-container[1924:20473] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 12:47:22 INFO - PROCESS | 1922 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:47:22 INFO - PROCESS | 1922 | 2017-01-15 12:47:22.712 plugin-container[1924:20473] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:47:22 INFO - PROCESS | 1922 | __metrics Screen width/height:1600/1200 12:47:22 INFO - PROCESS | 1922 | colorDepth:24 12:47:22 INFO - PROCESS | 1922 | Browser inner width/height: 1024/689 12:47:22 INFO - PROCESS | 1922 | __metrics 12:47:22 ERROR - PROCESS | 1922 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3249: TypeError: this.tabs is undefined 12:47:23 INFO - TEST-INFO | 1922: exit 0 12:47:23 INFO - Browser initialized. 12:47:23 INFO - Running cycle 1/1 for dromaeo_css test... 12:47:23 INFO - TEST-INFO | started process 1925 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmp5pccjP/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 12:47:25 INFO - PROCESS | 1925 | 2017-01-15 12:47:25.820 plugin-container[1926:20598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x873f, name = 'com.apple.tsm.portname' 12:47:25 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:47:25 INFO - PROCESS | 1925 | 2017-01-15 12:47:25.820 plugin-container[1926:20598] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8c0b, name = 'com.apple.CFPasteboardClient' 12:47:25 INFO - PROCESS | 1925 | See /usr/include/servers/bootstrap_defs.h for the error codes. 12:47:25 INFO - PROCESS | 1925 | 2017-01-15 12:47:25.821 plugin-container[1926:20598] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 12:47:35 INFO - PROCESS | 1925 | RSS: Main: 233553920 12:47:35 INFO - PROCESS | 1925 | 12:50:37 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-dojo.html (next: http://localhost:50869/tests/dromaeo/cssquery-ext.html) 12:50:39 INFO - PROCESS | 1925 | RSS: Main: 256430080 12:50:39 INFO - PROCESS | 1925 | 12:52:23 INFO - PROCESS | 1925 | ************************* 12:52:23 INFO - PROCESS | 1925 | A coding exception was thrown and uncaught in a Task. 12:52:23 INFO - PROCESS | 1925 | 12:52:23 ERROR - PROCESS | 1925 | Full message: TypeError: NetworkError when attempting to fetch resource. 12:52:23 INFO - PROCESS | 1925 | Full stack: 12:52:23 INFO - PROCESS | 1925 | ************************* 12:53:41 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-ext.html (next: http://localhost:50869/tests/dromaeo/cssquery-jquery.html) 12:53:42 INFO - PROCESS | 1925 | RSS: Main: 264757248 12:53:42 INFO - PROCESS | 1925 | 12:56:44 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-jquery.html (next: http://localhost:50869/tests/dromaeo/cssquery-mootools.html) 12:56:45 INFO - PROCESS | 1925 | RSS: Main: 273559552 12:56:45 INFO - PROCESS | 1925 | 12:59:46 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-mootools.html (next: http://localhost:50869/tests/dromaeo/cssquery-prototype.html) 12:59:47 INFO - PROCESS | 1925 | RSS: Main: 269701120 12:59:47 INFO - PROCESS | 1925 | 13:02:50 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-prototype.html (next: http://localhost:50869/tests/dromaeo/cssquery-yui.html) 13:02:50 INFO - PROCESS | 1925 | RSS: Main: 274563072 13:02:50 INFO - PROCESS | 1925 | 13:05:53 INFO - PROCESS | 1925 | Cycle 1(1): loaded http://localhost:50869/tests/dromaeo/cssquery-yui.html (next: http://localhost:50869/tests/dromaeo/cssquery-dojo.html) 13:05:54 INFO - PROCESS | 1925 | RSS: Main: 276844544 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | __start_tp_report 13:05:54 INFO - PROCESS | 1925 | _x_x_mozilla_page_load 13:05:54 INFO - PROCESS | 1925 | _x_x_mozilla_page_load_details 13:05:54 INFO - PROCESS | 1925 | |i|pagename|runs| 13:05:54 INFO - PROCESS | 1925 | |0;dojo.html;2950.395168990357;2991.596134521839;3290.55577497038;3313.867445302188;3375.8818441354556;5444.809431669893;5468.343798744151;5487.6981766002855;5498.807541736035;5630.971845140779;5624.128260119678;5694.715264236782;5713.114467257582;5821.09772985188;5840.153177789218;5162.8709282268055;5179.2749015137915;5200.6099542534275;5245.26566280721;5328.387235467932;5222.686638801684;5302.522772950453;5309.62832601718;5312.840614781543;5336.279602253685;5100.846974590731;5260.710660913648;5275.920861187085;5301.3373328333955;5330.28041214435;5107.463716309771;5150.536451719326;5157.5616072633875;5170.482951704799;5209.947900520984;5210.296609957645;5234.371875374942;5264.578833693296;5267.631265811395;5273.1035723927025;5533.778648854041;5637.633553818989;5702.971485142589;5744.971275143597;5749.195112684228;6100.732564372368;6145.985912324461;6230.532710046765;6282.2147231596055;6299;5429.53848922842;6254.6247225166635;6269.216347956505;6270.278917924452;6302.590331628429;5926.170336152942;5943.55423343251;5956.106584012433;5969.074793407029;5989.251343582052;6168.9691551541955;6308.936910630835;6358.904616430757;6374.745010199633;6392.680365981775;5060.7722652480725;5404.83785486436;6106.82471942484;6141.815745527641;6210.223722034746;5158.509941555546;6129.756146212483;6250.656213908188;6297.1813664223355;6299.748010079637;6128.105930682418;6148.477379422709;6158.661273629993;6166;6178.196834411498;6142.907856382158;6169.81490555284;6173.506119510428;6188.721507532172;6247.125402443661;4431.711938723973;4956.058348913695;4975.303457515951;4976.929960058533;5012.573931215815;4471.619912307425;4472.39622650938;4501.662375321864;4509.436320459942;4535.977320113444;4877.487863774323;5864.765409383577;5864.9413505864395;5927.73325200367;5936.643801371845;6132.2331366082535;6181.938180618136;6203.7828675996125;6242.375762423721;6276.435120839146;5912.3496415394275;5914.172015917775;5918.230630018156;5925.170476133345;5933.851653708605;4524.615407690383;5488.121900495901;5496.1755736639825;5504.284443022381;5522.889542209134;5546.639468434539;6143.972489078594;6293.181886354744;6308.432241098323;6370.267419246753;6250.468710159687;6266.717997690206;6277.905831412624;6291.811245662638;6327.841803954938;6380.872382552416;6388.329225431448;6395.808125756234;6405.615663060231;6412.198475190601;6133.815985520441;6193.163922870535;6208.441240288395;6256.280527739368;6286.15136956505;6200;6202.472789812736;6251.249850018027;6301.306856245901;6306.842328941813;6110.388961103854;6190.350013248724;6190.659513726789;6199.28708198563;6203.16257305258;6008.909866351921;6008.969955150196;6029.095635654687;6128.540359472968;6144.969275153596;4935.210366341368;5847.473727364558;5948.762049518144;5966.3735307793795;6046.062860256668;5860.267466566679;6191.628502289877;6200.7519699211525;6215.285242197025;6220.191375121205;4513;5273.78904843802;6021.2473440819895;6021.42796434338;6084.726187321493 13:05:54 INFO - PROCESS | 1925 | |1;ext.html;441.04293682708527;469.1274229932325;481.47038257916273;495.7546014722713;515.6029857010101;27599.72400275999;42271.94320141994;42450;42516.78741606295;42549.7235082948;42940;43010.35484467736;43394.56605433945;43401.565984340144;43519;9281.350305478602;9477.004914483983;9528;9539.141477267045;10772.192085593611;10902.890971090306;11116.777664446707;11140;11158.776824463506;11172.162087843404;11024.559017639285;11094.00153986141;11109.222354435193;11129.165312601546;11159.665210043711;11088.279261847954;11123.944380278128;11201.607943722012;11204.21570490066;11204.831927521094;10892.836607450894;10912.181586381053;10940.781184376348;10959;10988.230823842334;37556;37680.43479347812;37798.24403511915;37960.81019594912;37980.81009594962;3969;3980.5820388859333;4001.1197536541913;4001.479807625019;4014.8996275093064;23642;28808.855955720297;29138.70861291381;29295.560566591517;29483.968061117757;3184.26761844777;3263.282077942849;3325.085601459618;3328.700416962485;3341.8496167672515;494963;495779;495941;498643;499038.9999999964;57973.130403043986;58256;58332.7083364589;58425.415745842845;58572.82854342889;58767.41232587705;59082;59209;59323.70338148282;59331;15475;15589.376424942875;15592.766108508382;15623.14072726011;15624.76562851558;10792.622258220925;10959.561617535368;11900.940495297467;12170.330631815157;12187;17983.190756415992;18014.91910485375;18109.91340519573;18125.637487250446;18136.63726725458;6301.684915754194;6429.839254018687;7374.668139933716;7550.433717471212;7656.310932016146;866.2247288676599;867.2828939860818;867.9739607811775;876.7983363826228;893.6425429828121;31165.68834311628;31174;31181.68818311788;31275;31618;998.3161534348994;1058.9258751887448;1082.6481393547128;1089.8910108989037;1094.1848322999258;13709.245991470565;15863.286152123183;16236.132844723008;16279.186040697918;16639;609697;619204;621313;623356;623750;55863.44136558664;57264.56838579069;60984;61403;62128.689356552924;1385.3904282115836;1415.1367665723901;1444.0830072903782;1446.088963952724;1448;23606.645900311167;27413.629318534393;28144.71855281421;29125.7087429123;30649.846750766996;54176.91646167133;54712.726436367564;54965.450345496036;54999.90000200053;55106.89786204173;523069;527185;527707.3614631902;527736;527839;24331.756682432948;27484.58773118365;27635.170944871687;27743.58384624192;27920.86039569789;48493;48924.77688057827;48979.77550561265;49566.256506151716;49880.75059624824;33323.666763332054;39876.80061599673;40389;40399.39400909047;41359.37960930528;38407.231855363294;39987.80006099951;40204.597954020086;40704;41190.58809411988;38348.424773628976;38541.80729096449;38867.80566097151;39326.60673393229;39331.2133757329;32529.837350813094;34681.1329716749;35087.82456087703;35209;35298.647013530564;25379.873100634377;25395.238142855746;25541.489170216864;25829.483410332065;26095.60856587115 13:05:54 INFO - PROCESS | 1925 | |2;jquery.html;391.0887631817865;392.42901578203714;399.9880003599892;400.99150636150085;420.8300923432858;19859.007049647513;20968.89515552424;21092;21125.57748845022;21130.57738845222;20603.969801509902;20970.056347464364;21072.683909741365;21073.894630526866;21123.577528449423;5687.175359572849;5856.707164641773;5869.413058694117;5921.052631578948;5923.496502797268;5652.236948012028;5762.53899688026;5795.71021448928;5875.9118613220835;5889.057750759879;5712.11462223356;5741.913871291934;5744.109663002241;5752.424757524256;5763.39484354143;5467.5625949923915;5696.832149409361;5731.426857314256;5757.596968212227;5782.421757824226;5095.363079615048;5100.954304367596;5101.362329708786;5101.923471147898;5102.974485127551;25712.74287257141;25838;25853;26223.73776262232;26367;2719.9184024479296;2746.6978632350424;2768.183385901144;2789.7489225969557;2804.887804487818;15303.540893773094;15341.693166136727;15510.534683959499;15590.532284031497;15685.843141568552;1689.2060731456195;1788.2685991397539;1818.9818101818944;1873.1196337721249;1882.4729075858781;92374.5381273096;124473;132320;134689;144311.27844360922;9774.853377199348;11588.710282243012;11797.88202117985;11946.641600751991;12083.697907552381;11380.772384552263;15505.922470387575;15523.844761552466;15581.14303713284;15756.68486630284;7535.092085614734;8443.366747493963;8523.616437260338;8765.693200738071;8899.955500222586;8476.830463390697;8505.277051450696;8536;8643.135686431306;8653.740387788377;4710.199266124767;4826.541478559531;4836.250381190921;4887.0714564232685;4899.4365647950945;1997.3308941504758;2068.917243310251;2069.430906500724;2073;2078.189506092625;760.5056713136505;861.4917198852708;878.1076779540101;896.1979028769215;904.9954750226207;20212.89893550523;21071.894640527007;21956.451088722908;21979.670304945437;22138.77861221367;889.6396959231522;906.5857262670255;910.1262787723726;927.6196759328643;933.7758937854866;9002.774930626787;11064.778704425866;11417;11436.94281528587;11452.484638191134;506356.93643063836;540967;722833;730603;754243;110537.34193987097;112882.43558782153;138491.30754346165;148034;148627;970.3498655900563;973.78576713123;1002.3334482569155;1002.9749256268651;1051.6371851710994;19808.504787380432;19975.900120499304;20038.59922801565;20183.091760870502;20248.89875550613;46391.53608464007;56115.71942140263;56372;56756.71621641865;59656.31546580327;87060;90547.73630659287;93442;93669.06330936603;93923.06076939144;7226.313500217471;7343.228960959236;7485.401167906445;7857.842843143219;8109.635066422144;11471.713207169554;12922.612321630366;12956.416961236579;13440;13459.46162153542;5583.106702927512;8610.182032706884;8981.955090224506;10615.734606634896;10996.450177490933;9628.807423851344;10322.638707645196;11077.778444431227;11220.719482012688;11270.718232044264;11121.94439027832;11135.88864111381;11612.76774464489;11758.647240582453;11891.583794567148;8202.507849529047;8346.749597512084;8628.439151455135;8675.34934879874;8817.338699597427;10722.73193170177;10808.18938579594;11095.889041109485;11118.833217501582;11482.540698372306 13:05:54 INFO - PROCESS | 1925 | |3;mootools.html;1292.9612111636652;1426.3581388375228;1430.4492770283439;1448.073233130796;1454.78178273259;7574.507657002291;7841.137474877759;8133.87799183012;8571.871421928668;8579.52812595307;7162.033125528053;8071.677132914691;8228.053773815998;8339.416240863127;8436.367272454576;2304.4584522637165;2309.445733024075;2355.093289083698;2359.8584084954873;2360.3391050505884;2072.7823578524262;2092.602405542942;2158.686990386393;2219.989904593406;2342.625179971205;1849.9537511562185;2263.875486848223;2338.754430784769;2340.847844890085;2349.835511514195;1935.3903520391032;1947.2989723699457;2324.7094113235844;2340.1107579119825;2346.9530609387803;2312.6531020346915;2321.6401457774073;2379.690640216778;2380.27401642499;2384.523095380931;8460.703875364332;8461.788455288606;8497.95751021247;8514.361422893246;8521.531315777629;5074.340335756365;5221.321228240343;5229.660072095339;5238.81664141757;5246.31797866275;3444.741644376682;3452.067941655764;3495.5106285120105;3530.735194860396;3566.696830769388;1196.5931583261734;1208.0878936403046;1220.4446976625613;1241.745442184359;1257.5158563952853;10803.243772935897;10911.181661375349;10942.617008404668;10946.945265273702;10980.121590272838;4386.670999675037;4681.321208424827;4729.6452766042685;4760.785764640599;4776.426828780569;9439.056094390644;9472.763180920532;9497.335186537015;9506;9519.952805191424;5213.817516386908;5250.343707036621;5256.9737151314;5284.735763211824;5306;4675.76621168947;4816.710997340171;4824.927626085611;4845.297431872429;4866.4403593586485;3864.2851072551666;3958.5645578986287;3972.5828787977425;4034.1528279061727;4064.5122585289955;1179.628417048627;1223.9449224784905;1301.7852054411105;1305.8563558008611;1320.3002408723391;758.5486635452007;762.5882023707247;792.3859009267749;819.6352623082671;830.6220669595319;5459.590530710213;5535.059039963216;5537.307836520435;5647.124695672177;5782.2483077199695;905.4341036851968;970.8009857979098;983.2036050798876;988.6687959533637;1038.081298051215;3734.9355433701267;3750.249950010009;3753.155540003477;3822.4266360046217;3870.0905287257474;8808.471491710518;9326.95336523313;9994.10053095225;10002.799944001079;10040.297179197532;9943.45311007887;9967.501624918725;9979.60081596728;9986.850197247048;9994.550245238981;6514.674266286762;7378.372838308696;7830.256125668053;7860.567668778158;7971.72098976533;4811.735354555393;5440.966216418868;5526.253955715927;5701.7149142541875;5739.684317362585;8862.42394244372;9084;9148.817023659623;9284.628614855601;9364.859527107235;9114.088591140833;9303.488308143116;9318.02160773109;9382.906170938477;9400.059994000545;7039.859202815813;7231.096112985877;7319.414446844133;7336.853262934605;7348.3386495213545;7809.297163255334;7858.724944626911;7871.567063811545;7880.133185349721;7964.561949092856;7075.929240707527;7193.3166349196745;7242.710291588278;7295.708171673074;7300;6921.82695432598;7015.403690686145;7024.367806897404;7166.964165179141;7167.856642867009;6281.874362512633;6415.358464153547;6448.774292899726;6458.838529036811;6464.709088091141;4905.730184839868;4916.950830491793;4979.5269449403695;5067.341245638191;5088.084144853962;4988.176950803077;5001.749912504433;5096.796128154981;5156.639035267495;5191.195364718475 13:05:54 INFO - PROCESS | 1925 | |4;prototype.html;353.9911502212444;357.35497427144;366.0318457679437;367.6764447286388;371.5565029858997;4074.755514669122;4383.947852515397;4468.75421851798;4760.167700218373;4881.36542249508;4234.513031001436;4467.3299005149165;5139.023585518748;5331.706756128421;5398.2712333834925;4216.831326746927;4244.427002354673;4246.683528106281;4253.766042867641;4258.74447533149;3640.4539319102214;3987.2456119307412;4056.391541268804;4246.855338054278;4267.957320426787;3469.6703813137838;4165.541790403053;4170.77060761658;4181.853635122771;4215.683823713219;3377.885297851703;3905.258000979805;4081.6530594899323;4192.197355137289;4206.85276015338;2994.8203107813383;3156.952645710316;3713.16453797896;3716.4982727331735;3731.0151878497363;4719.622430205575;4982.227754698028;5190.11767999441;5236.6857988520815;5287.550558202557;3827.674647654953;4322.82708691652;4631.854490231258;4641.303804429329;4657.858824587964;5683.772649094031;5702.77188912443;5706.400827913051;5786.913196302016;5831.512964194146;3310.6192787829227;3438.415469370213;3589.3847768504015;3807.6382743639588;3900.726949113563;5893.204417403638;5915.467607915309;5919.6152250103605;5950.642961422329;5960.970195148997;3893.2213557288655;3899.941500877489;3923.843046278174;3924.860950781831;3925.823337949799;3547.911302217465;3847.711421643388;3965.1871366369824;3973.2848087344555;3980.20395920817;3955.3869150281175;4248.4901811782165;4339.370791235253;4583.495815460296;4603.792829322689;5069.112905241568;5245.449227831023;5463.344398672105;5720.399358067426;5759.568032397587;1206.8189771534164;1207.177162484637;1221.2733423613113;1239.8841043061136;1306.9087312094318;710.8187412209853;712.1596516111039;867.3581549653337;870.616928551448;881.2641444393875;4222.923154595558;5073.670211436319;5080.923786143211;5092.414372347153;5101.2603172539775;4330.39374487572;5319.707416092152;5577.637453565505;5608.411116832755;5623.353314368818;4707.952920470751;4709.811607535728;5011.248312753116;5079.187330027178;5085.237214417867;2668.3862711576057;2812.2688101093863;2917.6207093077765;2922.883084676589;2924.2835505300914;5056.696598204119;5100.795968161232;5335.598980458809;5478.45215478457;5682.886342273131;4853.272009198648;5232.764525596357;5364.973175134178;5609.607327487037;5682.829515114553;4684.484706682262;4948.925766113439;5293.364796224478;5713.6001679587935;5736.512396446349;4570.81716731327;4820.228763397839;4920.72935988524;5480.260164877691;5655.406182350958;4567.63458923292;4810.807567697253;5024.346834911438;5107.591392688501;5231.346081739783;4860.562549370574;5195.5843532518065;5733.627314224562;5746.252987111816;5747.3103227612955;3050.5729197912756;3639.761546648845;3656.817159141979;3691.2432951244396;3708.529918370382;4380.5838445347645;5276.440926150891;5503.3395992481155;5523.8066667666435;5561.6663000220115;4504.887377815581;5190.584753219809;5322.616119808955;5383.434739352469;5455.509004189642;4422.402975598253;4830.956497406612;5156.767945442389;5364.624476286778;5514.786746915672;4484.376639665832;5237;5250.89498210026;5372.382176049804;5430.9185362218805;5686.857828554319;5689.2319536862;5691.321060287308;5717.799877004285;5718.456746609066;4811.903761924672;4983.725895075806;5114.207297868837;5496.839317392435;5612.663240205601 13:05:54 INFO - PROCESS | 1925 | |5;yui.html;708.6740099554208;711.2531841566354;713.3544142550993;717.2361435071648;732.9670164842581;14893.4787282445;15481.612959676;15675;15868.444604438848;15906.602334941632;13743.862561374382;13820.792688109661;13864.722705545882;14052;14650.487232946849;850.6767428377208;862.7498025572577;867.9652813887437;868.2359523619207;870.6212797433105;691.3708525242031;711.5019486359544;853.9188777066169;858.3519442820661;878.7232021913109;915.3912648089013;918.3387960668308;919.839028170071;919.9604446974909;921.1433366968716;912.1015799437548;914.5290175559592;915.7298596913927;916.8716379706847;918.1186061381082;666.6566718140161;675.7060678604822;678.2640834694363;680.680080362229;681.3765404654735;15189.924050379788;15408.922955385264;15412.537623871302;15420.614484637861;15749.05505669652;13801.930990344985;13921.095128816696;14260.147970405857;14274.64313392163;14314.928425357806;11684.298942063504;11741.354225517593;11943.462544185446;12111.576094836726;12156.635300940987;3632.854685812564;3665.486831843544;3680.576733675635;3702.241040586673;3804.714646401531;108687;109072.4546377271;110922;112435.87564124417;113261.43369283101;10711.303765255234;10744.462776861126;10755.892441075646;10793.730156746144;10926.344419334866;18462.907685461487;19724.013799310265;20019.694780883077;21238.044288007077;21922.45193870166;6482.837929051717;7736.839474078821;7777.611119444005;7941.166177551389;7958.880616790752;3818.980905095457;3941.885910131022;4170;4274;4344.304911214191;3515.964840351615;3676.816159192083;3682.318771027315;3865.7100717446306;4046.4132700758223;603.5051257968512;728.5992704012772;728.6211170191576;741.5773009384599;753.898223739799;540.5162379670173;555.1921953557564;568.7355379748437;582.962107463022;619.8698273362554;8511.872321915176;10352.010310050837;10565.683029509128;10673.466326683634;10837.512311945824;690.7582346178898;707.2962402409636;711.9837374188131;717.1645033535976;729.5383405739799;4805.471398146201;4914.90170196594;5151.6908985461;5156.948430515647;5165.302684137669;106246;106553;108573;109545.45227273813;111574.44212778885;75480.62259688776;89396;89603;90916;91637.54181229051;66587.6682466348;87188.128118718;88147.86299575503;88608;88871.11128888628;8111.404350607749;9898.42899517623;9902.280130292987;9990.750231244278;10042.347247428894;53491.465085348646;54967;54983.45016549783;56695.433045669015;57225.71387143037;59138;68869.3113068883;73485.6325718368;75253.24746752682;75705;11647.88352116468;11764.823527647264;11791.233569817934;11828.70428239301;12105.636830895088;13139.408726607517;13353.599392018255;13450.394732236877;13483.988700847673;13655.180689158682;12273.69315767113;12495.750084998432;12527;12655.367231638565;12769.49085123339;12299.323537205539;12457.626271211879;12818.102732808615;12819;12831.358432078545;12085.516579336729;12237;12255.754884902073;12525.37373131358;12530.122891397516;10043;10134.290599657954;10276.897231027893;10764.3541387517;11161.883811618773;8391.916080839113;9980.500974951368;10034.247431442525;10120.89879101229;10126.898731012596 13:05:54 INFO - PROCESS | 1925 | __end_tp_report 13:05:54 INFO - PROCESS | 1925 | __start_cc_report 13:05:54 INFO - PROCESS | 1925 | _x_x_mozilla_cycle_collect,374 13:05:54 INFO - PROCESS | 1925 | __end_cc_report 13:05:54 INFO - PROCESS | 1925 | __startTimestamp1484514354000__endTimestamp 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | ------- Summary: start ------- 13:05:54 INFO - PROCESS | 1925 | Number of tests: 6 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#0] dojo.html Cycles:165 Average:5712.83 Median:5948.76 stddev:682.16 (11.5%) stddev-sans-first:648.92 13:05:54 INFO - PROCESS | 1925 | Values: 2950.4 2991.6 3290.6 3313.9 3375.9 5444.8 5468.3 5487.7 5498.8 5631.0 5624.1 5694.7 5713.1 5821.1 5840.2 5162.9 5179.3 5200.6 5245.3 5328.4 5222.7 5302.5 5309.6 5312.8 5336.3 5100.8 5260.7 5275.9 5301.3 5330.3 5107.5 5150.5 5157.6 5170.5 5209.9 5210.3 5234.4 5264.6 5267.6 5273.1 5533.8 5637.6 5703.0 5745.0 5749.2 6100.7 6146.0 6230.5 6282.2 6299.0 5429.5 6254.6 6269.2 6270.3 6302.6 5926.2 5943.6 5956.1 5969.1 5989.3 6169.0 6308.9 6358.9 6374.7 6392.7 5060.8 5404.8 6106.8 6141.8 6210.2 5158.5 6129.8 6250.7 6297.2 6299.7 6128.1 6148.5 6158.7 6166.0 6178.2 6142.9 6169.8 6173.5 6188.7 6247.1 4431.7 4956.1 4975.3 4976.9 5012.6 4471.6 4472.4 4501.7 4509.4 4536.0 4877.5 5864.8 5864.9 5927.7 5936.6 6132.2 6181.9 6203.8 6242.4 6276.4 5912.3 5914.2 5918.2 5925.2 5933.9 4524.6 5488.1 5496.2 5504.3 5522.9 5546.6 6144.0 6293.2 6308.4 6370.3 6250.5 6266.7 6277.9 6291.8 6327.8 6380.9 6388.3 6395.8 6405.6 6412.2 6133.8 6193.2 6208.4 6256.3 6286.2 6200.0 6202.5 6251.2 6301.3 6306.8 6110.4 6190.4 6190.7 6199.3 6203.2 6008.9 6009.0 6029.1 6128.5 6145.0 4935.2 5847.5 5948.8 5966.4 6046.1 5860.3 6191.6 6200.8 6215.3 6220.2 4513.0 5273.8 6021.2 6021.4 6084.7 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#1] ext.html Cycles:180 Average:68615.64 Median:37970.81 stddev:146728.95 (386.4%) stddev-sans-first:147051.28 13:05:54 INFO - PROCESS | 1925 | Values: 441.0 469.1 481.5 495.8 515.6 27599.7 42271.9 42450.0 42516.8 42549.7 42940.0 43010.4 43394.6 43401.6 43519.0 9281.4 9477.0 9528.0 9539.1 10772.2 10902.9 11116.8 11140.0 11158.8 11172.2 11024.6 11094.0 11109.2 11129.2 11159.7 11088.3 11123.9 11201.6 11204.2 11204.8 10892.8 10912.2 10940.8 10959.0 10988.2 37556.0 37680.4 37798.2 37960.8 37980.8 3969.0 3980.6 4001.1 4001.5 4014.9 23642.0 28808.9 29138.7 29295.6 29484.0 3184.3 3263.3 3325.1 3328.7 3341.8 494963.0 495779.0 495941.0 498643.0 499039.0 57973.1 58256.0 58332.7 58425.4 58572.8 58767.4 59082.0 59209.0 59323.7 59331.0 15475.0 15589.4 15592.8 15623.1 15624.8 10792.6 10959.6 11900.9 12170.3 12187.0 17983.2 18014.9 18109.9 18125.6 18136.6 6301.7 6429.8 7374.7 7550.4 7656.3 866.2 867.3 868.0 876.8 893.6 31165.7 31174.0 31181.7 31275.0 31618.0 998.3 1058.9 1082.6 1089.9 1094.2 13709.2 15863.3 16236.1 16279.2 16639.0 609697.0 619204.0 621313.0 623356.0 623750.0 55863.4 57264.6 60984.0 61403.0 62128.7 1385.4 1415.1 1444.1 1446.1 1448.0 23606.6 27413.6 28144.7 29125.7 30649.8 54176.9 54712.7 54965.5 54999.9 55106.9 523069.0 527185.0 527707.4 527736.0 527839.0 24331.8 27484.6 27635.2 27743.6 27920.9 48493.0 48924.8 48979.8 49566.3 49880.8 33323.7 39876.8 40389.0 40399.4 41359.4 38407.2 39987.8 40204.6 40704.0 41190.6 38348.4 38541.8 38867.8 39326.6 39331.2 32529.8 34681.1 35087.8 35209.0 35298.6 25379.9 25395.2 25541.5 25829.5 26095.6 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#2] jquery.html Cycles:180 Average:37445.14 Median:1597.99 stddev:110076.62 (6888.4%) stddev-sans-first:110350.25 13:05:54 INFO - PROCESS | 1925 | Values: 391.1 392.4 400.0 401.0 420.8 19859.0 20968.9 21092.0 21125.6 21130.6 20604.0 20970.1 21072.7 21073.9 21123.6 5687.2 5856.7 5869.4 5921.1 5923.5 5652.2 5762.5 5795.7 5875.9 5889.1 5712.1 5741.9 5744.1 5752.4 5763.4 5467.6 5696.8 5731.4 5757.6 5782.4 5095.4 5101.0 5101.4 5101.9 5103.0 25712.7 25838.0 25853.0 26223.7 26367.0 2719.9 2746.7 2768.2 2789.7 2804.9 15303.5 15341.7 15510.5 15590.5 15685.8 1689.2 1788.3 1819.0 1873.1 1882.5 92374.5 124473.0 132320.0 134689.0 144311.3 9774.9 11588.7 11797.9 11946.6 12083.7 11380.8 15505.9 15523.8 15581.1 15756.7 7535.1 8443.4 8523.6 8765.7 8900.0 8476.8 8505.3 8536.0 8643.1 8653.7 4710.2 4826.5 4836.3 4887.1 4899.4 1997.3 2068.9 2069.4 2073.0 2078.2 760.5 861.5 878.1 896.2 905.0 20212.9 21071.9 21956.5 21979.7 22138.8 889.6 906.6 910.1 927.6 933.8 9002.8 11064.8 11417.0 11436.9 11452.5 506356.9 540967.0 722833.0 730603.0 754243.0 110537.3 112882.4 138491.3 148034.0 148627.0 970.3 973.8 1002.3 1003.0 1051.6 19808.5 19975.9 20038.6 20183.1 20248.9 46391.5 56115.7 56372.0 56756.7 59656.3 87060.0 90547.7 93442.0 93669.1 93923.1 7226.3 7343.2 7485.4 7857.8 8109.6 11471.7 12922.6 12956.4 13440.0 13459.5 5583.1 8610.2 8982.0 10615.7 10996.5 9628.8 10322.6 11077.8 11220.7 11270.7 11121.9 11135.9 11612.8 11758.6 11891.6 8202.5 8346.7 8628.4 8675.3 8817.3 10722.7 10808.2 11095.9 11118.8 11482.5 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#3] mootools.html Cycles:180 Average:5437.43 Median:5253.66 stddev:2951.55 (56.2%) stddev-sans-first:2943.40 13:05:54 INFO - PROCESS | 1925 | Values: 1293.0 1426.4 1430.4 1448.1 1454.8 7574.5 7841.1 8133.9 8571.9 8579.5 7162.0 8071.7 8228.1 8339.4 8436.4 2304.5 2309.4 2355.1 2359.9 2360.3 2072.8 2092.6 2158.7 2220.0 2342.6 1850.0 2263.9 2338.8 2340.8 2349.8 1935.4 1947.3 2324.7 2340.1 2347.0 2312.7 2321.6 2379.7 2380.3 2384.5 8460.7 8461.8 8498.0 8514.4 8521.5 5074.3 5221.3 5229.7 5238.8 5246.3 3444.7 3452.1 3495.5 3530.7 3566.7 1196.6 1208.1 1220.4 1241.7 1257.5 10803.2 10911.2 10942.6 10946.9 10980.1 4386.7 4681.3 4729.6 4760.8 4776.4 9439.1 9472.8 9497.3 9506.0 9520.0 5213.8 5250.3 5257.0 5284.7 5306.0 4675.8 4816.7 4824.9 4845.3 4866.4 3864.3 3958.6 3972.6 4034.2 4064.5 1179.6 1223.9 1301.8 1305.9 1320.3 758.5 762.6 792.4 819.6 830.6 5459.6 5535.1 5537.3 5647.1 5782.2 905.4 970.8 983.2 988.7 1038.1 3734.9 3750.2 3753.2 3822.4 3870.1 8808.5 9327.0 9994.1 10002.8 10040.3 9943.5 9967.5 9979.6 9986.9 9994.6 6514.7 7378.4 7830.3 7860.6 7971.7 4811.7 5441.0 5526.3 5701.7 5739.7 8862.4 9084.0 9148.8 9284.6 9364.9 9114.1 9303.5 9318.0 9382.9 9400.1 7039.9 7231.1 7319.4 7336.9 7348.3 7809.3 7858.7 7871.6 7880.1 7964.6 7075.9 7193.3 7242.7 7295.7 7300.0 6921.8 7015.4 7024.4 7167.0 7167.9 6281.9 6415.4 6448.8 6458.8 6464.7 4905.7 4917.0 4979.5 5067.3 5088.1 4988.2 5001.7 5096.8 5156.6 5191.2 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#4] prototype.html Cycles:180 Average:4401.12 Median:4825.59 stddev:1337.69 (27.7%) stddev-sans-first:1306.50 13:05:54 INFO - PROCESS | 1925 | Values: 354.0 357.4 366.0 367.7 371.6 4074.8 4383.9 4468.8 4760.2 4881.4 4234.5 4467.3 5139.0 5331.7 5398.3 4216.8 4244.4 4246.7 4253.8 4258.7 3640.5 3987.2 4056.4 4246.9 4268.0 3469.7 4165.5 4170.8 4181.9 4215.7 3377.9 3905.3 4081.7 4192.2 4206.9 2994.8 3157.0 3713.2 3716.5 3731.0 4719.6 4982.2 5190.1 5236.7 5287.6 3827.7 4322.8 4631.9 4641.3 4657.9 5683.8 5702.8 5706.4 5786.9 5831.5 3310.6 3438.4 3589.4 3807.6 3900.7 5893.2 5915.5 5919.6 5950.6 5961.0 3893.2 3899.9 3923.8 3924.9 3925.8 3547.9 3847.7 3965.2 3973.3 3980.2 3955.4 4248.5 4339.4 4583.5 4603.8 5069.1 5245.4 5463.3 5720.4 5759.6 1206.8 1207.2 1221.3 1239.9 1306.9 710.8 712.2 867.4 870.6 881.3 4222.9 5073.7 5080.9 5092.4 5101.3 4330.4 5319.7 5577.6 5608.4 5623.4 4708.0 4709.8 5011.2 5079.2 5085.2 2668.4 2812.3 2917.6 2922.9 2924.3 5056.7 5100.8 5335.6 5478.5 5682.9 4853.3 5232.8 5365.0 5609.6 5682.8 4684.5 4948.9 5293.4 5713.6 5736.5 4570.8 4820.2 4920.7 5480.3 5655.4 4567.6 4810.8 5024.3 5107.6 5231.3 4860.6 5195.6 5733.6 5746.3 5747.3 3050.6 3639.8 3656.8 3691.2 3708.5 4380.6 5276.4 5503.3 5523.8 5561.7 4504.9 5190.6 5322.6 5383.4 5455.5 4422.4 4831.0 5156.8 5364.6 5514.8 4484.4 5237.0 5250.9 5372.4 5430.9 5686.9 5689.2 5691.3 5717.8 5718.5 4811.9 4983.7 5114.2 5496.8 5612.7 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - PROCESS | 1925 | [#5] yui.html Cycles:180 Average:20904.65 Median:3811.85 stddev:30856.52 (809.5%) stddev-sans-first:30905.82 13:05:54 INFO - PROCESS | 1925 | Values: 708.7 711.3 713.4 717.2 733.0 14893.5 15481.6 15675.0 15868.4 15906.6 13743.9 13820.8 13864.7 14052.0 14650.5 850.7 862.7 868.0 868.2 870.6 691.4 711.5 853.9 858.4 878.7 915.4 918.3 919.8 920.0 921.1 912.1 914.5 915.7 916.9 918.1 666.7 675.7 678.3 680.7 681.4 15189.9 15408.9 15412.5 15420.6 15749.1 13801.9 13921.1 14260.1 14274.6 14314.9 11684.3 11741.4 11943.5 12111.6 12156.6 3632.9 3665.5 3680.6 3702.2 3804.7 108687.0 109072.5 110922.0 112435.9 113261.4 10711.3 10744.5 10755.9 10793.7 10926.3 18462.9 19724.0 20019.7 21238.0 21922.5 6482.8 7736.8 7777.6 7941.2 7958.9 3819.0 3941.9 4170.0 4274.0 4344.3 3516.0 3676.8 3682.3 3865.7 4046.4 603.5 728.6 728.6 741.6 753.9 540.5 555.2 568.7 583.0 619.9 8511.9 10352.0 10565.7 10673.5 10837.5 690.8 707.3 712.0 717.2 729.5 4805.5 4914.9 5151.7 5156.9 5165.3 106246.0 106553.0 108573.0 109545.5 111574.4 75480.6 89396.0 89603.0 90916.0 91637.5 66587.7 87188.1 88147.9 88608.0 88871.1 8111.4 9898.4 9902.3 9990.8 10042.3 53491.5 54967.0 54983.5 56695.4 57225.7 59138.0 68869.3 73485.6 75253.2 75705.0 11647.9 11764.8 11791.2 11828.7 12105.6 13139.4 13353.6 13450.4 13484.0 13655.2 12273.7 12495.8 12527.0 12655.4 12769.5 12299.3 12457.6 12818.1 12819.0 12831.4 12085.5 12237.0 12255.8 12525.4 12530.1 10043.0 10134.3 10276.9 10764.4 11161.9 8391.9 9980.5 10034.2 10120.9 10126.9 13:05:54 INFO - PROCESS | 1925 | -------- Summary: end -------- 13:05:54 INFO - PROCESS | 1925 | 13:05:54 INFO - TEST-INFO | 1925: exit 0 13:05:54 INFO - TEST-OK | dromaeo_css | took 1114750ms 13:05:54 INFO - TEST-START | kraken 13:05:54 INFO - operating with platform_type : mac_ 13:05:54 INFO - Initialising browser for kraken test... 13:05:54 INFO - TEST-INFO | started process 1956 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpt1F4Xf/profile http://localhost:50869/getInfo.html) 13:05:57 INFO - PROCESS | 1956 | 2017-01-15 13:05:57.526 plugin-container[1957:22894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4747, name = 'com.apple.tsm.portname' 13:05:57 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:57 INFO - PROCESS | 1956 | 2017-01-15 13:05:57.530 plugin-container[1957:22894] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e1b, name = 'com.apple.CFPasteboardClient' 13:05:57 INFO - PROCESS | 1956 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:05:57 INFO - PROCESS | 1956 | 2017-01-15 13:05:57.530 plugin-container[1957:22894] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:05:57 INFO - PROCESS | 1956 | __metrics Screen width/height:1600/1200 13:05:57 INFO - PROCESS | 1956 | colorDepth:24 13:05:57 INFO - PROCESS | 1956 | Browser inner width/height: 1024/689 13:05:57 INFO - PROCESS | 1956 | __metrics 13:05:57 ERROR - PROCESS | 1956 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3249: TypeError: this.tabs is undefined 13:05:58 INFO - TEST-INFO | 1956: exit 0 13:05:58 INFO - Browser initialized. 13:05:58 INFO - Running cycle 1/1 for kraken test... 13:05:58 INFO - TEST-INFO | started process 1958 (/builds/slave/test/build/application/Nightly.app/Contents/MacOS/firefox -foreground -profile /var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/T/tmpt1F4Xf/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:06:00 INFO - PROCESS | 1958 | 2017-01-15 13:06:00.925 plugin-container[1959:23012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 13:06:00 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:06:00 INFO - PROCESS | 1958 | 2017-01-15 13:06:00.925 plugin-container[1959:23012] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8f0b, name = 'com.apple.CFPasteboardClient' 13:06:00 INFO - PROCESS | 1958 | See /usr/include/servers/bootstrap_defs.h for the error codes. 13:06:00 INFO - PROCESS | 1958 | 2017-01-15 13:06:00.925 plugin-container[1959:23012] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 13:06:10 INFO - PROCESS | 1958 | RSS: Main: 233172992 13:06:10 INFO - PROCESS | 1958 | 13:06:13 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:50869/tests/kraken/driver.html?testName=audio-beat-detection) 13:06:14 INFO - PROCESS | 1958 | RSS: Main: 246153216 13:06:14 INFO - PROCESS | 1958 | 13:06:17 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:50869/tests/kraken/driver.html?testName=audio-dft) 13:06:17 INFO - PROCESS | 1958 | RSS: Main: 254193664 13:06:17 INFO - PROCESS | 1958 | 13:06:21 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:50869/tests/kraken/driver.html?testName=audio-fft) 13:06:21 INFO - PROCESS | 1958 | RSS: Main: 256905216 13:06:21 INFO - PROCESS | 1958 | 13:06:23 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:50869/tests/kraken/driver.html?testName=audio-oscillator) 13:06:24 INFO - PROCESS | 1958 | RSS: Main: 258027520 13:06:24 INFO - PROCESS | 1958 | 13:06:26 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:50869/tests/kraken/driver.html?testName=imaging-gaussian-blur) 13:06:27 INFO - PROCESS | 1958 | RSS: Main: 258281472 13:06:27 INFO - PROCESS | 1958 | 13:06:30 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:50869/tests/kraken/driver.html?testName=imaging-darkroom) 13:06:30 INFO - PROCESS | 1958 | RSS: Main: 265183232 13:06:30 INFO - PROCESS | 1958 | 13:06:34 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:50869/tests/kraken/driver.html?testName=imaging-desaturate) 13:06:34 INFO - PROCESS | 1958 | RSS: Main: 264552448 13:06:34 INFO - PROCESS | 1958 | 13:06:37 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:50869/tests/kraken/driver.html?testName=json-parse-financial) 13:06:38 INFO - PROCESS | 1958 | RSS: Main: 264175616 13:06:38 INFO - PROCESS | 1958 | 13:06:39 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:50869/tests/kraken/driver.html?testName=json-stringify-tinderbox) 13:06:40 INFO - PROCESS | 1958 | RSS: Main: 263827456 13:06:40 INFO - PROCESS | 1958 | 13:06:42 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-aes) 13:06:43 INFO - PROCESS | 1958 | RSS: Main: 263819264 13:06:43 INFO - PROCESS | 1958 | 13:06:45 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-ccm) 13:06:46 INFO - PROCESS | 1958 | RSS: Main: 263847936 13:06:46 INFO - PROCESS | 1958 | 13:06:48 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 13:06:49 INFO - PROCESS | 1958 | RSS: Main: 264167424 13:06:49 INFO - PROCESS | 1958 | 13:06:52 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 13:06:53 INFO - PROCESS | 1958 | RSS: Main: 263983104 13:06:53 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | Cycle 1(1): loaded http://localhost:50869/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:50869/tests/kraken/driver.html?testName=ai-astar) 13:06:55 INFO - PROCESS | 1958 | RSS: Main: 265945088 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | __start_tp_report 13:06:55 INFO - PROCESS | 1958 | _x_x_mozilla_page_load 13:06:55 INFO - PROCESS | 1958 | _x_x_mozilla_page_load_details 13:06:55 INFO - PROCESS | 1958 | |i|pagename|runs| 13:06:55 INFO - PROCESS | 1958 | |0;ai-astar;100;139;120;119;118;120;118;120;118;118 13:06:55 INFO - PROCESS | 1958 | |1;audio-beat-detection;146;112;111;161;143;144;135;116;137;99 13:06:55 INFO - PROCESS | 1958 | |2;audio-dft;201;210;223;211;198;203;248;237;194;239 13:06:55 INFO - PROCESS | 1958 | |3;audio-fft;83;60;61;85;60;61;52;70;100;84 13:06:55 INFO - PROCESS | 1958 | |4;audio-oscillator;87;74;93;98;70;72;78;63;76;92 13:06:55 INFO - PROCESS | 1958 | |5;imaging-gaussian-blur;91;123;91;76;127;131;93;90;92;90 13:06:55 INFO - PROCESS | 1958 | |6;imaging-darkroom;100;155;98;151;153;111;151;114;104;153 13:06:55 INFO - PROCESS | 1958 | |7;imaging-desaturate;92;94;94;95;96;92;93;90;91;93 13:06:55 INFO - PROCESS | 1958 | |8;json-parse-financial;62;64;63;62;63;63;62;62;63;63 13:06:55 INFO - PROCESS | 1958 | |9;json-stringify-tinderbox;72;79;76;84;79;71;71;73;73;73 13:06:55 INFO - PROCESS | 1958 | |10;stanford-crypto-aes;91;84;104;111;115;95;87;87;91;90 13:06:55 INFO - PROCESS | 1958 | |11;stanford-crypto-ccm;126;141;142;140;162;143;122;165;209;147 13:06:55 INFO - PROCESS | 1958 | |12;stanford-crypto-pbkdf2;155;260;183;177;171;178;177;180;204;178 13:06:55 INFO - PROCESS | 1958 | |13;stanford-crypto-sha256-iterative;66;69;67;68;81;92;61;76;88;89 13:06:55 INFO - PROCESS | 1958 | __end_tp_report 13:06:55 INFO - PROCESS | 1958 | __start_cc_report 13:06:55 INFO - PROCESS | 1958 | _x_x_mozilla_cycle_collect,910 13:06:55 INFO - PROCESS | 1958 | __end_cc_report 13:06:55 INFO - PROCESS | 1958 | __startTimestamp1484514415474__endTimestamp 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | ------- Summary: start ------- 13:06:55 INFO - PROCESS | 1958 | Number of tests: 14 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#0] ai-astar Cycles:10 Average:119.00 Median:119.50 stddev:9.24 (7.7%) stddev-sans-first:6.77 13:06:55 INFO - PROCESS | 1958 | Values: 100.0 139.0 120.0 119.0 118.0 120.0 118.0 120.0 118.0 118.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#1] audio-beat-detection Cycles:10 Average:130.40 Median:143.50 stddev:19.71 (13.7%) stddev-sans-first:20.08 13:06:55 INFO - PROCESS | 1958 | Values: 146.0 112.0 111.0 161.0 143.0 144.0 135.0 116.0 137.0 99.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#2] audio-dft Cycles:10 Average:216.40 Median:217.00 stddev:19.16 (8.8%) stddev-sans-first:19.50 13:06:55 INFO - PROCESS | 1958 | Values: 201.0 210.0 223.0 211.0 198.0 203.0 248.0 237.0 194.0 239.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#3] audio-fft Cycles:10 Average:71.60 Median:65.50 stddev:15.46 (23.6%) stddev-sans-first:15.84 13:06:55 INFO - PROCESS | 1958 | Values: 83.0 60.0 61.0 85.0 60.0 61.0 52.0 70.0 100.0 84.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#4] audio-oscillator Cycles:10 Average:80.30 Median:82.50 stddev:11.52 (14.0%) stddev-sans-first:11.96 13:06:55 INFO - PROCESS | 1958 | Values: 87.0 74.0 93.0 98.0 70.0 72.0 78.0 63.0 76.0 92.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#5] imaging-gaussian-blur Cycles:10 Average:100.40 Median:90.50 stddev:19.06 (21.1%) stddev-sans-first:19.91 13:06:55 INFO - PROCESS | 1958 | Values: 91.0 123.0 91.0 76.0 127.0 131.0 93.0 90.0 92.0 90.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#6] imaging-darkroom Cycles:10 Average:129.00 Median:152.00 stddev:25.32 (16.7%) stddev-sans-first:24.59 13:06:55 INFO - PROCESS | 1958 | Values: 100.0 155.0 98.0 151.0 153.0 111.0 151.0 114.0 104.0 153.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#7] imaging-desaturate Cycles:10 Average:93.00 Median:93.50 stddev:1.83 (2.0%) stddev-sans-first:1.90 13:06:55 INFO - PROCESS | 1958 | Values: 92.0 94.0 94.0 95.0 96.0 92.0 93.0 90.0 91.0 93.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#8] json-parse-financial Cycles:10 Average:62.70 Median:63.00 stddev:0.67 (1.1%) stddev-sans-first:0.67 13:06:55 INFO - PROCESS | 1958 | Values: 62.0 64.0 63.0 62.0 63.0 63.0 62.0 62.0 63.0 63.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#9] json-stringify-tinderbox Cycles:10 Average:75.10 Median:74.50 stddev:4.31 (5.8%) stddev-sans-first:4.42 13:06:55 INFO - PROCESS | 1958 | Values: 72.0 79.0 76.0 84.0 79.0 71.0 71.0 73.0 73.0 73.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#10] stanford-crypto-aes Cycles:10 Average:95.50 Median:88.50 stddev:10.75 (12.1%) stddev-sans-first:11.28 13:06:55 INFO - PROCESS | 1958 | Values: 91.0 84.0 104.0 111.0 115.0 95.0 87.0 87.0 91.0 90.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#11] stanford-crypto-ccm Cycles:10 Average:149.70 Median:145.00 stddev:24.75 (17.1%) stddev-sans-first:24.72 13:06:55 INFO - PROCESS | 1958 | Values: 126.0 141.0 142.0 140.0 162.0 143.0 122.0 165.0 209.0 147.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:186.30 Median:179.00 stddev:28.52 (15.9%) stddev-sans-first:27.91 13:06:55 INFO - PROCESS | 1958 | Values: 155.0 260.0 183.0 177.0 171.0 178.0 177.0 180.0 204.0 178.0 13:06:55 INFO - PROCESS | 1958 | 13:06:55 INFO - PROCESS | 1958 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:75.70 Median:78.50 stddev:11.12 (14.2%) stddev-sans-first:11.22 13:06:55 INFO - PROCESS | 1958 | Values: 66.0 69.0 67.0 68.0 81.0 92.0 61.0 76.0 88.0 89.0 13:06:55 INFO - PROCESS | 1958 | -------- Summary: end -------- 13:06:55 INFO - PROCESS | 1958 | 13:06:56 INFO - TEST-INFO | 1958: exit 0 13:06:56 INFO - TEST-OK | kraken | took 61695ms 13:06:56 INFO - SUITE-END | took 1176s 13:06:56 INFO - Completed test suite (00:19:36) 13:06:56 INFO - javascript benchmark 13:06:56 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7263.35265175108, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2950.395168990357, 2991.596134521839, 3290.55577497038, 3313.867445302188, 3375.8818441354556, 5444.809431669893, 5468.343798744151, 5487.6981766002855, 5498.807541736035, 5630.971845140779, 5624.128260119678, 5694.715264236782, 5713.114467257582, 5821.09772985188, 5840.153177789218, 5162.8709282268055, 5179.2749015137915, 5200.6099542534275, 5245.26566280721, 5328.387235467932, 5222.686638801684, 5302.522772950453, 5309.62832601718, 5312.840614781543, 5336.279602253685, 5100.846974590731, 5260.710660913648, 5275.920861187085, 5301.3373328333955, 5330.28041214435, 5107.463716309771, 5150.536451719326, 5157.5616072633875, 5170.482951704799, 5209.947900520984, 5210.296609957645, 5234.371875374942, 5264.578833693296, 5267.631265811395, 5273.1035723927025, 5533.778648854041, 5637.633553818989, 5702.971485142589, 5744.971275143597, 5749.195112684228, 6100.732564372368, 6145.985912324461, 6230.532710046765, 6282.2147231596055, 6299.0, 5429.53848922842, 6254.6247225166635, 6269.216347956505, 6270.278917924452, 6302.590331628429, 5926.170336152942, 5943.55423343251, 5956.106584012433, 5969.074793407029, 5989.251343582052, 6168.9691551541955, 6308.936910630835, 6358.904616430757, 6374.745010199633, 6392.680365981775, 5060.7722652480725, 5404.83785486436, 6106.82471942484, 6141.815745527641, 6210.223722034746, 5158.509941555546, 6129.756146212483, 6250.656213908188, 6297.1813664223355, 6299.748010079637, 6128.105930682418, 6148.477379422709, 6158.661273629993, 6166.0, 6178.196834411498, 6142.907856382158, 6169.81490555284, 6173.506119510428, 6188.721507532172, 6247.125402443661, 4431.711938723973, 4956.058348913695, 4975.303457515951, 4976.929960058533, 5012.573931215815, 4471.619912307425, 4472.39622650938, 4501.662375321864, 4509.436320459942, 4535.977320113444, 4877.487863774323, 5864.765409383577, 5864.9413505864395, 5927.73325200367, 5936.643801371845, 6132.2331366082535, 6181.938180618136, 6203.7828675996125, 6242.375762423721, 6276.435120839146, 5912.3496415394275, 5914.172015917775, 5918.230630018156, 5925.170476133345, 5933.851653708605, 4524.615407690383, 5488.121900495901, 5496.1755736639825, 5504.284443022381, 5522.889542209134, 5546.639468434539, 6143.972489078594, 6293.181886354744, 6308.432241098323, 6370.267419246753, 6250.468710159687, 6266.717997690206, 6277.905831412624, 6291.811245662638, 6327.841803954938, 6380.872382552416, 6388.329225431448, 6395.808125756234, 6405.615663060231, 6412.198475190601, 6133.815985520441, 6193.163922870535, 6208.441240288395, 6256.280527739368, 6286.15136956505, 6200.0, 6202.472789812736, 6251.249850018027, 6301.306856245901, 6306.842328941813, 6110.388961103854, 6190.350013248724, 6190.659513726789, 6199.28708198563, 6203.16257305258, 6008.909866351921, 6008.969955150196, 6029.095635654687, 6128.540359472968, 6144.969275153596, 4935.210366341368, 5847.473727364558, 5948.762049518144, 5966.3735307793795, 6046.062860256668, 5860.267466566679, 6191.628502289877, 6200.7519699211525, 6215.285242197025, 6220.191375121205, 4513.0, 5273.78904843802, 6021.2473440819895, 6021.42796434338, 6084.726187321493], "value": 5668.732990159886, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [441.04293682708527, 469.1274229932325, 481.47038257916273, 495.7546014722713, 515.6029857010101, 27599.72400275999, 42271.94320141994, 42450.0, 42516.78741606295, 42549.7235082948, 42940.0, 43010.35484467736, 43394.56605433945, 43401.565984340144, 43519.0, 9281.350305478602, 9477.004914483983, 9528.0, 9539.141477267045, 10772.192085593611, 10902.890971090306, 11116.777664446707, 11140.0, 11158.776824463506, 11172.162087843404, 11024.559017639285, 11094.00153986141, 11109.222354435193, 11129.165312601546, 11159.665210043711, 11088.279261847954, 11123.944380278128, 11201.607943722012, 11204.21570490066, 11204.831927521094, 10892.836607450894, 10912.181586381053, 10940.781184376348, 10959.0, 10988.230823842334, 37556.0, 37680.43479347812, 37798.24403511915, 37960.81019594912, 37980.81009594962, 3969.0, 3980.5820388859333, 4001.1197536541913, 4001.479807625019, 4014.8996275093064, 23642.0, 28808.855955720297, 29138.70861291381, 29295.560566591517, 29483.968061117757, 3184.26761844777, 3263.282077942849, 3325.085601459618, 3328.700416962485, 3341.8496167672515, 494963.0, 495779.0, 495941.0, 498643.0, 499038.9999999964, 57973.130403043986, 58256.0, 58332.7083364589, 58425.415745842845, 58572.82854342889, 58767.41232587705, 59082.0, 59209.0, 59323.70338148282, 59331.0, 15475.0, 15589.376424942875, 15592.766108508382, 15623.14072726011, 15624.76562851558, 10792.622258220925, 10959.561617535368, 11900.940495297467, 12170.330631815157, 12187.0, 17983.190756415992, 18014.91910485375, 18109.91340519573, 18125.637487250446, 18136.63726725458, 6301.684915754194, 6429.839254018687, 7374.668139933716, 7550.433717471212, 7656.310932016146, 866.2247288676599, 867.2828939860818, 867.9739607811775, 876.7983363826228, 893.6425429828121, 31165.68834311628, 31174.0, 31181.68818311788, 31275.0, 31618.0, 998.3161534348994, 1058.9258751887448, 1082.6481393547128, 1089.8910108989037, 1094.1848322999258, 13709.245991470565, 15863.286152123183, 16236.132844723008, 16279.186040697918, 16639.0, 609697.0, 619204.0, 621313.0, 623356.0, 623750.0, 55863.44136558664, 57264.56838579069, 60984.0, 61403.0, 62128.689356552924, 1385.3904282115836, 1415.1367665723901, 1444.0830072903782, 1446.088963952724, 1448.0, 23606.645900311167, 27413.629318534393, 28144.71855281421, 29125.7087429123, 30649.846750766996, 54176.91646167133, 54712.726436367564, 54965.450345496036, 54999.90000200053, 55106.89786204173, 523069.0, 527185.0, 527707.3614631902, 527736.0, 527839.0, 24331.756682432948, 27484.58773118365, 27635.170944871687, 27743.58384624192, 27920.86039569789, 48493.0, 48924.77688057827, 48979.77550561265, 49566.256506151716, 49880.75059624824, 33323.666763332054, 39876.80061599673, 40389.0, 40399.39400909047, 41359.37960930528, 38407.231855363294, 39987.80006099951, 40204.597954020086, 40704.0, 41190.58809411988, 38348.424773628976, 38541.80729096449, 38867.80566097151, 39326.60673393229, 39331.2133757329, 32529.837350813094, 34681.1329716749, 35087.82456087703, 35209.0, 35298.647013530564, 25379.873100634377, 25395.238142855746, 25541.489170216864, 25829.483410332065, 26095.60856587115], "value": 20164.810942591565, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [391.0887631817865, 392.42901578203714, 399.9880003599892, 400.99150636150085, 420.8300923432858, 19859.007049647513, 20968.89515552424, 21092.0, 21125.57748845022, 21130.57738845222, 20603.969801509902, 20970.056347464364, 21072.683909741365, 21073.894630526866, 21123.577528449423, 5687.175359572849, 5856.707164641773, 5869.413058694117, 5921.052631578948, 5923.496502797268, 5652.236948012028, 5762.53899688026, 5795.71021448928, 5875.9118613220835, 5889.057750759879, 5712.11462223356, 5741.913871291934, 5744.109663002241, 5752.424757524256, 5763.39484354143, 5467.5625949923915, 5696.832149409361, 5731.426857314256, 5757.596968212227, 5782.421757824226, 5095.363079615048, 5100.954304367596, 5101.362329708786, 5101.923471147898, 5102.974485127551, 25712.74287257141, 25838.0, 25853.0, 26223.73776262232, 26367.0, 2719.9184024479296, 2746.6978632350424, 2768.183385901144, 2789.7489225969557, 2804.887804487818, 15303.540893773094, 15341.693166136727, 15510.534683959499, 15590.532284031497, 15685.843141568552, 1689.2060731456195, 1788.2685991397539, 1818.9818101818944, 1873.1196337721249, 1882.4729075858781, 92374.5381273096, 124473.0, 132320.0, 134689.0, 144311.27844360922, 9774.853377199348, 11588.710282243012, 11797.88202117985, 11946.641600751991, 12083.697907552381, 11380.772384552263, 15505.922470387575, 15523.844761552466, 15581.14303713284, 15756.68486630284, 7535.092085614734, 8443.366747493963, 8523.616437260338, 8765.693200738071, 8899.955500222586, 8476.830463390697, 8505.277051450696, 8536.0, 8643.135686431306, 8653.740387788377, 4710.199266124767, 4826.541478559531, 4836.250381190921, 4887.0714564232685, 4899.4365647950945, 1997.3308941504758, 2068.917243310251, 2069.430906500724, 2073.0, 2078.189506092625, 760.5056713136505, 861.4917198852708, 878.1076779540101, 896.1979028769215, 904.9954750226207, 20212.89893550523, 21071.894640527007, 21956.451088722908, 21979.670304945437, 22138.77861221367, 889.6396959231522, 906.5857262670255, 910.1262787723726, 927.6196759328643, 933.7758937854866, 9002.774930626787, 11064.778704425866, 11417.0, 11436.94281528587, 11452.484638191134, 506356.93643063836, 540967.0, 722833.0, 730603.0, 754243.0, 110537.34193987097, 112882.43558782153, 138491.30754346165, 148034.0, 148627.0, 970.3498655900563, 973.78576713123, 1002.3334482569155, 1002.9749256268651, 1051.6371851710994, 19808.504787380432, 19975.900120499304, 20038.59922801565, 20183.091760870502, 20248.89875550613, 46391.53608464007, 56115.71942140263, 56372.0, 56756.71621641865, 59656.31546580327, 87060.0, 90547.73630659287, 93442.0, 93669.06330936603, 93923.06076939144, 7226.313500217471, 7343.228960959236, 7485.401167906445, 7857.842843143219, 8109.635066422144, 11471.713207169554, 12922.612321630366, 12956.416961236579, 13440.0, 13459.46162153542, 5583.106702927512, 8610.182032706884, 8981.955090224506, 10615.734606634896, 10996.450177490933, 9628.807423851344, 10322.638707645196, 11077.778444431227, 11220.719482012688, 11270.718232044264, 11121.94439027832, 11135.88864111381, 11612.76774464489, 11758.647240582453, 11891.583794567148, 8202.507849529047, 8346.749597512084, 8628.439151455135, 8675.34934879874, 8817.338699597427, 10722.73193170177, 10808.18938579594, 11095.889041109485, 11118.833217501582, 11482.540698372306], "value": 9806.42207710291, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1292.9612111636652, 1426.3581388375228, 1430.4492770283439, 1448.073233130796, 1454.78178273259, 7574.507657002291, 7841.137474877759, 8133.87799183012, 8571.871421928668, 8579.52812595307, 7162.033125528053, 8071.677132914691, 8228.053773815998, 8339.416240863127, 8436.367272454576, 2304.4584522637165, 2309.445733024075, 2355.093289083698, 2359.8584084954873, 2360.3391050505884, 2072.7823578524262, 2092.602405542942, 2158.686990386393, 2219.989904593406, 2342.625179971205, 1849.9537511562185, 2263.875486848223, 2338.754430784769, 2340.847844890085, 2349.835511514195, 1935.3903520391032, 1947.2989723699457, 2324.7094113235844, 2340.1107579119825, 2346.9530609387803, 2312.6531020346915, 2321.6401457774073, 2379.690640216778, 2380.27401642499, 2384.523095380931, 8460.703875364332, 8461.788455288606, 8497.95751021247, 8514.361422893246, 8521.531315777629, 5074.340335756365, 5221.321228240343, 5229.660072095339, 5238.81664141757, 5246.31797866275, 3444.741644376682, 3452.067941655764, 3495.5106285120105, 3530.735194860396, 3566.696830769388, 1196.5931583261734, 1208.0878936403046, 1220.4446976625613, 1241.745442184359, 1257.5158563952853, 10803.243772935897, 10911.181661375349, 10942.617008404668, 10946.945265273702, 10980.121590272838, 4386.670999675037, 4681.321208424827, 4729.6452766042685, 4760.785764640599, 4776.426828780569, 9439.056094390644, 9472.763180920532, 9497.335186537015, 9506.0, 9519.952805191424, 5213.817516386908, 5250.343707036621, 5256.9737151314, 5284.735763211824, 5306.0, 4675.76621168947, 4816.710997340171, 4824.927626085611, 4845.297431872429, 4866.4403593586485, 3864.2851072551666, 3958.5645578986287, 3972.5828787977425, 4034.1528279061727, 4064.5122585289955, 1179.628417048627, 1223.9449224784905, 1301.7852054411105, 1305.8563558008611, 1320.3002408723391, 758.5486635452007, 762.5882023707247, 792.3859009267749, 819.6352623082671, 830.6220669595319, 5459.590530710213, 5535.059039963216, 5537.307836520435, 5647.124695672177, 5782.2483077199695, 905.4341036851968, 970.8009857979098, 983.2036050798876, 988.6687959533637, 1038.081298051215, 3734.9355433701267, 3750.249950010009, 3753.155540003477, 3822.4266360046217, 3870.0905287257474, 8808.471491710518, 9326.95336523313, 9994.10053095225, 10002.799944001079, 10040.297179197532, 9943.45311007887, 9967.501624918725, 9979.60081596728, 9986.850197247048, 9994.550245238981, 6514.674266286762, 7378.372838308696, 7830.256125668053, 7860.567668778158, 7971.72098976533, 4811.735354555393, 5440.966216418868, 5526.253955715927, 5701.7149142541875, 5739.684317362585, 8862.42394244372, 9084.0, 9148.817023659623, 9284.628614855601, 9364.859527107235, 9114.088591140833, 9303.488308143116, 9318.02160773109, 9382.906170938477, 9400.059994000545, 7039.859202815813, 7231.096112985877, 7319.414446844133, 7336.853262934605, 7348.3386495213545, 7809.297163255334, 7858.724944626911, 7871.567063811545, 7880.133185349721, 7964.561949092856, 7075.929240707527, 7193.3166349196745, 7242.710291588278, 7295.708171673074, 7300.0, 6921.82695432598, 7015.403690686145, 7024.367806897404, 7166.964165179141, 7167.856642867009, 6281.874362512633, 6415.358464153547, 6448.774292899726, 6458.838529036811, 6464.709088091141, 4905.730184839868, 4916.950830491793, 4979.5269449403695, 5067.341245638191, 5088.084144853962, 4988.176950803077, 5001.749912504433, 5096.796128154981, 5156.639035267495, 5191.195364718475], "value": 4414.043515093089, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [353.9911502212444, 357.35497427144, 366.0318457679437, 367.6764447286388, 371.5565029858997, 4074.755514669122, 4383.947852515397, 4468.75421851798, 4760.167700218373, 4881.36542249508, 4234.513031001436, 4467.3299005149165, 5139.023585518748, 5331.706756128421, 5398.2712333834925, 4216.831326746927, 4244.427002354673, 4246.683528106281, 4253.766042867641, 4258.74447533149, 3640.4539319102214, 3987.2456119307412, 4056.391541268804, 4246.855338054278, 4267.957320426787, 3469.6703813137838, 4165.541790403053, 4170.77060761658, 4181.853635122771, 4215.683823713219, 3377.885297851703, 3905.258000979805, 4081.6530594899323, 4192.197355137289, 4206.85276015338, 2994.8203107813383, 3156.952645710316, 3713.16453797896, 3716.4982727331735, 3731.0151878497363, 4719.622430205575, 4982.227754698028, 5190.11767999441, 5236.6857988520815, 5287.550558202557, 3827.674647654953, 4322.82708691652, 4631.854490231258, 4641.303804429329, 4657.858824587964, 5683.772649094031, 5702.77188912443, 5706.400827913051, 5786.913196302016, 5831.512964194146, 3310.6192787829227, 3438.415469370213, 3589.3847768504015, 3807.6382743639588, 3900.726949113563, 5893.204417403638, 5915.467607915309, 5919.6152250103605, 5950.642961422329, 5960.970195148997, 3893.2213557288655, 3899.941500877489, 3923.843046278174, 3924.860950781831, 3925.823337949799, 3547.911302217465, 3847.711421643388, 3965.1871366369824, 3973.2848087344555, 3980.20395920817, 3955.3869150281175, 4248.4901811782165, 4339.370791235253, 4583.495815460296, 4603.792829322689, 5069.112905241568, 5245.449227831023, 5463.344398672105, 5720.399358067426, 5759.568032397587, 1206.8189771534164, 1207.177162484637, 1221.2733423613113, 1239.8841043061136, 1306.9087312094318, 710.8187412209853, 712.1596516111039, 867.3581549653337, 870.616928551448, 881.2641444393875, 4222.923154595558, 5073.670211436319, 5080.923786143211, 5092.414372347153, 5101.2603172539775, 4330.39374487572, 5319.707416092152, 5577.637453565505, 5608.411116832755, 5623.353314368818, 4707.952920470751, 4709.811607535728, 5011.248312753116, 5079.187330027178, 5085.237214417867, 2668.3862711576057, 2812.2688101093863, 2917.6207093077765, 2922.883084676589, 2924.2835505300914, 5056.696598204119, 5100.795968161232, 5335.598980458809, 5478.45215478457, 5682.886342273131, 4853.272009198648, 5232.764525596357, 5364.973175134178, 5609.607327487037, 5682.829515114553, 4684.484706682262, 4948.925766113439, 5293.364796224478, 5713.6001679587935, 5736.512396446349, 4570.81716731327, 4820.228763397839, 4920.72935988524, 5480.260164877691, 5655.406182350958, 4567.63458923292, 4810.807567697253, 5024.346834911438, 5107.591392688501, 5231.346081739783, 4860.562549370574, 5195.5843532518065, 5733.627314224562, 5746.252987111816, 5747.3103227612955, 3050.5729197912756, 3639.761546648845, 3656.817159141979, 3691.2432951244396, 3708.529918370382, 4380.5838445347645, 5276.440926150891, 5503.3395992481155, 5523.8066667666435, 5561.6663000220115, 4504.887377815581, 5190.584753219809, 5322.616119808955, 5383.434739352469, 5455.509004189642, 4422.402975598253, 4830.956497406612, 5156.767945442389, 5364.624476286778, 5514.786746915672, 4484.376639665832, 5237.0, 5250.89498210026, 5372.382176049804, 5430.9185362218805, 5686.857828554319, 5689.2319536862, 5691.321060287308, 5717.799877004285, 5718.456746609066, 4811.903761924672, 4983.725895075806, 5114.207297868837, 5496.839317392435, 5612.663240205601], "value": 3988.282707474752, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.6740099554208, 711.2531841566354, 713.3544142550993, 717.2361435071648, 732.9670164842581, 14893.4787282445, 15481.612959676, 15675.0, 15868.444604438848, 15906.602334941632, 13743.862561374382, 13820.792688109661, 13864.722705545882, 14052.0, 14650.487232946849, 850.6767428377208, 862.7498025572577, 867.9652813887437, 868.2359523619207, 870.6212797433105, 691.3708525242031, 711.5019486359544, 853.9188777066169, 858.3519442820661, 878.7232021913109, 915.3912648089013, 918.3387960668308, 919.839028170071, 919.9604446974909, 921.1433366968716, 912.1015799437548, 914.5290175559592, 915.7298596913927, 916.8716379706847, 918.1186061381082, 666.6566718140161, 675.7060678604822, 678.2640834694363, 680.680080362229, 681.3765404654735, 15189.924050379788, 15408.922955385264, 15412.537623871302, 15420.614484637861, 15749.05505669652, 13801.930990344985, 13921.095128816696, 14260.147970405857, 14274.64313392163, 14314.928425357806, 11684.298942063504, 11741.354225517593, 11943.462544185446, 12111.576094836726, 12156.635300940987, 3632.854685812564, 3665.486831843544, 3680.576733675635, 3702.241040586673, 3804.714646401531, 108687.0, 109072.4546377271, 110922.0, 112435.87564124417, 113261.43369283101, 10711.303765255234, 10744.462776861126, 10755.892441075646, 10793.730156746144, 10926.344419334866, 18462.907685461487, 19724.013799310265, 20019.694780883077, 21238.044288007077, 21922.45193870166, 6482.837929051717, 7736.839474078821, 7777.611119444005, 7941.166177551389, 7958.880616790752, 3818.980905095457, 3941.885910131022, 4170.0, 4274.0, 4344.304911214191, 3515.964840351615, 3676.816159192083, 3682.318771027315, 3865.7100717446306, 4046.4132700758223, 603.5051257968512, 728.5992704012772, 728.6211170191576, 741.5773009384599, 753.898223739799, 540.5162379670173, 555.1921953557564, 568.7355379748437, 582.962107463022, 619.8698273362554, 8511.872321915176, 10352.010310050837, 10565.683029509128, 10673.466326683634, 10837.512311945824, 690.7582346178898, 707.2962402409636, 711.9837374188131, 717.1645033535976, 729.5383405739799, 4805.471398146201, 4914.90170196594, 5151.6908985461, 5156.948430515647, 5165.302684137669, 106246.0, 106553.0, 108573.0, 109545.45227273813, 111574.44212778885, 75480.62259688776, 89396.0, 89603.0, 90916.0, 91637.54181229051, 66587.6682466348, 87188.128118718, 88147.86299575503, 88608.0, 88871.11128888628, 8111.404350607749, 9898.42899517623, 9902.280130292987, 9990.750231244278, 10042.347247428894, 53491.465085348646, 54967.0, 54983.45016549783, 56695.433045669015, 57225.71387143037, 59138.0, 68869.3113068883, 73485.6325718368, 75253.24746752682, 75705.0, 11647.88352116468, 11764.823527647264, 11791.233569817934, 11828.70428239301, 12105.636830895088, 13139.408726607517, 13353.599392018255, 13450.394732236877, 13483.988700847673, 13655.180689158682, 12273.69315767113, 12495.750084998432, 12527.0, 12655.367231638565, 12769.49085123339, 12299.323537205539, 12457.626271211879, 12818.102732808615, 12819.0, 12831.358432078545, 12085.516579336729, 12237.0, 12255.754884902073, 12525.37373131358, 12530.122891397516, 10043.0, 10134.290599657954, 10276.897231027893, 10764.3541387517, 11161.883811618773, 8391.916080839113, 9980.500974951368, 10034.247431442525, 10120.89879101229, 10126.898731012596], "value": 7439.781427679945, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1585.1, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 139.0, 120.0, 119.0, 118.0, 120.0, 118.0, 120.0, 118.0, 118.0], "value": 119.0, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [146.0, 112.0, 111.0, 161.0, 143.0, 144.0, 135.0, 116.0, 137.0, 99.0], "value": 130.4, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [201.0, 210.0, 223.0, 211.0, 198.0, 203.0, 248.0, 237.0, 194.0, 239.0], "value": 216.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [83.0, 60.0, 61.0, 85.0, 60.0, 61.0, 52.0, 70.0, 100.0, 84.0], "value": 71.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [87.0, 74.0, 93.0, 98.0, 70.0, 72.0, 78.0, 63.0, 76.0, 92.0], "value": 80.3, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 123.0, 91.0, 76.0, 127.0, 131.0, 93.0, 90.0, 92.0, 90.0], "value": 100.4, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 155.0, 98.0, 151.0, 153.0, 111.0, 151.0, 114.0, 104.0, 153.0], "value": 129.0, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [92.0, 94.0, 94.0, 95.0, 96.0, 92.0, 93.0, 90.0, 91.0, 93.0], "value": 93.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [62.0, 64.0, 63.0, 62.0, 63.0, 63.0, 62.0, 62.0, 63.0, 63.0], "value": 62.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 79.0, 76.0, 84.0, 79.0, 71.0, 71.0, 73.0, 73.0, 73.0], "value": 75.1, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [91.0, 84.0, 104.0, 111.0, 115.0, 95.0, 87.0, 87.0, 91.0, 90.0], "value": 95.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [126.0, 141.0, 142.0, 140.0, 162.0, 143.0, 122.0, 165.0, 209.0, 147.0], "value": 149.7, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [155.0, 260.0, 183.0, 177.0, 171.0, 178.0, 177.0, 180.0, 204.0, 178.0], "value": 186.3, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [66.0, 69.0, 67.0, 68.0, 81.0, 92.0, 61.0, 76.0, 88.0, 89.0], "value": 75.7, "unit": "score"}]}]} 13:06:56 INFO - Return code: 0 13:06:56 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 13:06:56 ERROR - # TBPL SUCCESS # 13:06:56 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 13:06:56 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 13:06:56 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 13:06:56 INFO - 2017-01-15 13:06:56.778 screenresolution[1963:23396] starting screenresolution argv=screenresolution get 13:06:56 INFO - 2017-01-15 13:06:56.781 screenresolution[1963:23396] Display 0: 1600x1200x32@60 13:06:56 INFO - 2017-01-15 13:06:56.787 screenresolution[1964:23399] starting screenresolution argv=screenresolution list 13:06:56 INFO - Available Modes on Display 0 13:06:56 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 13:06:56 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 13:06:56 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 13:06:56 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 13:06:57 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 13:06:57 INFO - Intel Iris: 13:06:57 INFO - Chipset Model: Intel Iris 13:06:57 INFO - Type: GPU 13:06:57 INFO - Bus: Built-In 13:06:57 INFO - VRAM (Dynamic, Max): 1536 MB 13:06:57 INFO - Vendor: Intel (0x8086) 13:06:57 INFO - Device ID: 0x0a2e 13:06:57 INFO - Revision ID: 0x0009 13:06:57 INFO - Displays: 13:06:57 INFO - Display: 13:06:57 INFO - Resolution: 1600 x 1200 @ 60 Hz 13:06:57 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 13:06:57 INFO - Display Serial Number: 1600x1200 60 13:06:57 INFO - Main Display: Yes 13:06:57 INFO - Mirror: Off 13:06:57 INFO - Online: Yes 13:06:57 INFO - Rotation: Supported 13:06:57 INFO - Return code: 0 13:06:57 INFO - Running post-action listener: _resource_record_post_action 13:06:57 INFO - [mozharness: 2017-01-15 21:06:57.029011Z] Finished run-tests step (success) 13:06:57 INFO - Running post-run listener: _resource_record_post_run 13:06:57 INFO - Total resource usage - Wall time: 1199s; CPU: 54.0%; Read bytes: 89626624; Write bytes: 573995520; Read time: 1480; Write time: 1891 13:06:57 INFO - TinderboxPrint: CPU usage
54.5% 13:06:57 INFO - TinderboxPrint: I/O read bytes / time
89,626,624 / 1,480 13:06:57 INFO - TinderboxPrint: I/O write bytes / time
573,995,520 / 1,891 13:06:57 INFO - TinderboxPrint: CPU idle
2,184.2 (45.5%) 13:06:57 INFO - TinderboxPrint: CPU system
969.1 (20.2%) 13:06:57 INFO - TinderboxPrint: CPU user
1,644.4 (34.3%) 13:06:57 INFO - TinderboxPrint: Swap in / out
252,370,944 / 0 13:06:57 INFO - install - Wall time: 23s; CPU: 54.0%; Read bytes: 243079680; Write bytes: 237037568; Read time: 17753; Write time: 456 13:06:57 INFO - run-tests - Wall time: 1178s; CPU: 54.0%; Read bytes: 89626624; Write bytes: 319455744; Read time: 1480; Write time: 1394 13:06:57 INFO - Running post-run listener: _upload_blobber_files 13:06:57 INFO - Blob upload gear active. 13:06:57 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 13:06:57 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 13:06:57 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'] 13:06:57 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 13:06:57 INFO - (blobuploader) - INFO - Open directory for files ... 13:06:57 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 13:06:57 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:57 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:06:59 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 13:06:59 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:06:59 INFO - (blobuploader) - INFO - Done attempting. 13:06:59 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 13:06:59 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:06:59 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:00 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 13:07:00 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:00 INFO - (blobuploader) - INFO - Done attempting. 13:07:00 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 13:07:00 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:00 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:01 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 13:07:01 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:01 INFO - (blobuploader) - INFO - Done attempting. 13:07:01 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 13:07:01 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 13:07:01 INFO - (blobuploader) - INFO - Uploading, attempt #1. 13:07:03 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 13:07:03 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 13:07:03 INFO - (blobuploader) - INFO - Done attempting. 13:07:03 INFO - (blobuploader) - INFO - Iteration through files over. 13:07:03 INFO - Return code: 0 13:07:03 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 13:07:03 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 13:07:03 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29cf938da5da2618772db9cde3d3d5943e56b9d3d19ad9a96a556600c53bf07d7f6116bc94367a1f33b0eed549231aecb3c9a5333eb4f86a4e2aab86591e815a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/316ba56df332e655fa5b3e6fb2a1afcf889c338aeab5885c2f9da6271adbdfb2c82ef7b85756109a6c613d9154509c95edf15f58f3f20daa2c76c1991589b272", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d622e911983eaa59ad194b17bd040cb6d08ea0d9c250e7edb419ca93a7ce258ed534d2fc89d91b69b29035de9776f2e84220ca6607b88ae73a804127ab5e336d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb84d2c28019ebc678af90541e6aee3bd4f3be5066d1044b8d7f6bf981f4febb70b4987c869002ccd462e2ba5d5cc97c7a2c241305e3d1a72b145a7b812a6a67"} 13:07:03 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 13:07:03 INFO - Writing to file /builds/slave/test/properties/blobber_files 13:07:03 INFO - Contents: 13:07:03 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/29cf938da5da2618772db9cde3d3d5943e56b9d3d19ad9a96a556600c53bf07d7f6116bc94367a1f33b0eed549231aecb3c9a5333eb4f86a4e2aab86591e815a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/316ba56df332e655fa5b3e6fb2a1afcf889c338aeab5885c2f9da6271adbdfb2c82ef7b85756109a6c613d9154509c95edf15f58f3f20daa2c76c1991589b272", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d622e911983eaa59ad194b17bd040cb6d08ea0d9c250e7edb419ca93a7ce258ed534d2fc89d91b69b29035de9776f2e84220ca6607b88ae73a804127ab5e336d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb84d2c28019ebc678af90541e6aee3bd4f3be5066d1044b8d7f6bf981f4febb70b4987c869002ccd462e2ba5d5cc97c7a2c241305e3d1a72b145a7b812a6a67"} 13:07:03 INFO - Running post-run listener: copy_logs_to_upload_dir 13:07:03 INFO - Copying logs to upload dir... 13:07:03 INFO - mkdir: /builds/slave/test/build/upload/logs 13:07:03 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1260.741416 ========= master_lag: 0.07 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 21 mins, 0 secs) (at 2017-01-15 13:07:03.567626) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-15 13:07:03.570793) ========= 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.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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/29cf938da5da2618772db9cde3d3d5943e56b9d3d19ad9a96a556600c53bf07d7f6116bc94367a1f33b0eed549231aecb3c9a5333eb4f86a4e2aab86591e815a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/316ba56df332e655fa5b3e6fb2a1afcf889c338aeab5885c2f9da6271adbdfb2c82ef7b85756109a6c613d9154509c95edf15f58f3f20daa2c76c1991589b272", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d622e911983eaa59ad194b17bd040cb6d08ea0d9c250e7edb419ca93a7ce258ed534d2fc89d91b69b29035de9776f2e84220ca6607b88ae73a804127ab5e336d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb84d2c28019ebc678af90541e6aee3bd4f3be5066d1044b8d7f6bf981f4febb70b4987c869002ccd462e2ba5d5cc97c7a2c241305e3d1a72b145a7b812a6a67"} build_url:https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/artifacts/public/build/firefox-53.0a1.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.011358 build_url: 'https://queue.taskcluster.net/v1/task/H5qp3w0BSnGEctIVfL1NRw/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/29cf938da5da2618772db9cde3d3d5943e56b9d3d19ad9a96a556600c53bf07d7f6116bc94367a1f33b0eed549231aecb3c9a5333eb4f86a4e2aab86591e815a", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/316ba56df332e655fa5b3e6fb2a1afcf889c338aeab5885c2f9da6271adbdfb2c82ef7b85756109a6c613d9154509c95edf15f58f3f20daa2c76c1991589b272", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/d622e911983eaa59ad194b17bd040cb6d08ea0d9c250e7edb419ca93a7ce258ed534d2fc89d91b69b29035de9776f2e84220ca6607b88ae73a804127ab5e336d", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/bb84d2c28019ebc678af90541e6aee3bd4f3be5066d1044b8d7f6bf981f4febb70b4987c869002ccd462e2ba5d5cc97c7a2c241305e3d1a72b145a7b812a6a67"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-01-15 13:07:03.605071) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 13:07:03.605411) ========= 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.EICaXUUvJH/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.hokW0lq4Rr/Listeners TMPDIR=/var/folders/mc/k6pkwgw92536d85s37s4xsc800000w/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.005146 ========= master_lag: 0.05 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-01-15 13:07:03.656339) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-15 13:07:03.656899) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-01-15 13:07:03.657493) ========= ========= Total master_lag: 0.24 =========