builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0319 starttime: 1490099236.05 results: success (0) buildid: 20170321035228 builduid: 5783d43586334719889058c6e58331a7 revision: d1a93263a03d227c390c02791f295fd5dd7d9a4c ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.054724) ========= master: http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.055148) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.055435) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.081580) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.081910) ========= 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.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-03-21 05:27:16-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 176M=0s 2017-03-21 05:27:16 (176 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.147367 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.253156) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.253485) ========= 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.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.025409 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:16.301960) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:27:16.302317) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a93263a03d227c390c02791f295fd5dd7d9a4c --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', u'python archiver_client.py mozharness --repo releases/mozilla-beta --rev d1a93263a03d227c390c02791f295fd5dd7d9a4c --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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-03-21 05:27:16,406 truncating revision to first 12 chars 2017-03-21 05:27:16,406 Setting DEBUG logging. 2017-03-21 05:27:16,406 attempt 1/10 2017-03-21 05:27:16,406 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/d1a93263a03d?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-21 05:27:17,265 unpacking tar archive at: mozilla-beta-d1a93263a03d/testing/mozharness/ program finished with exit code 0 elapsedTime=1.130321 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-21 05:27:17.456590) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:17.456910) ========= script_repo_revision: d1a93263a03d227c390c02791f295fd5dd7d9a4c ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:17.457317) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:17.457581) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-21 05:27:17.477096) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-21 05:27:17.477415) ========= /tools/buildbot/bin/python -u scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', '-u', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs-e10s', '--add-option', '--webServer,localhost', '--branch-name', 'Mozilla-Beta', '--cfg', 'talos/mac_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Mozilla-Beta'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:17 INFO - MultiFileLogger online at 20170321 05:27:17 in /builds/slave/test 05:27:17 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Mozilla-Beta --cfg talos/mac_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Mozilla-Beta 05:27:17 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 05:27:17 INFO - {'append_to_log': False, 05:27:17 INFO - 'base_work_dir': '/builds/slave/test', 05:27:17 INFO - 'blob_upload_branch': 'Mozilla-Beta', 05:27:17 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 05:27:17 INFO - 'branch': 'Mozilla-Beta', 05:27:17 INFO - 'buildbot_json_path': 'buildprops.json', 05:27:17 INFO - 'config_files': ('talos/mac_config.py',), 05:27:17 INFO - 'default_actions': ('clobber', 05:27:17 INFO - 'read-buildbot-config', 05:27:17 INFO - 'download-and-extract', 05:27:17 INFO - 'populate-webroot', 05:27:17 INFO - 'create-virtualenv', 05:27:17 INFO - 'install', 05:27:17 INFO - 'run-tests'), 05:27:17 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 05:27:17 INFO - 'download_minidump_stackwalk': True, 05:27:17 INFO - 'download_symbols': 'ondemand', 05:27:17 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 05:27:17 INFO - 'tooltool.py': '/tools/tooltool.py', 05:27:17 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 05:27:17 INFO - '/tools/misc-python/virtualenv.py')}, 05:27:17 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 05:27:17 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 05:27:17 INFO - 'installer_path': 'installer.exe', 05:27:17 INFO - 'log_level': 'info', 05:27:17 INFO - 'log_name': 'talos', 05:27:17 INFO - 'log_to_console': True, 05:27:17 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 05:27:17 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 05:27:17 INFO - 'opt_config_files': (), 05:27:17 INFO - 'pip_index': False, 05:27:17 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:17 INFO - 'cmd': ('bash', 05:27:17 INFO - '-c', 05:27:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:17 INFO - 'enabled': True, 05:27:17 INFO - 'halt_on_failure': False, 05:27:17 INFO - 'name': 'check_screen_resolution'},), 05:27:17 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 05:27:17 INFO - 'cmd': ('bash', 05:27:17 INFO - '-c', 05:27:17 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 05:27:17 INFO - 'enabled': True, 05:27:17 INFO - 'halt_on_failure': False, 05:27:17 INFO - 'name': 'check_screen_resolution'},), 05:27:17 INFO - 'run_cmd_checks_enabled': True, 05:27:17 INFO - 'sps_profile': False, 05:27:17 INFO - 'sps_profile_interval': 0, 05:27:17 INFO - 'suite': 'dromaeojs-e10s', 05:27:17 INFO - 'system_bits': '32', 05:27:17 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 05:27:17 INFO - 'title': 't-yosemite-r7-0319', 05:27:17 INFO - 'tooltool_cache': '/builds/tooltool_cache', 05:27:17 INFO - 'use_talos_json': True, 05:27:17 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 05:27:17 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 05:27:17 INFO - 'work_dir': 'build'} 05:27:17 INFO - [mozharness: 2017-03-21 12:27:17.687681Z] Running clobber step. 05:27:17 INFO - Running pre-action listener: _resource_record_pre_action 05:27:17 INFO - Running main action method: clobber 05:27:17 INFO - rmtree: /builds/slave/test/build 05:27:17 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 05:27:19 INFO - Running post-action listener: _resource_record_post_action 05:27:19 INFO - [mozharness: 2017-03-21 12:27:19.996478Z] Finished clobber step (success) 05:27:19 INFO - [mozharness: 2017-03-21 12:27:19.996610Z] Running read-buildbot-config step. 05:27:19 INFO - Running pre-action listener: _resource_record_pre_action 05:27:19 INFO - Running main action method: read_buildbot_config 05:27:19 INFO - Using buildbot properties: 05:27:19 INFO - { 05:27:19 INFO - "project": "", 05:27:19 INFO - "product": "firefox", 05:27:19 INFO - "script_repo_revision": "production", 05:27:19 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 05:27:19 INFO - "repository": "", 05:27:19 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 05:27:19 INFO - "buildid": "20170321035228", 05:27:19 INFO - "pgo_build": "False", 05:27:19 INFO - "basedir": "/builds/slave/test", 05:27:19 INFO - "buildnumber": 32, 05:27:19 INFO - "slavename": "t-yosemite-r7-0319", 05:27:19 INFO - "master": "http://buildbot-master136.bb.releng.scl3.mozilla.com:8201/", 05:27:19 INFO - "platform": "yosemite_r7", 05:27:19 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 05:27:19 INFO - "branch": "mozilla-beta", 05:27:19 INFO - "repo_path": "releases/mozilla-beta", 05:27:19 INFO - "revision": "d1a93263a03d227c390c02791f295fd5dd7d9a4c", 05:27:19 INFO - "stage_platform": "macosx64", 05:27:19 INFO - "builduid": "5783d43586334719889058c6e58331a7", 05:27:19 INFO - "slavebuilddir": "test" 05:27:19 INFO - } 05:27:19 INFO - Found installer url https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 05:27:19 INFO - Running post-action listener: _resource_record_post_action 05:27:19 INFO - [mozharness: 2017-03-21 12:27:19.999884Z] Finished read-buildbot-config step (success) 05:27:20 INFO - [mozharness: 2017-03-21 12:27:19.999992Z] Running download-and-extract step. 05:27:20 INFO - Running pre-action listener: _resource_record_pre_action 05:27:20 INFO - Running main action method: download_and_extract 05:27:20 INFO - mkdir: /builds/slave/test/build/tests 05:27:20 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')]} 05:27:20 INFO - trying https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 05:27:20 INFO - Downloading https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json to /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:27:20 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json'}, attempt #1 05:27:21 INFO - Downloaded 1165 bytes. 05:27:21 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 05:27:21 INFO - Using the following test package requirements: 05:27:21 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:27:21 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 05:27:21 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 05:27:21 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 05:27:21 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 05:27:21 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 05:27:21 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 05:27:21 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 05:27:21 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 05:27:21 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 05:27:21 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 05:27:21 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 05:27:21 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:27:21 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:27:21 INFO - Fetch https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:27:21 INFO - Expected file size: 16108238 05:27:21 INFO - Obtained file size: 16108238 05:27:28 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip', u'firefox-53.0.en-US.mac.talos.tests.zip'] for test suite category: talos 05:27:28 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 05:27:28 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 05:27:28 INFO - Fetch https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 05:27:29 INFO - Expected file size: 16108238 05:27:29 INFO - Obtained file size: 16108238 05:27:35 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 05:27:35 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 05:27:35 INFO - Fetch https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 05:27:37 INFO - Expected file size: 14403663 05:27:37 INFO - Obtained file size: 14403663 05:27: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')]} 05:27:37 INFO - trying https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:27:37 INFO - Downloading https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 05:27:37 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg', 'file_name': '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg'}, attempt #1 05:27:39 INFO - Downloaded 58736516 bytes. 05:27:39 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:27:39 INFO - mkdir: /builds/slave/test/properties 05:27:39 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 05:27:39 INFO - Writing to file /builds/slave/test/properties/build_url 05:27:39 INFO - Contents: 05:27:39 INFO - build_url:https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg 05:27:39 INFO - Running post-action listener: _resource_record_post_action 05:27:39 INFO - Running post-action listener: set_extra_try_arguments 05:27:39 INFO - [mozharness: 2017-03-21 12:27:39.862115Z] Finished download-and-extract step (success) 05:27:39 INFO - [mozharness: 2017-03-21 12:27:39.862268Z] Running populate-webroot step. 05:27:39 INFO - Running pre-action listener: _resource_record_pre_action 05:27:39 INFO - Running main action method: populate_webroot 05:27:39 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 05:27:39 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 05:27:39 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 05:27:39 INFO - '--tppagecycles', 05:27:39 INFO - '7'], 05:27:39 INFO - 'tests': ['tsvgm']}}, 05:27:39 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['tresize', 'tcanvasmark']}, 05:27:39 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 05:27:39 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['dromaeo_css', 'kraken']}, 05:27:39 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 05:27:39 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:27:39 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 05:27:39 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['damp', 'tps']}, 05:27:39 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'tests': ['damp', 'tps']}, 05:27:39 INFO - 'g3': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['dromaeo_dom']}, 05:27:39 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 05:27:39 INFO - 'g4': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['basic_compositor_video']}, 05:27:39 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 05:27:39 INFO - 'other': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'other-e10s': {'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'other-e10s_l64': {'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['a11yr', 05:27:39 INFO - 'ts_paint', 05:27:39 INFO - 'tpaint', 05:27:39 INFO - 'sessionrestore', 05:27:39 INFO - 'sessionrestore_no_auto_restore', 05:27:39 INFO - 'tabpaint']}, 05:27:39 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['tsvgx', 05:27:39 INFO - 'tsvgr_opacity', 05:27:39 INFO - 'tart', 05:27:39 INFO - 'tscrollx', 05:27:39 INFO - 'cart', 05:27:39 INFO - 'tsvg_static']}, 05:27:39 INFO - 'svgr-e10s': {'tests': ['tsvgx', 05:27:39 INFO - 'tsvgr_opacity', 05:27:39 INFO - 'tart', 05:27:39 INFO - 'tscrollx', 05:27:39 INFO - 'cart', 05:27:39 INFO - 'tsvg_static']}, 05:27:39 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'talos_options': ['--disable-e10s'], 05:27:39 INFO - 'tests': ['tp5o']}, 05:27:39 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 05:27:39 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'talos_options': ['--disable-e10s', 05:27:39 INFO - '--xperf_path', 05:27:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:27:39 INFO - 'tests': ['tp5n']}, 05:27:39 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 05:27:39 INFO - 'talos_options': ['--xperf_path', 05:27:39 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 05:27:39 INFO - 'tests': ['tp5n']}}, 05:27:39 INFO - 'talos.zip': {'path': '', 05:27:39 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 05:27:39 INFO - Running post-action listener: _resource_record_post_action 05:27:39 INFO - [mozharness: 2017-03-21 12:27:39.881149Z] Finished populate-webroot step (success) 05:27:39 INFO - [mozharness: 2017-03-21 12:27:39.881260Z] Running create-virtualenv step. 05:27:39 INFO - Running pre-action listener: _resource_record_pre_action 05:27:39 INFO - Running main action method: create_virtualenv 05:27:39 INFO - Creating virtualenv /builds/slave/test/build/venv 05:27:39 INFO - Running command: ['/tools/buildbot/bin/python', '/tools/misc-python/virtualenv.py', '--no-site-packages', '--distribute', '/builds/slave/test/build/venv'] in /builds/slave/test/build 05:27:39 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 05:27:40 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 05:27:40 INFO - Using real prefix '/tools/python27' 05:27:40 INFO - New python executable in /builds/slave/test/build/venv/bin/python 05:27:40 INFO - Installing distribute.............................................................................................................................................................................................done. 05:27:43 INFO - Installing pip.................done. 05:27:43 INFO - Return code: 0 05:27:43 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 05:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 05:27:43 INFO - Reading from file tmpfile_stdout 05:27:43 INFO - Output received: 05:27:43 INFO - pip 1.5.5 from /builds/slave/test/build/venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 05:27:43 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 05:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:43 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:43 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:43 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:43 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:43 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:43 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'pip>=1.5'] in /builds/slave/test/build 05:27:43 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub pip>=1.5 05:27:43 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:43 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:43 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:43 INFO - 'HOME': '/Users/cltbld', 05:27:43 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:43 INFO - 'LOGNAME': 'cltbld', 05:27:43 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:43 INFO - 'MOZ_NO_REMOTE': '1', 05:27:43 INFO - 'NO_EM_RESTART': '1', 05:27:43 INFO - 'PAGER': '/bin/cat', 05:27:43 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:43 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:43 INFO - 'PWD': '/builds/slave/test', 05:27:43 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:43 INFO - 'SHELL': '/bin/bash', 05:27:43 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:43 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:43 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:43 INFO - 'USER': 'cltbld', 05:27:43 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:43 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:43 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:43 INFO - 'XPC_FLAGS': '0x0', 05:27:43 INFO - 'XPC_SERVICE_NAME': '0', 05:27:43 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:44 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 05:27:44 INFO - Cleaning up... 05:27:44 INFO - Return code: 0 05:27:44 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 05:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:44 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:44 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:44 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:44 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:44 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:44 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:44 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'psutil>=3.1.1'] in /builds/slave/test/build 05:27:44 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub psutil>=3.1.1 05:27:44 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:44 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:44 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:44 INFO - 'HOME': '/Users/cltbld', 05:27:44 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:44 INFO - 'LOGNAME': 'cltbld', 05:27:44 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:44 INFO - 'MOZ_NO_REMOTE': '1', 05:27:44 INFO - 'NO_EM_RESTART': '1', 05:27:44 INFO - 'PAGER': '/bin/cat', 05:27:44 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:44 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:44 INFO - 'PWD': '/builds/slave/test', 05:27:44 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:44 INFO - 'SHELL': '/bin/bash', 05:27:44 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:44 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:44 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:44 INFO - 'USER': 'cltbld', 05:27:44 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:44 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:44 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:44 INFO - 'XPC_FLAGS': '0x0', 05:27:44 INFO - 'XPC_SERVICE_NAME': '0', 05:27:44 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:44 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:44 INFO - Downloading/unpacking psutil>=3.1.1 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:47 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 05:27:47 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:47 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:47 INFO - Installing collected packages: psutil 05:27:47 INFO - Running setup.py install for psutil 05:27:47 INFO - building 'psutil._psutil_osx' extension 05:27:47 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 05:27:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/_psutil_common.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o 05:27:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -DPSUTIL_VERSION=311 -I/tools/python27/include/python2.7 -c psutil/arch/osx/process_info.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o 05:27:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_common.o build/temp.macosx-10.10-x86_64-2.7/psutil/arch/osx/process_info.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_osx.so -framework CoreFoundation -framework IOKit 05:27:48 INFO - building 'psutil._psutil_posix' extension 05:27:48 INFO - gcc -fno-strict-aliasing -g -O2 -DNDEBUG -g -fwrapv -O3 -Wall -Wstrict-prototypes -I/tools/python27/include/python2.7 -c psutil/_psutil_posix.c -o build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o 05:27:48 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 05:27:48 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 05:27:48 INFO - ^ 05:27:48 INFO - 1 warning generated. 05:27:48 INFO - gcc -bundle -bundle_loader /tools/python27/bin/python2.7 build/temp.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.o -o build/lib.macosx-10.10-x86_64-2.7/psutil/_psutil_posix.so 05:27:48 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 05:27:48 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 05:27:49 INFO - Successfully installed psutil 05:27:49 INFO - Cleaning up... 05:27:49 INFO - Return code: 0 05:27:49 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 05:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:49 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:49 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:49 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:49 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:49 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:49 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 05:27:49 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 05:27:49 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:49 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:49 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:49 INFO - 'HOME': '/Users/cltbld', 05:27:49 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:49 INFO - 'LOGNAME': 'cltbld', 05:27:49 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:49 INFO - 'MOZ_NO_REMOTE': '1', 05:27:49 INFO - 'NO_EM_RESTART': '1', 05:27:49 INFO - 'PAGER': '/bin/cat', 05:27:49 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:49 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:49 INFO - 'PWD': '/builds/slave/test', 05:27:49 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:49 INFO - 'SHELL': '/bin/bash', 05:27:49 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:49 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:49 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:49 INFO - 'USER': 'cltbld', 05:27:49 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:49 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:49 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:49 INFO - 'XPC_FLAGS': '0x0', 05:27:49 INFO - 'XPC_SERVICE_NAME': '0', 05:27:49 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:49 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:49 INFO - Downloading/unpacking mozsystemmonitor==0.3 05:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:49 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:49 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:49 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:49 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:51 INFO - Downloading mozsystemmonitor-0.3.tar.gz 05:27:51 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 05:27:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 05:27:52 INFO - Installing collected packages: mozsystemmonitor 05:27:52 INFO - Running setup.py install for mozsystemmonitor 05:27:52 INFO - Successfully installed mozsystemmonitor 05:27:52 INFO - Cleaning up... 05:27:52 INFO - Return code: 0 05:27:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 05:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:52 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:52 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:52 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 05:27:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 05:27:52 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:52 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:52 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:52 INFO - 'HOME': '/Users/cltbld', 05:27:52 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:52 INFO - 'LOGNAME': 'cltbld', 05:27:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:52 INFO - 'MOZ_NO_REMOTE': '1', 05:27:52 INFO - 'NO_EM_RESTART': '1', 05:27:52 INFO - 'PAGER': '/bin/cat', 05:27:52 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:52 INFO - 'PWD': '/builds/slave/test', 05:27:52 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:52 INFO - 'SHELL': '/bin/bash', 05:27:52 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:52 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:52 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:52 INFO - 'USER': 'cltbld', 05:27:52 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:52 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:52 INFO - 'XPC_FLAGS': '0x0', 05:27:52 INFO - 'XPC_SERVICE_NAME': '0', 05:27:52 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:52 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:52 INFO - Downloading/unpacking jsonschema==2.5.1 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27: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 05:27:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 05:27:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 05:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:55 INFO - Downloading functools32-3.2.3-2.tar.gz 05:27:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 05:27:55 INFO - warning: no files found matching '*.txt' 05:27:55 INFO - no previously-included directories found matching 'build' 05:27:55 INFO - no previously-included directories found matching 'dist' 05:27:55 INFO - no previously-included directories found matching '.git*' 05:27:55 INFO - Installing collected packages: jsonschema, functools32 05:27:55 INFO - Running setup.py install for functools32 05:27:55 INFO - warning: no files found matching '*.txt' 05:27:55 INFO - no previously-included directories found matching 'build' 05:27:55 INFO - no previously-included directories found matching 'dist' 05:27:55 INFO - no previously-included directories found matching '.git*' 05:27:55 INFO - Successfully installed jsonschema functools32 05:27:55 INFO - Cleaning up... 05:27:55 INFO - Return code: 0 05:27:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 05:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:27:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 05:27:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 05:27:55 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:55 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:55 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:55 INFO - 'HOME': '/Users/cltbld', 05:27:55 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:55 INFO - 'LOGNAME': 'cltbld', 05:27:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:55 INFO - 'MOZ_NO_REMOTE': '1', 05:27:55 INFO - 'NO_EM_RESTART': '1', 05:27:55 INFO - 'PAGER': '/bin/cat', 05:27:55 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:55 INFO - 'PWD': '/builds/slave/test', 05:27:55 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:55 INFO - 'SHELL': '/bin/bash', 05:27:55 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:55 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:55 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:55 INFO - 'USER': 'cltbld', 05:27:55 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:55 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:55 INFO - 'XPC_FLAGS': '0x0', 05:27:55 INFO - 'XPC_SERVICE_NAME': '0', 05:27:55 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:56 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 05:27:56 INFO - Cleaning up... 05:27:56 INFO - Return code: 0 05:27:56 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 05:27: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')]} 05:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:27:56 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27: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')]} 05:27:56 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:27:56 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:27:56 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:27:56 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 05:27:56 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 05:27:56 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:27:56 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:27:56 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:27:56 INFO - 'HOME': '/Users/cltbld', 05:27:56 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:27:56 INFO - 'LOGNAME': 'cltbld', 05:27:56 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:27:56 INFO - 'MOZ_NO_REMOTE': '1', 05:27:56 INFO - 'NO_EM_RESTART': '1', 05:27:56 INFO - 'PAGER': '/bin/cat', 05:27:56 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:27:56 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:27:56 INFO - 'PWD': '/builds/slave/test', 05:27:56 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:27:56 INFO - 'SHELL': '/bin/bash', 05:27:56 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:27:56 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:27:56 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:27:56 INFO - 'USER': 'cltbld', 05:27:56 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:27:56 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:27:56 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:27:56 INFO - 'XPC_FLAGS': '0x0', 05:27:56 INFO - 'XPC_SERVICE_NAME': '0', 05:27:56 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:27:56 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:27:56 INFO - Downloading/unpacking blobuploader==1.2.4 05:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:59 INFO - Downloading blobuploader-1.2.4.tar.gz 05:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 05:27:59 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:59 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 05:27:59 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 05:27:59 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 05:27:59 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 05:28:00 INFO - Downloading docopt-0.6.1.tar.gz 05:28:00 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 05:28:00 INFO - Installing collected packages: blobuploader, requests, docopt 05:28:00 INFO - Running setup.py install for blobuploader 05:28:00 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 05:28:00 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 05:28:00 INFO - Running setup.py install for requests 05:28:01 INFO - Running setup.py install for docopt 05:28:01 INFO - Successfully installed blobuploader requests docopt 05:28:01 INFO - Cleaning up... 05:28:01 INFO - Return code: 0 05:28:01 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:28:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--no-deps', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:28:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --no-deps --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:28:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:28:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:01 INFO - 'HOME': '/Users/cltbld', 05:28:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:01 INFO - 'LOGNAME': 'cltbld', 05:28:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:01 INFO - 'MOZ_NO_REMOTE': '1', 05:28:01 INFO - 'NO_EM_RESTART': '1', 05:28:01 INFO - 'PAGER': '/bin/cat', 05:28:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:01 INFO - 'PWD': '/builds/slave/test', 05:28:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:01 INFO - 'SHELL': '/bin/bash', 05:28:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:28:01 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:28:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:01 INFO - 'USER': 'cltbld', 05:28:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:01 INFO - 'XPC_FLAGS': '0x0', 05:28:01 INFO - 'XPC_SERVICE_NAME': '0', 05:28:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:01 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-h16gs8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:01 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-wE9IQb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:01 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-vu0GFt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:01 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:01 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-KXIqRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9E6_mG-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-t1SIry-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-SpbPIH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-GVWmzT-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-X4GAbf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-_tmRr8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-h6ePlg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-pc493r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-XmlRH4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:02 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-2xWOup-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:03 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-9CYECo-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:03 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-QktrNK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:03 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-wHXlAv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:03 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 05:28:03 INFO - Running setup.py install for manifestparser 05:28:03 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 05:28:03 INFO - Running setup.py install for mozcrash 05:28:03 INFO - Running setup.py install for mozdebug 05:28:03 INFO - Running setup.py install for mozdevice 05:28:03 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 05:28:03 INFO - Installing dm script to /builds/slave/test/build/venv/bin 05:28:03 INFO - Running setup.py install for mozfile 05:28:04 INFO - Running setup.py install for mozhttpd 05:28:04 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Running setup.py install for mozinfo 05:28:04 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Running setup.py install for mozInstall 05:28:04 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Running setup.py install for mozleak 05:28:04 INFO - Running setup.py install for mozlog 05:28:04 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 05:28:04 INFO - Running setup.py install for moznetwork 05:28:05 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Running setup.py install for mozprocess 05:28:05 INFO - Running setup.py install for mozprofile 05:28:05 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Running setup.py install for mozrunner 05:28:05 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Running setup.py install for mozscreenshot 05:28:05 INFO - Running setup.py install for moztest 05:28:05 INFO - Running setup.py install for mozversion 05:28:05 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 05:28:05 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 05:28:05 INFO - Cleaning up... 05:28:05 INFO - Return code: 0 05:28:05 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:05 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:05 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:28:05 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '-r', '/builds/slave/test/build/tests/config/mozbase_requirements.txt', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub'] in /builds/slave/test/build/tests/config 05:28:05 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 -r /builds/slave/test/build/tests/config/mozbase_requirements.txt --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub 05:28:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:28:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:05 INFO - 'HOME': '/Users/cltbld', 05:28:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:05 INFO - 'LOGNAME': 'cltbld', 05:28:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:05 INFO - 'MOZ_NO_REMOTE': '1', 05:28:05 INFO - 'NO_EM_RESTART': '1', 05:28:05 INFO - 'PAGER': '/bin/cat', 05:28:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:05 INFO - 'PWD': '/builds/slave/test', 05:28:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:05 INFO - 'SHELL': '/bin/bash', 05:28:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:28:05 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:28:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:05 INFO - 'USER': 'cltbld', 05:28:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:05 INFO - 'XPC_FLAGS': '0x0', 05:28:05 INFO - 'XPC_SERVICE_NAME': '0', 05:28:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-8LGjax-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 05:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-I8_h6m-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 05:28:06 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)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-HrUVb6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 05:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-xNt7jn-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 05:28:06 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)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-fgKvHg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 05:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-tiNW5v-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 05:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 05:28:06 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-bzXRNs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 05:28:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 05:28:06 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-_8qjaz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 05:28:07 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)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-jeVEz8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 05:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-4peo6E-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 05:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog==3.4 from file:///builds/slave/test/build/tests/mozbase/mozlog in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-JSPjfY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 05:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-fs81bi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 05:28:07 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)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-aYdNCc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 05:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile==0.28 from file:///builds/slave/test/build/tests/mozbase/mozprofile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 13)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-Qrneno-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 05:28:07 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)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-FFh94g-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 05:28:07 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-sCXkSY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 05:28:07 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)) 05:28:07 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 05:28:07 INFO - Running setup.py (path:/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/pip-hiMJ_X-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 05:28:08 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)) 05:28:08 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)) 05:28:08 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)) 05:28:08 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)) 05:28:08 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)) 05:28:08 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)) 05:28:08 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:08 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 05:28:11 INFO - Downloading blessings-1.6.tar.gz 05:28:11 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 05:28:11 INFO - Installing collected packages: blessings 05:28:11 INFO - Running setup.py install for blessings 05:28:11 INFO - Successfully installed blessings 05:28:11 INFO - Cleaning up... 05:28:11 INFO - Return code: 0 05:28:11 INFO - Done creating virtualenv /builds/slave/test/build/venv. 05:28:11 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:11 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:11 INFO - Reading from file tmpfile_stdout 05:28:11 INFO - Current package versions: 05:28:11 INFO - blessings == 1.6 05:28:11 INFO - blobuploader == 1.2.4 05:28:11 INFO - docopt == 0.6.1 05:28:11 INFO - functools32 == 3.2.3-2 05:28:11 INFO - jsonschema == 2.5.1 05:28:11 INFO - manifestparser == 1.1 05:28:11 INFO - mozInstall == 1.12 05:28:11 INFO - mozcrash == 1.0 05:28:11 INFO - mozdebug == 0.1 05:28:11 INFO - mozdevice == 0.49 05:28:11 INFO - mozfile == 1.2 05:28:11 INFO - mozhttpd == 0.7 05:28:11 INFO - mozinfo == 0.9 05:28:11 INFO - mozleak == 0.1 05:28:11 INFO - mozlog == 3.4 05:28:11 INFO - moznetwork == 0.27 05:28:11 INFO - mozprocess == 0.24 05:28:11 INFO - mozprofile == 0.28 05:28:11 INFO - mozrunner == 6.13 05:28:11 INFO - mozscreenshot == 0.1 05:28:11 INFO - mozsystemmonitor == 0.3 05:28:11 INFO - moztest == 0.8 05:28:11 INFO - mozversion == 1.4 05:28:11 INFO - psutil == 3.1.1 05:28:11 INFO - requests == 1.2.3 05:28:11 INFO - wsgiref == 0.1.2 05:28:11 INFO - Installing None into virtualenv /builds/slave/test/build/venv 05:28:11 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 05:28:11 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:12 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')]} 05:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:12 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 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:28:12 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 05:28:12 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 05:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:12 INFO - 'HOME': '/Users/cltbld', 05:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:12 INFO - 'LOGNAME': 'cltbld', 05:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:12 INFO - 'MOZ_NO_REMOTE': '1', 05:28:12 INFO - 'NO_EM_RESTART': '1', 05:28:12 INFO - 'PAGER': '/bin/cat', 05:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:12 INFO - 'PWD': '/builds/slave/test', 05:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:12 INFO - 'SHELL': '/bin/bash', 05:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:28:12 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:12 INFO - 'USER': 'cltbld', 05:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:12 INFO - 'XPC_FLAGS': '0x0', 05:28:12 INFO - 'XPC_SERVICE_NAME': '0', 05:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 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)) 05:28:12 INFO - Cleaning up... 05:28:12 INFO - Return code: 0 05:28:12 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 05:28:12 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')]} 05:28:12 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 05:28:12 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:12 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')]} 05:28:12 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 05:28:12 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 05:28:12 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 0x102f32b70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102f35768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fd5615a4660>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, '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.0PXMFxzfPU/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.AGXttl26qH/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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 05:28:12 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 05:28:12 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 05:28:12 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:28:12 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:12 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:12 INFO - 'HOME': '/Users/cltbld', 05:28:12 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:12 INFO - 'LOGNAME': 'cltbld', 05:28:12 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:12 INFO - 'MOZ_NO_REMOTE': '1', 05:28:12 INFO - 'NO_EM_RESTART': '1', 05:28:12 INFO - 'PAGER': '/bin/cat', 05:28:12 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:12 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:12 INFO - 'PWD': '/builds/slave/test', 05:28:12 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:12 INFO - 'SHELL': '/bin/bash', 05:28:12 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:28:12 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:28:12 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:12 INFO - 'USER': 'cltbld', 05:28:12 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:12 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:12 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:12 INFO - 'XPC_FLAGS': '0x0', 05:28:12 INFO - 'XPC_SERVICE_NAME': '0', 05:28:12 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:12 INFO - Ignoring indexes: https://pypi.python.org/simple/ 05:28:12 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 05:28:12 INFO - Cleaning up... 05:28:12 INFO - Return code: 0 05:28:12 INFO - Running post-action listener: _resource_record_post_action 05:28:12 INFO - Running post-action listener: _start_resource_monitoring 05:28:12 INFO - Starting resource monitoring. 05:28:12 INFO - [mozharness: 2017-03-21 12:28:12.685533Z] Finished create-virtualenv step (success) 05:28:12 INFO - [mozharness: 2017-03-21 12:28:12.686509Z] Running install step. 05:28:12 INFO - Running pre-action listener: _resource_record_pre_action 05:28:12 INFO - Running main action method: install 05:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 05:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 05:28:12 INFO - Reading from file tmpfile_stdout 05:28:12 INFO - Detecting whether we're running mozinstall >=1.0... 05:28:12 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 05:28:12 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 05:28:13 INFO - Reading from file tmpfile_stdout 05:28:13 INFO - Output received: 05:28:13 INFO - Usage: mozinstall [options] installer 05:28:13 INFO - Options: 05:28:13 INFO - -h, --help show this help message and exit 05:28:13 INFO - -d DEST, --destination=DEST 05:28:13 INFO - Directory to install application into. [default: 05:28:13 INFO - "/builds/slave/test"] 05:28:13 INFO - --app=APP Application being installed. [default: firefox] 05:28:13 INFO - mkdir: /builds/slave/test/build/application 05:28:13 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/firefox-53.0.en-US.mac.dmg', '--destination', '/builds/slave/test/build/application'] 05:28:13 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/firefox-53.0.en-US.mac.dmg --destination /builds/slave/test/build/application 05:28:27 INFO - Reading from file tmpfile_stdout 05:28:27 INFO - Output received: 05:28:27 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 05:28:27 INFO - Running post-action listener: _resource_record_post_action 05:28:27 INFO - [mozharness: 2017-03-21 12:28:27.649368Z] Finished install step (success) 05:28:27 INFO - [mozharness: 2017-03-21 12:28:27.649525Z] Running run-tests step. 05:28:27 INFO - Running pre-action listener: _resource_record_pre_action 05:28:27 INFO - Running main action method: run_tests 05:28:27 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:28:27 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:28:27 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:28:27 INFO - 2017-03-21 05:28:27.662 screenresolution[1620:7557] starting screenresolution argv=screenresolution get 05:28:27 INFO - 2017-03-21 05:28:27.664 screenresolution[1620:7557] Display 0: 1600x1200x32@60 05:28:27 INFO - 2017-03-21 05:28:27.669 screenresolution[1621:7559] starting screenresolution argv=screenresolution list 05:28:27 INFO - Available Modes on Display 0 05:28:27 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:28:27 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:28:27 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:28:27 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:28:27 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:28:27 INFO - Intel Iris: 05:28:27 INFO - Chipset Model: Intel Iris 05:28:27 INFO - Type: GPU 05:28:27 INFO - Bus: Built-In 05:28:27 INFO - VRAM (Dynamic, Max): 1536 MB 05:28:27 INFO - Vendor: Intel (0x8086) 05:28:27 INFO - Device ID: 0x0a2e 05:28:27 INFO - Revision ID: 0x0009 05:28:27 INFO - Displays: 05:28:27 INFO - Display: 05:28:27 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:28:27 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:28:27 INFO - Display Serial Number: 1600x1200 60 05:28:27 INFO - Main Display: Yes 05:28:27 INFO - Mirror: Off 05:28:27 INFO - Online: Yes 05:28:27 INFO - Rotation: Supported 05:28:27 INFO - Return code: 0 05:28:27 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 05:28:27 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 05:28:27 INFO - Python 2.7.3 05:28:27 INFO - Return code: 0 05:28:27 INFO - grabbing minidump binary from tooltool 05:28:27 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')]} 05:28:27 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 0x102d10d30>, '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 0x102c37db0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x102d05be8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 05:28:27 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 05:28:27 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 05:28:27 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 05:28:27 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 05:28:28 INFO - Return code: 0 05:28:28 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 05:28:28 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 05:28:28 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 05:28:28 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 05:28:28 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 05:28:28 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 05:28:28 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Mozilla-Beta --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox --symbolsPath https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0319 --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 05:28:28 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.0PXMFxzfPU/Render', 05:28:28 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 05:28:28 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 05:28:28 INFO - 'HOME': '/Users/cltbld', 05:28:28 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 05:28:28 INFO - 'LOGNAME': 'cltbld', 05:28:28 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 05:28:28 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 05:28:28 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 05:28:28 INFO - 'MOZ_NO_REMOTE': '1', 05:28:28 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 05:28:28 INFO - 'NO_EM_RESTART': '1', 05:28:28 INFO - 'PAGER': '/bin/cat', 05:28:28 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 05:28:28 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 05:28:28 INFO - 'PWD': '/builds/slave/test', 05:28:28 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 05:28:28 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 05:28:28 INFO - 'SHELL': '/bin/bash', 05:28:28 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.AGXttl26qH/Listeners', 05:28:28 INFO - 'TMPDIR': '/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/', 05:28:28 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 05:28:28 INFO - 'USER': 'cltbld', 05:28:28 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 05:28:28 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 05:28:28 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 05:28:28 INFO - 'XPC_FLAGS': '0x0', 05:28:28 INFO - 'XPC_SERVICE_NAME': '0', 05:28:28 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 05:28:28 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Mozilla-Beta', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0319', '--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 05:28:28 INFO - mozversion application_buildid: 20170321035228 05:28:28 INFO - mozversion application_changeset: d1a93263a03d227c390c02791f295fd5dd7d9a4c 05:28:28 INFO - mozversion application_display_name: Firefox 05:28:28 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 05:28:28 INFO - mozversion application_name: Firefox 05:28:28 INFO - mozversion application_remotingname: firefox 05:28:28 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 05:28:28 INFO - mozversion application_vendor: Mozilla 05:28:28 INFO - mozversion application_version: 53.0 05:28:28 INFO - mozversion platform_buildid: 20170321035228 05:28:28 INFO - mozversion platform_changeset: d1a93263a03d227c390c02791f295fd5dd7d9a4c 05:28:28 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 05:28:28 INFO - mozversion platform_version: 53.0 05:28:28 INFO - using testdate: 1490099308 05:28:28 INFO - actual date: 1490099308 05:28:28 INFO - starting webserver on 'localhost:49244' 05:28:28 INFO - SUITE-START | Running 2 tests 05:28:28 INFO - TEST-START | dromaeo_css 05:28:28 INFO - operating with platform_type : mac_ 05:28:28 INFO - Initialising browser for dromaeo_css test... 05:28:28 INFO - TEST-INFO | started process 1631 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpDVrhjK/profile http://localhost:49244/getInfo.html) 05:28:31 INFO - PROCESS | 1631 | 2017-03-21 05:28:31.169 plugin-container[1634:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a3f, name = 'com.apple.tsm.portname' 05:28:31 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:31 INFO - PROCESS | 1631 | 2017-03-21 05:28:31.172 plugin-container[1634:7731] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x920b, name = 'com.apple.CFPasteboardClient' 05:28:31 INFO - PROCESS | 1631 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:31 INFO - PROCESS | 1631 | 2017-03-21 05:28:31.172 plugin-container[1634:7731] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:31 INFO - PROCESS | 1631 | __metrics Screen width/height:1600/1200 05:28:31 INFO - PROCESS | 1631 | colorDepth:24 05:28:31 INFO - PROCESS | 1631 | Browser inner width/height: 1024/689 05:28:31 INFO - PROCESS | 1631 | __metrics 05:28:31 ERROR - PROCESS | 1631 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:28:31 INFO - TEST-INFO | 1631: exit 0 05:28:31 INFO - Browser initialized. 05:28:31 INFO - Running cycle 1/1 for dromaeo_css test... 05:28:31 INFO - TEST-INFO | started process 1635 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpDVrhjK/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:28:34 INFO - PROCESS | 1635 | 2017-03-21 05:28:34.264 plugin-container[1636:7866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8443, name = 'com.apple.tsm.portname' 05:28:34 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:34 INFO - PROCESS | 1635 | 2017-03-21 05:28:34.265 plugin-container[1636:7866] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b03, name = 'com.apple.CFPasteboardClient' 05:28:34 INFO - PROCESS | 1635 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:28:34 INFO - PROCESS | 1635 | 2017-03-21 05:28:34.265 plugin-container[1636:7866] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:28:43 INFO - PROCESS | 1635 | RSS: Main: 173043712 05:28:43 INFO - PROCESS | 1635 | 05:31:45 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49244/tests/dromaeo/cssquery-ext.html) 05:31:47 INFO - PROCESS | 1635 | RSS: Main: 191979520 05:31:47 INFO - PROCESS | 1635 | 05:34:50 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-ext.html (next: http://localhost:49244/tests/dromaeo/cssquery-jquery.html) 05:34:50 INFO - PROCESS | 1635 | RSS: Main: 202477568 05:34:50 INFO - PROCESS | 1635 | 05:37:52 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49244/tests/dromaeo/cssquery-mootools.html) 05:37:53 INFO - PROCESS | 1635 | RSS: Main: 204836864 05:37:53 INFO - PROCESS | 1635 | 05:40:55 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49244/tests/dromaeo/cssquery-prototype.html) 05:40:55 INFO - PROCESS | 1635 | RSS: Main: 212258816 05:40:55 INFO - PROCESS | 1635 | 05:43:58 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49244/tests/dromaeo/cssquery-yui.html) 05:43:59 INFO - PROCESS | 1635 | RSS: Main: 213364736 05:43:59 INFO - PROCESS | 1635 | 05:47:01 INFO - PROCESS | 1635 | Cycle 1(1): loaded http://localhost:49244/tests/dromaeo/cssquery-yui.html (next: http://localhost:49244/tests/dromaeo/cssquery-dojo.html) 05:47:02 INFO - PROCESS | 1635 | RSS: Main: 214638592 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | __start_tp_report 05:47:02 INFO - PROCESS | 1635 | _x_x_mozilla_page_load 05:47:02 INFO - PROCESS | 1635 | _x_x_mozilla_page_load_details 05:47:02 INFO - PROCESS | 1635 | |i|pagename|runs| 05:47:02 INFO - PROCESS | 1635 | |0;dojo.html;3533.1520435095576;3543.787372757635;3574.374484465218;3574.624664410237;3575.0347406200326;5984.910226346608;6014.428629280212;6032.396760323965;6042.8489287767825;6053.667048312341;5989.4010598940085;6071.392860713926;6075.362086980869;6100.054491553817;6124.693765311739;4559.9772001139945;5345.625806193568;5408.513233808956;5418.024755544;5543.473370029841;5458.91811622824;5474.808381706641;5479.397266300703;5488.7530061147345;5499.560035197194;5189.558887494568;5194.480551944813;5469.179623056533;5470.507654311111;5485.945140548603;4343.261645520253;5133.30700355451;5302.3106996090455;5398.055340315429;5460.890782184374;5253.973730131325;5314.202869569557;5328.8401347959625;5346;5367.382750983647;5854.151148083547;5912.911306330366;5962.642241465483;5996.580239383245;6046.96976515119;6258.9359808832605;6273.654948977804;6288.025356069817;6298.4646305064125;6334.556581039329;6413.102165696806;6421.454176395012;6432.163818703585;6450.580712253735;6455.515836312248;6124.755009799603;6144.262688477412;6152.477039451607;6156.969215153896;6195.380461953859;6145.539084568675;6339;6346.365363463617;6446.677666116675;6475.838104047437;6285.717142728588;6309.558330916883;6326.936730632727;6336.556441049174;6337.493000559944;6442.420182183626;6453.419192272718;6468.191476065492;6473.2232132144445;6473.41739243461;5231.529162375405;6197.5351848610635;6215.316315205324;6231.750729970841;6281.371862813773;6298.08677741734;6317.052442133625;6336.651484168415;6348.206474190726;6359.682015899187;5145.202493613496;5157.355330583602;5175.45657705943;5178.766955487012;5180.274761533388;3698.815059247027;4521.819127234874;4588.747618880993;4614.653900957442;4670.813167473263;6071.3928607139815;6118.939503446732;6134.620279381772;6153.476954458921;6161.7227224775;6468.676566171673;6476.190476190476;6477.578957367756;6481.578697384655;6485.286618471964;5852.209951656557;6058.727357268934;6089.543284253698;6104.114903338991;6130.080487926757;5660.830175094754;5664.660120392789;5671.262735844396;5672.659640421588;5711.714414279352;6607.603543787388;6620.073189753439;6623.172103487064;6663.800085997428;6664.666766661648;5351.250824884597;6219.906701399573;6272.121902933684;6364.87270254583;6480.740770369132;6648.933510664831;6659.2008958925235;6659.600423974577;6676.165479315086;6678;6385.074164246251;6385.840353991188;6387.265464471459;6387.872242555216;6406.199225096863;6242.5942313749465;6326.746930122928;6420.6147631142285;6432.970724684028;6469.450096741642;6253.062040693932;6270.310265870842;6299.842503937438;6323.430891219812;6326.4622507087415;6268.937310626835;6274.780382686584;6291.811245662638;6300.117983482408;6305.2433707953505;4983.72589507566;6118.010103775335;6121.693915304306;6157.96921015392;6217.067439884054;6125.203723515914;6189.597676151036;6255.093011513396;6283.497320214463;6287.654179020198;6216.160818289473;6246.469614944368;6254.687265636609;6268.091126786681;6274.152989346379 05:47:02 INFO - PROCESS | 1635 | |1;ext.html;457.4350676914013;457.55388496216193;483.93224948507213;534.8047962493689;555.0203890133912;31131.533027004614;42356.57643423565;42579.36130958038;42602.573974260245;43133.137337253276;43664.563354366524;44123.77938110313;44420.77789611056;44424.3336349955;44431.77784111075;7891.565963871984;9092.681756138536;9417.340786144972;9678.9837067108;9686.225101991824;11705.3562054087;11980.700482487964;12039.93980030103;12148;12164.452599632994;9841.36031157976;11611.883881161208;11796.528138874477;11816.350100744456;11835.230710003865;11853.348065856375;11897.286162830258;11898.107641926847;11924.151756477673;11946.880531194663;11903.821442678367;11938.985186259179;12012.819807702892;12017.218880772809;12044.457999390048;41717.58282417167;42371.36442953358;42698.93252668677;42816;42837.57162428367;4090.1819636072905;4125.793710314502;4150.377443383486;4259.2759230930815;4400;29728.55407168894;30224.94212702545;30446.238844028856;30457.543136852964;30515;3291.2594666200152;3314.801111933292;3344.464885618314;3459.0131317150667;3502.7548071634997;523472;524155;527598;527641;528715;63329.68335158295;63556.04665930015;63675.68162159252;63736;63872.041919371244;67187.99218011733;67400.65198695999;67423.66288168626;67539.66230168818;67686.32313676899;17608.735868961976;17813.287468501116;17844.929304241527;17857.928524288585;17860.464186074438;12504.3747812609;12759.915407190307;13442.798358024638;13612.251326177155;13746.587602371945;16888.324467021182;16946.067966261973;16972.83027169737;16977.660446790997;16981.40565080241;6997.580145191305;7403.51877127985;7469.813254668677;7478.775636730907;7486.326230639269;887.6759982606302;888.493558671564;896.421807933879;917.7613820406609;928.3594319919234;30940;31324.227146210545;31444.842775786434;31655.683443165733;31906.20234494156;965.0253244223385;1052.6315789473622;1109.7170221593442;1111.111111111108;1215.392303848076;15874.603134921488;15914;16080.115593642462;16152.838471615369;16387.262573184234;643852;644240;650919;651880;653349;62074.689626551575;62286;62408.68795655993;62451.06323405152;62745.68627156926;1462.531989763287;1469.4122351059661;1483.2138966347852;1502.8797696184279;1517.6661134550382;29581.112566623036;30720.539191912587;30895;31305;31372.215694606904;51528.22707659463;51967.2204916919;52016.739916300176;52041;52201;558515;561350;563079;563252;563821;36790.34443449997;37668.62331376651;40957.590424095375;41449;41512;43383.783081084395;43736.78131609321;44371.77814110909;46197.614071577904;46215.53784462247;30507.237319066313;37177;37410.25179496449;37505;37863;36714.44928326024;36723;36751.71369002072;37752.86741397763;38125.046873828374;36647.26705465929;37180.62819371772;37409.06477338088;37500.24999500049;38113.42829857499;25699;29717.66270517875;31317.686823131477;32224.677753222168;32739.854105106206;24531.14141005056;24722.629160562246;24747.133850315153;24994;25577.488450230518 05:47:02 INFO - PROCESS | 1635 | |2;jquery.html;289.74357693441306;393.49828968065697;398.2691760619264;411.2392074661874;424.2427267327819;17649.7352539712;20088.4977875553;21360.931953402305;21701.67447488289;21773.891130544343;12311.507539698423;12487.375631218449;15249.618759531017;21003.789962100374;22230.333090007287;4804.711717296956;4994.901121753227;5609.439056094378;5610;5612.522935550483;6034.215551978258;6104.206453161083;6153.7230824613;6159.846003849895;6179.876402471948;4898.461169271395;4967.751612419383;6031.065184896348;6115.143879856823;6127.846803829918;4833.830815921426;5459.945400545984;5484.945150548483;5497.038018346773;5497.725113744316;4673;5209.114450543417;5259.921101183485;5375.677459352412;5379.112446446332;13891.583252502442;13896.027278090538;14000.719985600334;14026.368813403422;28566.71433285682;2918.5038543447663;3031.5149576067943;3305.9504107438406;3459.446488561818;3466.6360032196762;16057.277422515897;16718.58203544909;17393;17456.30174793007;18240.817591824176;1979.8515111366562;2066.90698918549;2081.6357137501027;2085.666293393065;2087.8016588424075;128313;174152.12923935425;186211;187369;187482;18673.272340066487;20010.799892001185;25155.62266566003;25285.367865803502;25285.87357063203;42938;43218;43961.90095247644;44474.55525444704;44645.55354446479;7589;9120.81758364829;9333.533323333806;9419.199368053654;9429.66996155145;7430.368418684364;9043.140901614335;9103.49930753815;9120.224780893566;9259;4120.402541631447;4889.875328674425;4914.901701966012;5122.282880396748;5127.897442051138;1747.519432156167;2140.2295173737443;2145.538709177534;2150.989245053796;2153.343230314738;858.038996324121;1001.354126588361;1038.3302769713493;1042.8279333909973;1068.417712346773;16641.084740339156;21239;21642.567148656937;21721;21925.451863703536;1058.523664351045;1080.974064541678;1084.2572837606108;1091.0943916549245;1093.283899046126;8716.869246961302;10956;11336.659900203007;11505.079593632488;11532.94233528827;768142.1592892;771661;776602;795198;797174;100111.49944250232;114548;152560;175842;182562;406.91047969446674;473.37987236720005;474.79821076042543;476.1999840268313;480.92305231163544;10230.130438912776;10299.279050466395;11514.481848317013;11611.941940290582;12137.393130343271;37594;43845;58039.70980145072;61983.52065917493;62688.37311626825;90989;91585;91759.46547884283;92445.07554924364;92552.53723731339;6873.690683919136;7406.555606663617;8386.706465273686;8478.40651154438;8557;10839.187060970302;11525.711857203636;12819.871801281868;13033.47866085346;13263.602091937257;11311.151663625096;11447.25592836463;11602.88397116018;11603.6518904433;11775.352355620524;10410.271281010257;10848.837267441153;11432.542698291974;11616.186866919235;11702.648920532398;11607.941960290145;11691.532338706356;11707.239029463057;12043.819342710041;12187.817182742443;8261.628226729932;8650.956745216485;8811.955940220258;8995.505247211466;9050.456972581665;9699;11022.779544408906;11104.722381940515;11361.602343917923;11663.533458661559 05:47:02 INFO - PROCESS | 1635 | |3;mootools.html;1309.2929817898334;1451.8330392004923;1466.633341664584;1473.2854565535713;1474.7124310759407;7649.426293028031;7864.528128312305;8131.67473301068;8498.107698691641;8537.487750734952;7998.1601931797195;8319.209675080872;8329.95835020824;8430.831383372344;8436.831263374745;2360.716713994319;2386.200622791367;2388.283514945518;2388.677528533643;2394.964075538868;2138;2381.285614315707;2397.7242617098996;2398.796102331295;2402.9279121626378;2159.967600485994;2297.873616951067;2377.405648587853;2384.415818124562;2386.4988352446007;2339.7426283108844;2359.0327965534048;2365.44412063165;2368.0646144772804;2372.0274687378264;2340.3330050935574;2350.471143992588;2402.2913240593894;2403.8197135214928;2413.0589070262613;8381.62282697274;8432.40973131881;8434.40959132861;8474.322054235647;8502.532360720157;5147.279380886679;5274.815381461669;5274.841754747364;5288.762005709752;5294.6029047821185;3380.2056516718553;3544.113971507123;3597.784132952005;3609.9097522562147;3686.1706116123923;1259.307380940479;1260.1242136714986;1293.9482420703162;1314.4282237226741;1315.1714419915488;10860.45697715111;10876.510557024953;10897.346159230472;10902.182336324808;10922.29005114665;3949.861754838567;4644.2801365788355;4832.081904438216;4851.466338702739;4859.052484765437;8713.215810576952;8771.254443372258;8771.736847894574;8808.383413161146;8812.955935220283;4602.930956035662;4818.349522814376;4826.710397376169;4837.346958160673;4839.41926968759;4379.49635791888;4381.298992161239;4388.100439409914;4730.645201609893;4854.68444551103;3823.6558709715705;3852.6708285641407;3878;3913.3542965410957;3920.31394505966;886.8603844060351;1128.4470609401335;1179.5222934711285;1204.325577676504;1208.746163305698;761.8209720715628;774.6978678315461;806.8265322955475;832.9292010179081;846.7883029242689;4710.788014539354;5514.751836167382;5787.131930210502;5866.8533286667325;5988.461038506555;919.9586018629178;997.5112195024386;1004.4776716107583;1007.2948935745007;1057.0697785948316;3085.41377138343;3315.966840331566;3762.567304759933;3768.8304026318883;3920.5883382244456;9694.709158725249;9962.601495940082;10037.648682296085;10057.396556206651;10117.848232276521;9694;9815.01849815027;9903.603855845686;9904.752381190383;10022.899771002343;7729.458937874295;7824;7843.098043724931;7856.450048496665;7857.646405911748;5441.238226648352;5444.128939369682;5479.0685583451705;5613.270274864241;5613.943860561505;9068.637254509746;9122.726318210463;9332.020137885424;9357.719268421959;9391.577379018097;7414.110306763223;9261.67584134552;9265.53672316395;9316.254699624149;9350.064993500595;7196.10048743907;7313.085864266966;7342.706291748484;7349.889751653623;7351.301626345488;7819.648115834687;7884.408669349937;7890.368770498231;7899.763007109796;7916.6041697916025;6992.3357281058215;6996.720131194898;7132.251113632994;7136.500444968802;7157.7136914522835;7004.894926576004;7055.047568578177;7089.539179953286;7089.82275443118;7144.4641651877355;6371.745130194926;6374.808755737336;6417.679116044085;6435.09908612804;6438.098666186831;4845.577211394303;4972.328735620645;4978.726170060537;4986.551210391082;5072.923906141485;4932.235503496964;5046.974765126298;5090.389153301628;5097.0315640028275;5189.299444575085 05:47:02 INFO - PROCESS | 1635 | |4;prototype.html;298.4553190427468;360.18597968590996;376.6515972725229;383.3999790328137;386.7118996347721;4803.085485329454;5389.5688344932405;5400.70296133713;5643.758373157909;5659.830494922846;4810.494898035699;5417.83746487606;5451.863703407417;5485.533729632986;5530.834074977747;3729.552453705559;3977.701672374569;4212.515560710512;4213.957860421395;4217.683673724483;4137.400076988834;4146.709730318879;4164.167166566684;4183;4212.684048696345;4130.277201489742;4139.7309174903685;4163.958360416403;4164.458620379346;4203.327467605184;3653.9086522836874;3669.889903302905;4022.1352409232154;4044.292248856453;4203.390508376299;3369.22507823202;3568.8750893718857;3600.5499312585926;3635.654612811779;3647.288778688157;4179.456670632829;4504.346869703907;5270.629636294591;5412.83761487156;5447.863803404907;4635.490096089427;4823.372961515016;4841.636877234221;4850.854474365775;4867.34290870735;4965.503449655042;5735.833978473116;5772.595918285721;5846.239988801472;5889.617174883661;3956.3867600521967;3972.2849887020325;4001.97999010006;4003.939940900889;4022.597740225983;5182.844514664566;5847.415258474119;6004.519638428871;6076.939230607682;6118.8776224475705;3968.265870813851;3988.12261302513;4054.6350828424993;4059.8835320287158;4073.877783666495;3811.4854494643423;4123.876283711494;4133.421321015;4135.545090040093;4140.958590414118;4488.483824360241;4750.928736068961;4834.613230941516;4839;4849.345338379344;4541.409616749867;5944.4352786485215;5960.731767070492;5979.01346277868;5992.790252341147;1178.5109179690503;1209.195884736658;1337.2177276293478;1348.3797453171455;1367.3641756583038;794.9863923497587;909.2453263791037;915.844306467902;921.7004473546124;925.3245131054368;4809.350737650442;5229.92155117666;5263.23683065961;5365;5426.67439953604;5171.482851714798;5201.113382072986;5472.616916815785;5530.944690553123;5609.429359779268;5235.973820130875;5251.343582052244;5256.316678831728;5342;5589.385167631557;2667.6124868242814;2966.8281028993856;3057.480228361184;3090.5364195370876;3097.643770966368;6123.958926982424;6153.323134455206;6156.6921653918025;6163.84590385244;6297;5775.595708300462;5970.940290597126;6139.4781443577795;6236.719347629368;6276.529260305495;5465.9180112298345;5640.407757185519;6079.270487541523;6187.721552530147;6453.290138084901;5372.623916325976;5534.225208470898;5714.885492367851;6134.4172303631085;6152.876942461036;4747.501512341292;4997.5002499749735;5275.709835958905;6108.236470441195;6119.76628530592;5530.91703624454;6081.962542548119;6126.938730612816;6174.2524576563;6306.432421082125;4411.404460397805;4437.134758722018;4437.955620443883;4454.265046267459;4463.085067561077;5112.693238405707;5195.921631347491;5223.059849227024;5857.502112320501;6178;4702.600278976189;5167.563570225758;5958.672273025025;5964.880702385841;5994;4926.655134140576;5173.559582877345;5895.528357731286;5985.850353741192;6009.45914867664;4953.529414705597;5746.9712651436475;5764.365919748908;5766.94233057664;5891.852703682271;4859.549719699054;5949.52403807703;5952.910706339322;5977.073553599199;5997.880042399041;5573.801632649;5595.944040559542;5815.912761308668;5887.558433117448;5893.970530147321 05:47:02 INFO - PROCESS | 1635 | |5;yui.html;708.7873637908626;725.0755287009059;741.6106544064365;778.1246098139593;885.4996926736395;15315.234238288083;16166.353345866151;16198.190090495473;16204.108774017424;16255.34978600856;12267;14799.33402996865;14845;14855.811535077195;14963.102213867176;800.1398496616132;873.7247766953421;887.077439462961;890.4123278636101;923.224491427201;792.2275781113426;832.1927730101792;885.3448448148356;887.1793590928365;887.817997310553;759.0701390796258;774.4733581164805;931.9813603727955;953.9379940303893;956.6077908057698;947.1759569174793;958.396210387455;959.3188835926501;962.5572236771094;969.0793745941384;716.0405057223294;719.2914978745931;719.4064896460388;719.6937558331612;727.1564984617871;15871.682566348723;16148.27332770028;16336.591585210346;16352.264148113245;16368.918155409267;14459.855401445957;14583.416663333453;14666.853331466655;14718.484853030199;14727.410903563952;13107.541236056788;13239.66900827487;13848;14003.019788614802;14371.065880717824;4526.8868278292975;4587.654838339557;4617.168909596271;4652.5114862939245;4671.416072990876;119574.8042519581;121158.39420802928;123204.38397808043;123643;126885.36557317247;11096.61161859331;11172.776544469065;11195.832062519068;11251.718707032389;11281.717957050976;22564.323070307917;22601.773982260296;22609.547809044056;22616.773832261795;22988.965496552697;7635.69457221716;8167.060788009455;8198.344132469389;8304.958475207586;8424.199701028392;4459.464864216315;4545.2954792007295;4555.1117532080925;4583.335416364608;4584.816607335669;3766.227923275722;3832.4442955771815;3849.172427928009;3858.401947698111;3884.708646851441;689.2074114767956;700.4431476975715;709.1171491493124;709.4253654539839;709.8402859356603;554.5785203245563;558.7485631465856;567.2087438023933;576.665020477593;599.4994179859779;9209.309301802392;10197.694069177936;10684.358938463716;10734.57061717538;10801.189910756724;669.9296573859674;714.6462501062008;721.3940290156189;723.8939544938461;738.2174894611729;4878.487758785276;5095.312132862016;5162.948370516322;5173.663711858717;5186.844394668166;103893;115246;117031.41484292524;118047.40976295063;119787;98502;99217.50391247998;99614.5019274899;100708;101235;71726.64136679283;86531;88110.67833982495;89746.55126724455;90501.54749226343;9551.617935282511;9861;10071.949640251752;10074;10807.837882431928;40319;50116.749416252685;50587.49412505828;50781.492185077674;50854.74572627113;57349.13976290442;68120.97818532627;69974.65012674904;76204;76597;12941.288229147487;13339;13444.327783610976;13776.65558361049;13796.793048104488;14838.258087095386;14944.103353798806;15077.39690412415;15327.080375177526;15878.047317161007;10859.51132199037;12608.87391126077;13736.93131534336;14270.286485675882;14826.406943722563;12369.196002259825;13249.801252981406;14255.50105746294;14277.14337139775;14283.71432571322;12997.350132493526;13173.934130329288;13772.449102035805;13796.933573992947;14089.929550352183;10924.399158046066;11289.322640641587;11320.943395282971;11541;11556.94221528887;9023.774405639911;10047.598096076075;11455.770884582094;11461.083113351078;11516.30902145874 05:47:02 INFO - PROCESS | 1635 | __end_tp_report 05:47:02 INFO - PROCESS | 1635 | __start_cc_report 05:47:02 INFO - PROCESS | 1635 | _x_x_mozilla_cycle_collect,381 05:47:02 INFO - PROCESS | 1635 | __end_cc_report 05:47:02 INFO - PROCESS | 1635 | __startTimestamp1490100422011__endTimestamp 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | ------- Summary: start ------- 05:47:02 INFO - PROCESS | 1635 | Number of tests: 6 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#0] dojo.html Cycles:165 Average:5928.49 Median:6189.60 stddev:679.03 (11.0%) stddev-sans-first:654.60 05:47:02 INFO - PROCESS | 1635 | Values: 3533.2 3543.8 3574.4 3574.6 3575.0 5984.9 6014.4 6032.4 6042.8 6053.7 5989.4 6071.4 6075.4 6100.1 6124.7 4560.0 5345.6 5408.5 5418.0 5543.5 5458.9 5474.8 5479.4 5488.8 5499.6 5189.6 5194.5 5469.2 5470.5 5485.9 4343.3 5133.3 5302.3 5398.1 5460.9 5254.0 5314.2 5328.8 5346.0 5367.4 5854.2 5912.9 5962.6 5996.6 6047.0 6258.9 6273.7 6288.0 6298.5 6334.6 6413.1 6421.5 6432.2 6450.6 6455.5 6124.8 6144.3 6152.5 6157.0 6195.4 6145.5 6339.0 6346.4 6446.7 6475.8 6285.7 6309.6 6326.9 6336.6 6337.5 6442.4 6453.4 6468.2 6473.2 6473.4 5231.5 6197.5 6215.3 6231.8 6281.4 6298.1 6317.1 6336.7 6348.2 6359.7 5145.2 5157.4 5175.5 5178.8 5180.3 3698.8 4521.8 4588.7 4614.7 4670.8 6071.4 6118.9 6134.6 6153.5 6161.7 6468.7 6476.2 6477.6 6481.6 6485.3 5852.2 6058.7 6089.5 6104.1 6130.1 5660.8 5664.7 5671.3 5672.7 5711.7 6607.6 6620.1 6623.2 6663.8 6664.7 5351.3 6219.9 6272.1 6364.9 6480.7 6648.9 6659.2 6659.6 6676.2 6678.0 6385.1 6385.8 6387.3 6387.9 6406.2 6242.6 6326.7 6420.6 6433.0 6469.5 6253.1 6270.3 6299.8 6323.4 6326.5 6268.9 6274.8 6291.8 6300.1 6305.2 4983.7 6118.0 6121.7 6158.0 6217.1 6125.2 6189.6 6255.1 6283.5 6287.7 6216.2 6246.5 6254.7 6268.1 6274.2 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#1] ext.html Cycles:180 Average:71998.71 Median:37586.81 stddev:155146.04 (412.8%) stddev-sans-first:155488.28 05:47:02 INFO - PROCESS | 1635 | Values: 457.4 457.6 483.9 534.8 555.0 31131.5 42356.6 42579.4 42602.6 43133.1 43664.6 44123.8 44420.8 44424.3 44431.8 7891.6 9092.7 9417.3 9679.0 9686.2 11705.4 11980.7 12039.9 12148.0 12164.5 9841.4 11611.9 11796.5 11816.4 11835.2 11853.3 11897.3 11898.1 11924.2 11946.9 11903.8 11939.0 12012.8 12017.2 12044.5 41717.6 42371.4 42698.9 42816.0 42837.6 4090.2 4125.8 4150.4 4259.3 4400.0 29728.6 30224.9 30446.2 30457.5 30515.0 3291.3 3314.8 3344.5 3459.0 3502.8 523472.0 524155.0 527598.0 527641.0 528715.0 63329.7 63556.0 63675.7 63736.0 63872.0 67188.0 67400.7 67423.7 67539.7 67686.3 17608.7 17813.3 17844.9 17857.9 17860.5 12504.4 12759.9 13442.8 13612.3 13746.6 16888.3 16946.1 16972.8 16977.7 16981.4 6997.6 7403.5 7469.8 7478.8 7486.3 887.7 888.5 896.4 917.8 928.4 30940.0 31324.2 31444.8 31655.7 31906.2 965.0 1052.6 1109.7 1111.1 1215.4 15874.6 15914.0 16080.1 16152.8 16387.3 643852.0 644240.0 650919.0 651880.0 653349.0 62074.7 62286.0 62408.7 62451.1 62745.7 1462.5 1469.4 1483.2 1502.9 1517.7 29581.1 30720.5 30895.0 31305.0 31372.2 51528.2 51967.2 52016.7 52041.0 52201.0 558515.0 561350.0 563079.0 563252.0 563821.0 36790.3 37668.6 40957.6 41449.0 41512.0 43383.8 43736.8 44371.8 46197.6 46215.5 30507.2 37177.0 37410.3 37505.0 37863.0 36714.4 36723.0 36751.7 37752.9 38125.0 36647.3 37180.6 37409.1 37500.2 38113.4 25699.0 29717.7 31317.7 32224.7 32739.9 24531.1 24722.6 24747.1 24994.0 25577.5 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#2] jquery.html Cycles:180 Average:43330.75 Median:26926.29 stddev:130869.52 (486.0%) stddev-sans-first:131196.74 05:47:02 INFO - PROCESS | 1635 | Values: 289.7 393.5 398.3 411.2 424.2 17649.7 20088.5 21360.9 21701.7 21773.9 12311.5 12487.4 15249.6 21003.8 22230.3 4804.7 4994.9 5609.4 5610.0 5612.5 6034.2 6104.2 6153.7 6159.8 6179.9 4898.5 4967.8 6031.1 6115.1 6127.8 4833.8 5459.9 5484.9 5497.0 5497.7 4673.0 5209.1 5259.9 5375.7 5379.1 13891.6 13896.0 14000.7 14026.4 28566.7 2918.5 3031.5 3306.0 3459.4 3466.6 16057.3 16718.6 17393.0 17456.3 18240.8 1979.9 2066.9 2081.6 2085.7 2087.8 128313.0 174152.1 186211.0 187369.0 187482.0 18673.3 20010.8 25155.6 25285.4 25285.9 42938.0 43218.0 43961.9 44474.6 44645.6 7589.0 9120.8 9333.5 9419.2 9429.7 7430.4 9043.1 9103.5 9120.2 9259.0 4120.4 4889.9 4914.9 5122.3 5127.9 1747.5 2140.2 2145.5 2151.0 2153.3 858.0 1001.4 1038.3 1042.8 1068.4 16641.1 21239.0 21642.6 21721.0 21925.5 1058.5 1081.0 1084.3 1091.1 1093.3 8716.9 10956.0 11336.7 11505.1 11532.9 768142.2 771661.0 776602.0 795198.0 797174.0 100111.5 114548.0 152560.0 175842.0 182562.0 406.9 473.4 474.8 476.2 480.9 10230.1 10299.3 11514.5 11611.9 12137.4 37594.0 43845.0 58039.7 61983.5 62688.4 90989.0 91585.0 91759.5 92445.1 92552.5 6873.7 7406.6 8386.7 8478.4 8557.0 10839.2 11525.7 12819.9 13033.5 13263.6 11311.2 11447.3 11602.9 11603.7 11775.4 10410.3 10848.8 11432.5 11616.2 11702.6 11607.9 11691.5 11707.2 12043.8 12187.8 8261.6 8651.0 8812.0 8995.5 9050.5 9699.0 11022.8 11104.7 11361.6 11663.5 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#3] mootools.html Cycles:180 Average:5415.34 Median:5122.16 stddev:2930.02 (57.2%) stddev-sans-first:2921.98 05:47:02 INFO - PROCESS | 1635 | Values: 1309.3 1451.8 1466.6 1473.3 1474.7 7649.4 7864.5 8131.7 8498.1 8537.5 7998.2 8319.2 8330.0 8430.8 8436.8 2360.7 2386.2 2388.3 2388.7 2395.0 2138.0 2381.3 2397.7 2398.8 2402.9 2160.0 2297.9 2377.4 2384.4 2386.5 2339.7 2359.0 2365.4 2368.1 2372.0 2340.3 2350.5 2402.3 2403.8 2413.1 8381.6 8432.4 8434.4 8474.3 8502.5 5147.3 5274.8 5274.8 5288.8 5294.6 3380.2 3544.1 3597.8 3609.9 3686.2 1259.3 1260.1 1293.9 1314.4 1315.2 10860.5 10876.5 10897.3 10902.2 10922.3 3949.9 4644.3 4832.1 4851.5 4859.1 8713.2 8771.3 8771.7 8808.4 8813.0 4602.9 4818.3 4826.7 4837.3 4839.4 4379.5 4381.3 4388.1 4730.6 4854.7 3823.7 3852.7 3878.0 3913.4 3920.3 886.9 1128.4 1179.5 1204.3 1208.7 761.8 774.7 806.8 832.9 846.8 4710.8 5514.8 5787.1 5866.9 5988.5 920.0 997.5 1004.5 1007.3 1057.1 3085.4 3316.0 3762.6 3768.8 3920.6 9694.7 9962.6 10037.6 10057.4 10117.8 9694.0 9815.0 9903.6 9904.8 10022.9 7729.5 7824.0 7843.1 7856.5 7857.6 5441.2 5444.1 5479.1 5613.3 5613.9 9068.6 9122.7 9332.0 9357.7 9391.6 7414.1 9261.7 9265.5 9316.3 9350.1 7196.1 7313.1 7342.7 7349.9 7351.3 7819.6 7884.4 7890.4 7899.8 7916.6 6992.3 6996.7 7132.3 7136.5 7157.7 7004.9 7055.0 7089.5 7089.8 7144.5 6371.7 6374.8 6417.7 6435.1 6438.1 4845.6 4972.3 4978.7 4986.6 5072.9 4932.2 5047.0 5090.4 5097.0 5189.3 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#4] prototype.html Cycles:180 Average:4710.74 Median:5226.49 stddev:1446.71 (27.7%) stddev-sans-first:1412.35 05:47:02 INFO - PROCESS | 1635 | Values: 298.5 360.2 376.7 383.4 386.7 4803.1 5389.6 5400.7 5643.8 5659.8 4810.5 5417.8 5451.9 5485.5 5530.8 3729.6 3977.7 4212.5 4214.0 4217.7 4137.4 4146.7 4164.2 4183.0 4212.7 4130.3 4139.7 4164.0 4164.5 4203.3 3653.9 3669.9 4022.1 4044.3 4203.4 3369.2 3568.9 3600.5 3635.7 3647.3 4179.5 4504.3 5270.6 5412.8 5447.9 4635.5 4823.4 4841.6 4850.9 4867.3 4965.5 5735.8 5772.6 5846.2 5889.6 3956.4 3972.3 4002.0 4003.9 4022.6 5182.8 5847.4 6004.5 6076.9 6118.9 3968.3 3988.1 4054.6 4059.9 4073.9 3811.5 4123.9 4133.4 4135.5 4141.0 4488.5 4750.9 4834.6 4839.0 4849.3 4541.4 5944.4 5960.7 5979.0 5992.8 1178.5 1209.2 1337.2 1348.4 1367.4 795.0 909.2 915.8 921.7 925.3 4809.4 5229.9 5263.2 5365.0 5426.7 5171.5 5201.1 5472.6 5530.9 5609.4 5236.0 5251.3 5256.3 5342.0 5589.4 2667.6 2966.8 3057.5 3090.5 3097.6 6124.0 6153.3 6156.7 6163.8 6297.0 5775.6 5970.9 6139.5 6236.7 6276.5 5465.9 5640.4 6079.3 6187.7 6453.3 5372.6 5534.2 5714.9 6134.4 6152.9 4747.5 4997.5 5275.7 6108.2 6119.8 5530.9 6082.0 6126.9 6174.3 6306.4 4411.4 4437.1 4438.0 4454.3 4463.1 5112.7 5195.9 5223.1 5857.5 6178.0 4702.6 5167.6 5958.7 5964.9 5994.0 4926.7 5173.6 5895.5 5985.9 6009.5 4953.5 5747.0 5764.4 5766.9 5891.9 4859.5 5949.5 5952.9 5977.1 5997.9 5573.8 5595.9 5815.9 5887.6 5894.0 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - PROCESS | 1635 | [#5] yui.html Cycles:180 Average:22076.67 Median:3853.79 stddev:32872.64 (853.0%) stddev-sans-first:32925.70 05:47:02 INFO - PROCESS | 1635 | Values: 708.8 725.1 741.6 778.1 885.5 15315.2 16166.4 16198.2 16204.1 16255.3 12267.0 14799.3 14845.0 14855.8 14963.1 800.1 873.7 887.1 890.4 923.2 792.2 832.2 885.3 887.2 887.8 759.1 774.5 932.0 953.9 956.6 947.2 958.4 959.3 962.6 969.1 716.0 719.3 719.4 719.7 727.2 15871.7 16148.3 16336.6 16352.3 16368.9 14459.9 14583.4 14666.9 14718.5 14727.4 13107.5 13239.7 13848.0 14003.0 14371.1 4526.9 4587.7 4617.2 4652.5 4671.4 119574.8 121158.4 123204.4 123643.0 126885.4 11096.6 11172.8 11195.8 11251.7 11281.7 22564.3 22601.8 22609.5 22616.8 22989.0 7635.7 8167.1 8198.3 8305.0 8424.2 4459.5 4545.3 4555.1 4583.3 4584.8 3766.2 3832.4 3849.2 3858.4 3884.7 689.2 700.4 709.1 709.4 709.8 554.6 558.7 567.2 576.7 599.5 9209.3 10197.7 10684.4 10734.6 10801.2 669.9 714.6 721.4 723.9 738.2 4878.5 5095.3 5162.9 5173.7 5186.8 103893.0 115246.0 117031.4 118047.4 119787.0 98502.0 99217.5 99614.5 100708.0 101235.0 71726.6 86531.0 88110.7 89746.6 90501.5 9551.6 9861.0 10071.9 10074.0 10807.8 40319.0 50116.7 50587.5 50781.5 50854.7 57349.1 68121.0 69974.7 76204.0 76597.0 12941.3 13339.0 13444.3 13776.7 13796.8 14838.3 14944.1 15077.4 15327.1 15878.0 10859.5 12608.9 13736.9 14270.3 14826.4 12369.2 13249.8 14255.5 14277.1 14283.7 12997.4 13173.9 13772.4 13796.9 14089.9 10924.4 11289.3 11320.9 11541.0 11556.9 9023.8 10047.6 11455.8 11461.1 11516.3 05:47:02 INFO - PROCESS | 1635 | -------- Summary: end -------- 05:47:02 INFO - PROCESS | 1635 | 05:47:02 INFO - TEST-INFO | 1635: exit 0 05:47:02 INFO - TEST-OK | dromaeo_css | took 1114320ms 05:47:02 INFO - TEST-START | kraken 05:47:02 INFO - operating with platform_type : mac_ 05:47:02 INFO - Initialising browser for kraken test... 05:47:02 INFO - TEST-INFO | started process 1647 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpoeNIHo/profile http://localhost:49244/getInfo.html) 05:47:05 INFO - PROCESS | 1647 | 2017-03-21 05:47:05.222 plugin-container[1649:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4647, name = 'com.apple.tsm.portname' 05:47:05 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:05 INFO - PROCESS | 1647 | 2017-03-21 05:47:05.223 plugin-container[1649:10277] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8d0b, name = 'com.apple.CFPasteboardClient' 05:47:05 INFO - PROCESS | 1647 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:05 INFO - PROCESS | 1647 | 2017-03-21 05:47:05.223 plugin-container[1649:10277] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:05 INFO - PROCESS | 1647 | __metrics Screen width/height:1600/1200 05:47:05 INFO - PROCESS | 1647 | colorDepth:24 05:47:05 INFO - PROCESS | 1647 | Browser inner width/height: 1024/689 05:47:05 INFO - PROCESS | 1647 | __metrics 05:47:05 ERROR - PROCESS | 1647 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 05:47:05 INFO - TEST-INFO | 1647: exit 0 05:47:05 INFO - Browser initialized. 05:47:05 INFO - Running cycle 1/1 for kraken test... 05:47:05 INFO - TEST-INFO | started process 1650 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/tmpoeNIHo/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 05:47:08 INFO - PROCESS | 1650 | 2017-03-21 05:47:08.229 plugin-container[1651:10403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x843f, name = 'com.apple.tsm.portname' 05:47:08 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:08 INFO - PROCESS | 1650 | 2017-03-21 05:47:08.230 plugin-container[1651:10403] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8a0b, name = 'com.apple.CFPasteboardClient' 05:47:08 INFO - PROCESS | 1650 | See /usr/include/servers/bootstrap_defs.h for the error codes. 05:47:08 INFO - PROCESS | 1650 | 2017-03-21 05:47:08.230 plugin-container[1651:10403] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 05:47:17 INFO - PROCESS | 1650 | RSS: Main: 173121536 05:47:17 INFO - PROCESS | 1650 | 05:47:20 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection) 05:47:21 INFO - PROCESS | 1650 | RSS: Main: 185958400 05:47:21 INFO - PROCESS | 1650 | 05:47:24 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-dft) 05:47:24 INFO - PROCESS | 1650 | RSS: Main: 193921024 05:47:24 INFO - PROCESS | 1650 | 05:47:28 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-fft) 05:47:28 INFO - PROCESS | 1650 | RSS: Main: 197611520 05:47:28 INFO - PROCESS | 1650 | 05:47:30 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator) 05:47:31 INFO - PROCESS | 1650 | RSS: Main: 199266304 05:47:31 INFO - PROCESS | 1650 | 05:47:33 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur) 05:47:34 INFO - PROCESS | 1650 | RSS: Main: 199102464 05:47:34 INFO - PROCESS | 1650 | 05:47:37 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom) 05:47:37 INFO - PROCESS | 1650 | RSS: Main: 203464704 05:47:37 INFO - PROCESS | 1650 | 05:47:40 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate) 05:47:41 INFO - PROCESS | 1650 | RSS: Main: 204107776 05:47:41 INFO - PROCESS | 1650 | 05:47:44 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial) 05:47:45 INFO - PROCESS | 1650 | RSS: Main: 203448320 05:47:45 INFO - PROCESS | 1650 | 05:47:46 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox) 05:47:47 INFO - PROCESS | 1650 | RSS: Main: 202088448 05:47:47 INFO - PROCESS | 1650 | 05:47:49 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes) 05:47:49 INFO - PROCESS | 1650 | RSS: Main: 202141696 05:47:49 INFO - PROCESS | 1650 | 05:47:51 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm) 05:47:52 INFO - PROCESS | 1650 | RSS: Main: 202092544 05:47:52 INFO - PROCESS | 1650 | 05:47:55 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 05:47:56 INFO - PROCESS | 1650 | RSS: Main: 202498048 05:47:56 INFO - PROCESS | 1650 | 05:47:59 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 05:47:59 INFO - PROCESS | 1650 | RSS: Main: 202584064 05:47:59 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | Cycle 1(1): loaded http://localhost:49244/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49244/tests/kraken/driver.html?testName=ai-astar) 05:48:01 INFO - PROCESS | 1650 | RSS: Main: 204423168 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | __start_tp_report 05:48:01 INFO - PROCESS | 1650 | _x_x_mozilla_page_load 05:48:01 INFO - PROCESS | 1650 | _x_x_mozilla_page_load_details 05:48:01 INFO - PROCESS | 1650 | |i|pagename|runs| 05:48:01 INFO - PROCESS | 1650 | |0;ai-astar;104;117;116;118;117;117;117;119;115;119 05:48:01 INFO - PROCESS | 1650 | |1;audio-beat-detection;174;112;111;145;109;119;104;111;128;113 05:48:01 INFO - PROCESS | 1650 | |2;audio-dft;198;204;195;204;197;197;196;198;203;194 05:48:01 INFO - PROCESS | 1650 | |3;audio-fft;81;77;84;93;63;60;60;60;74;50 05:48:01 INFO - PROCESS | 1650 | |4;audio-oscillator;100;57;71;91;102;58;82;93;102;68 05:48:01 INFO - PROCESS | 1650 | |5;imaging-gaussian-blur;104;109;90;124;82;117;129;118;90;95 05:48:01 INFO - PROCESS | 1650 | |6;imaging-darkroom;96;97;95;94;102;151;152;95;94;97 05:48:01 INFO - PROCESS | 1650 | |7;imaging-desaturate;93;96;115;149;129;147;146;91;95;90 05:48:01 INFO - PROCESS | 1650 | |8;json-parse-financial;58;57;57;59;60;62;61;61;61;61 05:48:01 INFO - PROCESS | 1650 | |9;json-stringify-tinderbox;73;69;76;77;76;70;69;72;65;70 05:48:01 INFO - PROCESS | 1650 | |10;stanford-crypto-aes;84;85;83;82;83;82;84;80;80;87 05:48:01 INFO - PROCESS | 1650 | |11;stanford-crypto-ccm;134;134;132;156;133;156;150;154;206;179 05:48:01 INFO - PROCESS | 1650 | |12;stanford-crypto-pbkdf2;168;171;172;164;145;210;226;183;226;230 05:48:01 INFO - PROCESS | 1650 | |13;stanford-crypto-sha256-iterative;65;65;64;65;69;64;66;64;69;77 05:48:01 INFO - PROCESS | 1650 | __end_tp_report 05:48:01 INFO - PROCESS | 1650 | __start_cc_report 05:48:01 INFO - PROCESS | 1650 | _x_x_mozilla_cycle_collect,955 05:48:01 INFO - PROCESS | 1650 | __end_cc_report 05:48:01 INFO - PROCESS | 1650 | __startTimestamp1490100481856__endTimestamp 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | ------- Summary: start ------- 05:48:01 INFO - PROCESS | 1650 | Number of tests: 14 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#0] ai-astar Cycles:10 Average:115.90 Median:117.00 stddev:4.36 (3.7%) stddev-sans-first:1.30 05:48:01 INFO - PROCESS | 1650 | Values: 104.0 117.0 116.0 118.0 117.0 117.0 117.0 119.0 115.0 119.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#1] audio-beat-detection Cycles:10 Average:122.60 Median:116.00 stddev:21.58 (18.6%) stddev-sans-first:12.52 05:48:01 INFO - PROCESS | 1650 | Values: 174.0 112.0 111.0 145.0 109.0 119.0 104.0 111.0 128.0 113.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#2] audio-dft Cycles:10 Average:198.60 Median:198.00 stddev:3.72 (1.9%) stddev-sans-first:3.94 05:48:01 INFO - PROCESS | 1650 | Values: 198.0 204.0 195.0 204.0 197.0 197.0 196.0 198.0 203.0 194.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#3] audio-fft Cycles:10 Average:70.20 Median:75.50 stddev:13.58 (18.0%) stddev-sans-first:13.83 05:48:01 INFO - PROCESS | 1650 | Values: 81.0 77.0 84.0 93.0 63.0 60.0 60.0 60.0 74.0 50.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#4] audio-oscillator Cycles:10 Average:82.40 Median:69.50 stddev:17.77 (25.6%) stddev-sans-first:17.67 05:48:01 INFO - PROCESS | 1650 | Values: 100.0 57.0 71.0 91.0 102.0 58.0 82.0 93.0 102.0 68.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#5] imaging-gaussian-blur Cycles:10 Average:105.80 Median:105.50 stddev:16.12 (15.3%) stddev-sans-first:17.09 05:48:01 INFO - PROCESS | 1650 | Values: 104.0 109.0 90.0 124.0 82.0 117.0 129.0 118.0 90.0 95.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#6] imaging-darkroom Cycles:10 Average:107.30 Median:95.00 stddev:23.41 (24.6%) stddev-sans-first:24.47 05:48:01 INFO - PROCESS | 1650 | Values: 96.0 97.0 95.0 94.0 102.0 151.0 152.0 95.0 94.0 97.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#7] imaging-desaturate Cycles:10 Average:115.10 Median:90.50 stddev:25.35 (28.0%) stddev-sans-first:25.59 05:48:01 INFO - PROCESS | 1650 | Values: 93.0 96.0 115.0 149.0 129.0 147.0 146.0 91.0 95.0 90.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#8] json-parse-financial Cycles:10 Average:59.70 Median:61.00 stddev:1.83 (3.0%) stddev-sans-first:1.83 05:48:01 INFO - PROCESS | 1650 | Values: 58.0 57.0 57.0 59.0 60.0 62.0 61.0 61.0 61.0 61.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#9] json-stringify-tinderbox Cycles:10 Average:71.70 Median:72.50 stddev:3.83 (5.3%) stddev-sans-first:4.03 05:48:01 INFO - PROCESS | 1650 | Values: 73.0 69.0 76.0 77.0 76.0 70.0 69.0 72.0 65.0 70.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#10] stanford-crypto-aes Cycles:10 Average:83.00 Median:83.50 stddev:2.16 (2.6%) stddev-sans-first:2.26 05:48:01 INFO - PROCESS | 1650 | Values: 84.0 85.0 83.0 82.0 83.0 82.0 84.0 80.0 80.0 87.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#11] stanford-crypto-ccm Cycles:10 Average:153.40 Median:155.00 stddev:23.74 (15.3%) stddev-sans-first:24.13 05:48:01 INFO - PROCESS | 1650 | Values: 134.0 134.0 132.0 156.0 133.0 156.0 150.0 154.0 206.0 179.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:189.50 Median:196.50 stddev:30.75 (15.6%) stddev-sans-first:31.61 05:48:01 INFO - PROCESS | 1650 | Values: 168.0 171.0 172.0 164.0 145.0 210.0 226.0 183.0 226.0 230.0 05:48:01 INFO - PROCESS | 1650 | 05:48:01 INFO - PROCESS | 1650 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:66.80 Median:65.50 stddev:4.05 (6.2%) stddev-sans-first:4.24 05:48:01 INFO - PROCESS | 1650 | Values: 65.0 65.0 64.0 65.0 69.0 64.0 66.0 64.0 69.0 77.0 05:48:01 INFO - PROCESS | 1650 | -------- Summary: end -------- 05:48:01 INFO - PROCESS | 1650 | 05:48:02 INFO - TEST-INFO | 1650: exit 0 05:48:02 INFO - TEST-OK | kraken | took 60081ms 05:48:02 INFO - SUITE-END | took 1174s 05:48:03 INFO - Completed test suite (00:19:34) 05:48:03 INFO - javascript benchmark 05:48:03 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7544.53108476938, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3533.1520435095576, 3543.787372757635, 3574.374484465218, 3574.624664410237, 3575.0347406200326, 5984.910226346608, 6014.428629280212, 6032.396760323965, 6042.8489287767825, 6053.667048312341, 5989.4010598940085, 6071.392860713926, 6075.362086980869, 6100.054491553817, 6124.693765311739, 4559.9772001139945, 5345.625806193568, 5408.513233808956, 5418.024755544, 5543.473370029841, 5458.91811622824, 5474.808381706641, 5479.397266300703, 5488.7530061147345, 5499.560035197194, 5189.558887494568, 5194.480551944813, 5469.179623056533, 5470.507654311111, 5485.945140548603, 4343.261645520253, 5133.30700355451, 5302.3106996090455, 5398.055340315429, 5460.890782184374, 5253.973730131325, 5314.202869569557, 5328.8401347959625, 5346.0, 5367.382750983647, 5854.151148083547, 5912.911306330366, 5962.642241465483, 5996.580239383245, 6046.96976515119, 6258.9359808832605, 6273.654948977804, 6288.025356069817, 6298.4646305064125, 6334.556581039329, 6413.102165696806, 6421.454176395012, 6432.163818703585, 6450.580712253735, 6455.515836312248, 6124.755009799603, 6144.262688477412, 6152.477039451607, 6156.969215153896, 6195.380461953859, 6145.539084568675, 6339.0, 6346.365363463617, 6446.677666116675, 6475.838104047437, 6285.717142728588, 6309.558330916883, 6326.936730632727, 6336.556441049174, 6337.493000559944, 6442.420182183626, 6453.419192272718, 6468.191476065492, 6473.2232132144445, 6473.41739243461, 5231.529162375405, 6197.5351848610635, 6215.316315205324, 6231.750729970841, 6281.371862813773, 6298.08677741734, 6317.052442133625, 6336.651484168415, 6348.206474190726, 6359.682015899187, 5145.202493613496, 5157.355330583602, 5175.45657705943, 5178.766955487012, 5180.274761533388, 3698.815059247027, 4521.819127234874, 4588.747618880993, 4614.653900957442, 4670.813167473263, 6071.3928607139815, 6118.939503446732, 6134.620279381772, 6153.476954458921, 6161.7227224775, 6468.676566171673, 6476.190476190476, 6477.578957367756, 6481.578697384655, 6485.286618471964, 5852.209951656557, 6058.727357268934, 6089.543284253698, 6104.114903338991, 6130.080487926757, 5660.830175094754, 5664.660120392789, 5671.262735844396, 5672.659640421588, 5711.714414279352, 6607.603543787388, 6620.073189753439, 6623.172103487064, 6663.800085997428, 6664.666766661648, 5351.250824884597, 6219.906701399573, 6272.121902933684, 6364.87270254583, 6480.740770369132, 6648.933510664831, 6659.2008958925235, 6659.600423974577, 6676.165479315086, 6678.0, 6385.074164246251, 6385.840353991188, 6387.265464471459, 6387.872242555216, 6406.199225096863, 6242.5942313749465, 6326.746930122928, 6420.6147631142285, 6432.970724684028, 6469.450096741642, 6253.062040693932, 6270.310265870842, 6299.842503937438, 6323.430891219812, 6326.4622507087415, 6268.937310626835, 6274.780382686584, 6291.811245662638, 6300.117983482408, 6305.2433707953505, 4983.72589507566, 6118.010103775335, 6121.693915304306, 6157.96921015392, 6217.067439884054, 6125.203723515914, 6189.597676151036, 6255.093011513396, 6283.497320214463, 6287.654179020198, 6216.160818289473, 6246.469614944368, 6254.687265636609, 6268.091126786681, 6274.152989346379], "value": 5885.842159207242, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [457.4350676914013, 457.55388496216193, 483.93224948507213, 534.8047962493689, 555.0203890133912, 31131.533027004614, 42356.57643423565, 42579.36130958038, 42602.573974260245, 43133.137337253276, 43664.563354366524, 44123.77938110313, 44420.77789611056, 44424.3336349955, 44431.77784111075, 7891.565963871984, 9092.681756138536, 9417.340786144972, 9678.9837067108, 9686.225101991824, 11705.3562054087, 11980.700482487964, 12039.93980030103, 12148.0, 12164.452599632994, 9841.36031157976, 11611.883881161208, 11796.528138874477, 11816.350100744456, 11835.230710003865, 11853.348065856375, 11897.286162830258, 11898.107641926847, 11924.151756477673, 11946.880531194663, 11903.821442678367, 11938.985186259179, 12012.819807702892, 12017.218880772809, 12044.457999390048, 41717.58282417167, 42371.36442953358, 42698.93252668677, 42816.0, 42837.57162428367, 4090.1819636072905, 4125.793710314502, 4150.377443383486, 4259.2759230930815, 4400.0, 29728.55407168894, 30224.94212702545, 30446.238844028856, 30457.543136852964, 30515.0, 3291.2594666200152, 3314.801111933292, 3344.464885618314, 3459.0131317150667, 3502.7548071634997, 523472.0, 524155.0, 527598.0, 527641.0, 528715.0, 63329.68335158295, 63556.04665930015, 63675.68162159252, 63736.0, 63872.041919371244, 67187.99218011733, 67400.65198695999, 67423.66288168626, 67539.66230168818, 67686.32313676899, 17608.735868961976, 17813.287468501116, 17844.929304241527, 17857.928524288585, 17860.464186074438, 12504.3747812609, 12759.915407190307, 13442.798358024638, 13612.251326177155, 13746.587602371945, 16888.324467021182, 16946.067966261973, 16972.83027169737, 16977.660446790997, 16981.40565080241, 6997.580145191305, 7403.51877127985, 7469.813254668677, 7478.775636730907, 7486.326230639269, 887.6759982606302, 888.493558671564, 896.421807933879, 917.7613820406609, 928.3594319919234, 30940.0, 31324.227146210545, 31444.842775786434, 31655.683443165733, 31906.20234494156, 965.0253244223385, 1052.6315789473622, 1109.7170221593442, 1111.111111111108, 1215.392303848076, 15874.603134921488, 15914.0, 16080.115593642462, 16152.838471615369, 16387.262573184234, 643852.0, 644240.0, 650919.0, 651880.0, 653349.0, 62074.689626551575, 62286.0, 62408.68795655993, 62451.06323405152, 62745.68627156926, 1462.531989763287, 1469.4122351059661, 1483.2138966347852, 1502.8797696184279, 1517.6661134550382, 29581.112566623036, 30720.539191912587, 30895.0, 31305.0, 31372.215694606904, 51528.22707659463, 51967.2204916919, 52016.739916300176, 52041.0, 52201.0, 558515.0, 561350.0, 563079.0, 563252.0, 563821.0, 36790.34443449997, 37668.62331376651, 40957.590424095375, 41449.0, 41512.0, 43383.783081084395, 43736.78131609321, 44371.77814110909, 46197.614071577904, 46215.53784462247, 30507.237319066313, 37177.0, 37410.25179496449, 37505.0, 37863.0, 36714.44928326024, 36723.0, 36751.71369002072, 37752.86741397763, 38125.046873828374, 36647.26705465929, 37180.62819371772, 37409.06477338088, 37500.24999500049, 38113.42829857499, 25699.0, 29717.66270517875, 31317.686823131477, 32224.677753222168, 32739.854105106206, 24531.14141005056, 24722.629160562246, 24747.133850315153, 24994.0, 25577.488450230518], "value": 20927.61582667469, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [289.74357693441306, 393.49828968065697, 398.2691760619264, 411.2392074661874, 424.2427267327819, 17649.7352539712, 20088.4977875553, 21360.931953402305, 21701.67447488289, 21773.891130544343, 12311.507539698423, 12487.375631218449, 15249.618759531017, 21003.789962100374, 22230.333090007287, 4804.711717296956, 4994.901121753227, 5609.439056094378, 5610.0, 5612.522935550483, 6034.215551978258, 6104.206453161083, 6153.7230824613, 6159.846003849895, 6179.876402471948, 4898.461169271395, 4967.751612419383, 6031.065184896348, 6115.143879856823, 6127.846803829918, 4833.830815921426, 5459.945400545984, 5484.945150548483, 5497.038018346773, 5497.725113744316, 4673.0, 5209.114450543417, 5259.921101183485, 5375.677459352412, 5379.112446446332, 13891.583252502442, 13896.027278090538, 14000.719985600334, 14026.368813403422, 28566.71433285682, 2918.5038543447663, 3031.5149576067943, 3305.9504107438406, 3459.446488561818, 3466.6360032196762, 16057.277422515897, 16718.58203544909, 17393.0, 17456.30174793007, 18240.817591824176, 1979.8515111366562, 2066.90698918549, 2081.6357137501027, 2085.666293393065, 2087.8016588424075, 128313.0, 174152.12923935425, 186211.0, 187369.0, 187482.0, 18673.272340066487, 20010.799892001185, 25155.62266566003, 25285.367865803502, 25285.87357063203, 42938.0, 43218.0, 43961.90095247644, 44474.55525444704, 44645.55354446479, 7589.0, 9120.81758364829, 9333.533323333806, 9419.199368053654, 9429.66996155145, 7430.368418684364, 9043.140901614335, 9103.49930753815, 9120.224780893566, 9259.0, 4120.402541631447, 4889.875328674425, 4914.901701966012, 5122.282880396748, 5127.897442051138, 1747.519432156167, 2140.2295173737443, 2145.538709177534, 2150.989245053796, 2153.343230314738, 858.038996324121, 1001.354126588361, 1038.3302769713493, 1042.8279333909973, 1068.417712346773, 16641.084740339156, 21239.0, 21642.567148656937, 21721.0, 21925.451863703536, 1058.523664351045, 1080.974064541678, 1084.2572837606108, 1091.0943916549245, 1093.283899046126, 8716.869246961302, 10956.0, 11336.659900203007, 11505.079593632488, 11532.94233528827, 768142.1592892, 771661.0, 776602.0, 795198.0, 797174.0, 100111.49944250232, 114548.0, 152560.0, 175842.0, 182562.0, 406.91047969446674, 473.37987236720005, 474.79821076042543, 476.1999840268313, 480.92305231163544, 10230.130438912776, 10299.279050466395, 11514.481848317013, 11611.941940290582, 12137.393130343271, 37594.0, 43845.0, 58039.70980145072, 61983.52065917493, 62688.37311626825, 90989.0, 91585.0, 91759.46547884283, 92445.07554924364, 92552.53723731339, 6873.690683919136, 7406.555606663617, 8386.706465273686, 8478.40651154438, 8557.0, 10839.187060970302, 11525.711857203636, 12819.871801281868, 13033.47866085346, 13263.602091937257, 11311.151663625096, 11447.25592836463, 11602.88397116018, 11603.6518904433, 11775.352355620524, 10410.271281010257, 10848.837267441153, 11432.542698291974, 11616.186866919235, 11702.648920532398, 11607.941960290145, 11691.532338706356, 11707.239029463057, 12043.819342710041, 12187.817182742443, 8261.628226729932, 8650.956745216485, 8811.955940220258, 8995.505247211466, 9050.456972581665, 9699.0, 11022.779544408906, 11104.722381940515, 11361.602343917923, 11663.533458661559], "value": 10157.023869048277, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1309.2929817898334, 1451.8330392004923, 1466.633341664584, 1473.2854565535713, 1474.7124310759407, 7649.426293028031, 7864.528128312305, 8131.67473301068, 8498.107698691641, 8537.487750734952, 7998.1601931797195, 8319.209675080872, 8329.95835020824, 8430.831383372344, 8436.831263374745, 2360.716713994319, 2386.200622791367, 2388.283514945518, 2388.677528533643, 2394.964075538868, 2138.0, 2381.285614315707, 2397.7242617098996, 2398.796102331295, 2402.9279121626378, 2159.967600485994, 2297.873616951067, 2377.405648587853, 2384.415818124562, 2386.4988352446007, 2339.7426283108844, 2359.0327965534048, 2365.44412063165, 2368.0646144772804, 2372.0274687378264, 2340.3330050935574, 2350.471143992588, 2402.2913240593894, 2403.8197135214928, 2413.0589070262613, 8381.62282697274, 8432.40973131881, 8434.40959132861, 8474.322054235647, 8502.532360720157, 5147.279380886679, 5274.815381461669, 5274.841754747364, 5288.762005709752, 5294.6029047821185, 3380.2056516718553, 3544.113971507123, 3597.784132952005, 3609.9097522562147, 3686.1706116123923, 1259.307380940479, 1260.1242136714986, 1293.9482420703162, 1314.4282237226741, 1315.1714419915488, 10860.45697715111, 10876.510557024953, 10897.346159230472, 10902.182336324808, 10922.29005114665, 3949.861754838567, 4644.2801365788355, 4832.081904438216, 4851.466338702739, 4859.052484765437, 8713.215810576952, 8771.254443372258, 8771.736847894574, 8808.383413161146, 8812.955935220283, 4602.930956035662, 4818.349522814376, 4826.710397376169, 4837.346958160673, 4839.41926968759, 4379.49635791888, 4381.298992161239, 4388.100439409914, 4730.645201609893, 4854.68444551103, 3823.6558709715705, 3852.6708285641407, 3878.0, 3913.3542965410957, 3920.31394505966, 886.8603844060351, 1128.4470609401335, 1179.5222934711285, 1204.325577676504, 1208.746163305698, 761.8209720715628, 774.6978678315461, 806.8265322955475, 832.9292010179081, 846.7883029242689, 4710.788014539354, 5514.751836167382, 5787.131930210502, 5866.8533286667325, 5988.461038506555, 919.9586018629178, 997.5112195024386, 1004.4776716107583, 1007.2948935745007, 1057.0697785948316, 3085.41377138343, 3315.966840331566, 3762.567304759933, 3768.8304026318883, 3920.5883382244456, 9694.709158725249, 9962.601495940082, 10037.648682296085, 10057.396556206651, 10117.848232276521, 9694.0, 9815.01849815027, 9903.603855845686, 9904.752381190383, 10022.899771002343, 7729.458937874295, 7824.0, 7843.098043724931, 7856.450048496665, 7857.646405911748, 5441.238226648352, 5444.128939369682, 5479.0685583451705, 5613.270274864241, 5613.943860561505, 9068.637254509746, 9122.726318210463, 9332.020137885424, 9357.719268421959, 9391.577379018097, 7414.110306763223, 9261.67584134552, 9265.53672316395, 9316.254699624149, 9350.064993500595, 7196.10048743907, 7313.085864266966, 7342.706291748484, 7349.889751653623, 7351.301626345488, 7819.648115834687, 7884.408669349937, 7890.368770498231, 7899.763007109796, 7916.6041697916025, 6992.3357281058215, 6996.720131194898, 7132.251113632994, 7136.500444968802, 7157.7136914522835, 7004.894926576004, 7055.047568578177, 7089.539179953286, 7089.82275443118, 7144.4641651877355, 6371.745130194926, 6374.808755737336, 6417.679116044085, 6435.09908612804, 6438.098666186831, 4845.577211394303, 4972.328735620645, 4978.726170060537, 4986.551210391082, 5072.923906141485, 4932.235503496964, 5046.974765126298, 5090.389153301628, 5097.0315640028275, 5189.299444575085], "value": 4409.738283348827, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [298.4553190427468, 360.18597968590996, 376.6515972725229, 383.3999790328137, 386.7118996347721, 4803.085485329454, 5389.5688344932405, 5400.70296133713, 5643.758373157909, 5659.830494922846, 4810.494898035699, 5417.83746487606, 5451.863703407417, 5485.533729632986, 5530.834074977747, 3729.552453705559, 3977.701672374569, 4212.515560710512, 4213.957860421395, 4217.683673724483, 4137.400076988834, 4146.709730318879, 4164.167166566684, 4183.0, 4212.684048696345, 4130.277201489742, 4139.7309174903685, 4163.958360416403, 4164.458620379346, 4203.327467605184, 3653.9086522836874, 3669.889903302905, 4022.1352409232154, 4044.292248856453, 4203.390508376299, 3369.22507823202, 3568.8750893718857, 3600.5499312585926, 3635.654612811779, 3647.288778688157, 4179.456670632829, 4504.346869703907, 5270.629636294591, 5412.83761487156, 5447.863803404907, 4635.490096089427, 4823.372961515016, 4841.636877234221, 4850.854474365775, 4867.34290870735, 4965.503449655042, 5735.833978473116, 5772.595918285721, 5846.239988801472, 5889.617174883661, 3956.3867600521967, 3972.2849887020325, 4001.97999010006, 4003.939940900889, 4022.597740225983, 5182.844514664566, 5847.415258474119, 6004.519638428871, 6076.939230607682, 6118.8776224475705, 3968.265870813851, 3988.12261302513, 4054.6350828424993, 4059.8835320287158, 4073.877783666495, 3811.4854494643423, 4123.876283711494, 4133.421321015, 4135.545090040093, 4140.958590414118, 4488.483824360241, 4750.928736068961, 4834.613230941516, 4839.0, 4849.345338379344, 4541.409616749867, 5944.4352786485215, 5960.731767070492, 5979.01346277868, 5992.790252341147, 1178.5109179690503, 1209.195884736658, 1337.2177276293478, 1348.3797453171455, 1367.3641756583038, 794.9863923497587, 909.2453263791037, 915.844306467902, 921.7004473546124, 925.3245131054368, 4809.350737650442, 5229.92155117666, 5263.23683065961, 5365.0, 5426.67439953604, 5171.482851714798, 5201.113382072986, 5472.616916815785, 5530.944690553123, 5609.429359779268, 5235.973820130875, 5251.343582052244, 5256.316678831728, 5342.0, 5589.385167631557, 2667.6124868242814, 2966.8281028993856, 3057.480228361184, 3090.5364195370876, 3097.643770966368, 6123.958926982424, 6153.323134455206, 6156.6921653918025, 6163.84590385244, 6297.0, 5775.595708300462, 5970.940290597126, 6139.4781443577795, 6236.719347629368, 6276.529260305495, 5465.9180112298345, 5640.407757185519, 6079.270487541523, 6187.721552530147, 6453.290138084901, 5372.623916325976, 5534.225208470898, 5714.885492367851, 6134.4172303631085, 6152.876942461036, 4747.501512341292, 4997.5002499749735, 5275.709835958905, 6108.236470441195, 6119.76628530592, 5530.91703624454, 6081.962542548119, 6126.938730612816, 6174.2524576563, 6306.432421082125, 4411.404460397805, 4437.134758722018, 4437.955620443883, 4454.265046267459, 4463.085067561077, 5112.693238405707, 5195.921631347491, 5223.059849227024, 5857.502112320501, 6178.0, 4702.600278976189, 5167.563570225758, 5958.672273025025, 5964.880702385841, 5994.0, 4926.655134140576, 5173.559582877345, 5895.528357731286, 5985.850353741192, 6009.45914867664, 4953.529414705597, 5746.9712651436475, 5764.365919748908, 5766.94233057664, 5891.852703682271, 4859.549719699054, 5949.52403807703, 5952.910706339322, 5977.073553599199, 5997.880042399041, 5573.801632649, 5595.944040559542, 5815.912761308668, 5887.558433117448, 5893.970530147321], "value": 4256.806436951915, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [708.7873637908626, 725.0755287009059, 741.6106544064365, 778.1246098139593, 885.4996926736395, 15315.234238288083, 16166.353345866151, 16198.190090495473, 16204.108774017424, 16255.34978600856, 12267.0, 14799.33402996865, 14845.0, 14855.811535077195, 14963.102213867176, 800.1398496616132, 873.7247766953421, 887.077439462961, 890.4123278636101, 923.224491427201, 792.2275781113426, 832.1927730101792, 885.3448448148356, 887.1793590928365, 887.817997310553, 759.0701390796258, 774.4733581164805, 931.9813603727955, 953.9379940303893, 956.6077908057698, 947.1759569174793, 958.396210387455, 959.3188835926501, 962.5572236771094, 969.0793745941384, 716.0405057223294, 719.2914978745931, 719.4064896460388, 719.6937558331612, 727.1564984617871, 15871.682566348723, 16148.27332770028, 16336.591585210346, 16352.264148113245, 16368.918155409267, 14459.855401445957, 14583.416663333453, 14666.853331466655, 14718.484853030199, 14727.410903563952, 13107.541236056788, 13239.66900827487, 13848.0, 14003.019788614802, 14371.065880717824, 4526.8868278292975, 4587.654838339557, 4617.168909596271, 4652.5114862939245, 4671.416072990876, 119574.8042519581, 121158.39420802928, 123204.38397808043, 123643.0, 126885.36557317247, 11096.61161859331, 11172.776544469065, 11195.832062519068, 11251.718707032389, 11281.717957050976, 22564.323070307917, 22601.773982260296, 22609.547809044056, 22616.773832261795, 22988.965496552697, 7635.69457221716, 8167.060788009455, 8198.344132469389, 8304.958475207586, 8424.199701028392, 4459.464864216315, 4545.2954792007295, 4555.1117532080925, 4583.335416364608, 4584.816607335669, 3766.227923275722, 3832.4442955771815, 3849.172427928009, 3858.401947698111, 3884.708646851441, 689.2074114767956, 700.4431476975715, 709.1171491493124, 709.4253654539839, 709.8402859356603, 554.5785203245563, 558.7485631465856, 567.2087438023933, 576.665020477593, 599.4994179859779, 9209.309301802392, 10197.694069177936, 10684.358938463716, 10734.57061717538, 10801.189910756724, 669.9296573859674, 714.6462501062008, 721.3940290156189, 723.8939544938461, 738.2174894611729, 4878.487758785276, 5095.312132862016, 5162.948370516322, 5173.663711858717, 5186.844394668166, 103893.0, 115246.0, 117031.41484292524, 118047.40976295063, 119787.0, 98502.0, 99217.50391247998, 99614.5019274899, 100708.0, 101235.0, 71726.64136679283, 86531.0, 88110.67833982495, 89746.55126724455, 90501.54749226343, 9551.617935282511, 9861.0, 10071.949640251752, 10074.0, 10807.837882431928, 40319.0, 50116.749416252685, 50587.49412505828, 50781.492185077674, 50854.74572627113, 57349.13976290442, 68120.97818532627, 69974.65012674904, 76204.0, 76597.0, 12941.288229147487, 13339.0, 13444.327783610976, 13776.65558361049, 13796.793048104488, 14838.258087095386, 14944.103353798806, 15077.39690412415, 15327.080375177526, 15878.047317161007, 10859.51132199037, 12608.87391126077, 13736.93131534336, 14270.286485675882, 14826.406943722563, 12369.196002259825, 13249.801252981406, 14255.50105746294, 14277.14337139775, 14283.71432571322, 12997.350132493526, 13173.934130329288, 13772.449102035805, 13796.933573992947, 14089.929550352183, 10924.399158046066, 11289.322640641587, 11320.943395282971, 11541.0, 11556.94221528887, 9023.774405639911, 10047.598096076075, 11455.770884582094, 11461.083113351078, 11516.30902145874], "value": 7851.528940766412, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1542.0, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 117.0, 116.0, 118.0, 117.0, 117.0, 117.0, 119.0, 115.0, 119.0], "value": 115.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [174.0, 112.0, 111.0, 145.0, 109.0, 119.0, 104.0, 111.0, 128.0, 113.0], "value": 122.6, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [198.0, 204.0, 195.0, 204.0, 197.0, 197.0, 196.0, 198.0, 203.0, 194.0], "value": 198.6, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [81.0, 77.0, 84.0, 93.0, 63.0, 60.0, 60.0, 60.0, 74.0, 50.0], "value": 70.2, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [100.0, 57.0, 71.0, 91.0, 102.0, 58.0, 82.0, 93.0, 102.0, 68.0], "value": 82.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 109.0, 90.0, 124.0, 82.0, 117.0, 129.0, 118.0, 90.0, 95.0], "value": 105.8, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 97.0, 95.0, 94.0, 102.0, 151.0, 152.0, 95.0, 94.0, 97.0], "value": 107.3, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 96.0, 115.0, 149.0, 129.0, 147.0, 146.0, 91.0, 95.0, 90.0], "value": 115.1, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [58.0, 57.0, 57.0, 59.0, 60.0, 62.0, 61.0, 61.0, 61.0, 61.0], "value": 59.7, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [73.0, 69.0, 76.0, 77.0, 76.0, 70.0, 69.0, 72.0, 65.0, 70.0], "value": 71.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 85.0, 83.0, 82.0, 83.0, 82.0, 84.0, 80.0, 80.0, 87.0], "value": 83.0, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [134.0, 134.0, 132.0, 156.0, 133.0, 156.0, 150.0, 154.0, 206.0, 179.0], "value": 153.4, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 171.0, 172.0, 164.0, 145.0, 210.0, 226.0, 183.0, 226.0, 230.0], "value": 189.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [65.0, 65.0, 64.0, 65.0, 69.0, 64.0, 66.0, 64.0, 69.0, 77.0], "value": 66.8, "unit": "score"}]}]} 05:48:03 INFO - Return code: 0 05:48:03 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 05:48:03 ERROR - # TBPL SUCCESS # 05:48:03 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 05:48:03 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 05:48:03 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 05:48:03 INFO - 2017-03-21 05:48:03.182 screenresolution[1655:10794] starting screenresolution argv=screenresolution get 05:48:03 INFO - 2017-03-21 05:48:03.185 screenresolution[1655:10794] Display 0: 1600x1200x32@60 05:48:03 INFO - 2017-03-21 05:48:03.191 screenresolution[1656:10797] starting screenresolution argv=screenresolution list 05:48:03 INFO - Available Modes on Display 0 05:48:03 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 05:48:03 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 05:48:03 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 05:48:03 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 05:48:03 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 05:48:03 INFO - Intel Iris: 05:48:03 INFO - Chipset Model: Intel Iris 05:48:03 INFO - Type: GPU 05:48:03 INFO - Bus: Built-In 05:48:03 INFO - VRAM (Dynamic, Max): 1536 MB 05:48:03 INFO - Vendor: Intel (0x8086) 05:48:03 INFO - Device ID: 0x0a2e 05:48:03 INFO - Revision ID: 0x0009 05:48:03 INFO - Displays: 05:48:03 INFO - Display: 05:48:03 INFO - Resolution: 1600 x 1200 @ 60 Hz 05:48:03 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 05:48:03 INFO - Display Serial Number: 1600x1200 60 05:48:03 INFO - Main Display: Yes 05:48:03 INFO - Mirror: Off 05:48:03 INFO - Online: Yes 05:48:03 INFO - Rotation: Supported 05:48:03 INFO - Return code: 0 05:48:03 INFO - Running post-action listener: _resource_record_post_action 05:48:03 INFO - [mozharness: 2017-03-21 12:48:03.435291Z] Finished run-tests step (success) 05:48:03 INFO - Running post-run listener: _resource_record_post_run 05:48:03 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 39536640; Write bytes: 424372224; Read time: 256; Write time: 1436 05:48:03 INFO - TinderboxPrint: CPU usage
54.1% 05:48:03 INFO - TinderboxPrint: I/O read bytes / time
39,536,640 / 256 05:48:03 INFO - TinderboxPrint: I/O write bytes / time
424,372,224 / 1,436 05:48:03 INFO - TinderboxPrint: CPU idle
2,187.1 (45.9%) 05:48:03 INFO - TinderboxPrint: CPU system
953.7 (20.0%) 05:48:03 INFO - TinderboxPrint: CPU user
1,620.9 (34.0%) 05:48:03 INFO - TinderboxPrint: Swap in / out
161,378,304 / 0 05:48:03 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 109984256; Write bytes: 101281792; Read time: 9949; Write time: 227 05:48:03 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 38606848; Write bytes: 301561856; Read time: 245; Write time: 1147 05:48:03 INFO - Running post-run listener: _upload_blobber_files 05:48:03 INFO - Blob upload gear active. 05:48:03 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 05:48:03 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 05:48:03 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/venv/bin/blobberc.py', '-u', 'https://blobupload.elasticbeanstalk.com', '-a', '/builds/slave/test/oauth.txt', '-b', 'Mozilla-Beta', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 05:48:03 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/venv/bin/blobberc.py -u https://blobupload.elasticbeanstalk.com -a /builds/slave/test/oauth.txt -b Mozilla-Beta -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 05:48:04 INFO - (blobuploader) - INFO - Open directory for files ... 05:48:04 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 05:48:04 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:04 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:05 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 05:48:05 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:05 INFO - (blobuploader) - INFO - Done attempting. 05:48:05 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 05:48:05 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:05 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:06 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 05:48:06 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:06 INFO - (blobuploader) - INFO - Done attempting. 05:48:06 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 05:48:06 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:06 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:07 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 05:48:07 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:07 INFO - (blobuploader) - INFO - Done attempting. 05:48:07 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 05:48:07 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 05:48:07 INFO - (blobuploader) - INFO - Uploading, attempt #1. 05:48:09 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 05:48:09 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 05:48:09 INFO - (blobuploader) - INFO - Done attempting. 05:48:09 INFO - (blobuploader) - INFO - Iteration through files over. 05:48:09 INFO - Return code: 0 05:48:09 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 05:48:09 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 05:48:09 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99bddc9cb4357b32ec7fdd044901270b1913e2c1a529163673dc9c9356c81e63fa36e10456cce30c75af279c30312fc5a2618cac6d0a2afc94c34463f1d1a525", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e3e9a631252238c2d7ec6b2b956bcdaf2f6a638c3aa6a5e96afd0f03bec84d116da5963dd3e0d9cdbaaaaa69eb626e8e62f007709e57ead32fbec079f3dbf23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eb23a9119ba280fad352c6a65548377ff06a0e09126a46fb529e4afc2746321a6ff617426be9a7988f30bfdf1808fa27dc47b7ebc34f155b7936a0b78f6cc86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27b8b61e8dac9663470fec22c6e31de135af8deeb6b3cec6771f972c613283f3e566baeff6602e193544a991b2e0b8c133b0068c6c096923dca1a2273af85466"} 05:48:09 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 05:48:09 INFO - Writing to file /builds/slave/test/properties/blobber_files 05:48:09 INFO - Contents: 05:48:09 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99bddc9cb4357b32ec7fdd044901270b1913e2c1a529163673dc9c9356c81e63fa36e10456cce30c75af279c30312fc5a2618cac6d0a2afc94c34463f1d1a525", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e3e9a631252238c2d7ec6b2b956bcdaf2f6a638c3aa6a5e96afd0f03bec84d116da5963dd3e0d9cdbaaaaa69eb626e8e62f007709e57ead32fbec079f3dbf23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eb23a9119ba280fad352c6a65548377ff06a0e09126a46fb529e4afc2746321a6ff617426be9a7988f30bfdf1808fa27dc47b7ebc34f155b7936a0b78f6cc86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27b8b61e8dac9663470fec22c6e31de135af8deeb6b3cec6771f972c613283f3e566baeff6602e193544a991b2e0b8c133b0068c6c096923dca1a2273af85466"} 05:48:09 INFO - Running post-run listener: copy_logs_to_upload_dir 05:48:09 INFO - Copying logs to upload dir... 05:48:09 INFO - mkdir: /builds/slave/test/build/upload/logs 05:48:09 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1252.409042 ========= master_lag: -0.34 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 52 secs) (at 2017-03-21 05:48:09.544900) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:48:09.548712) ========= 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.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/T/ TWISTD_LOG_PATH=/builds/slave/twistd.log USER=cltbld VERSIONER_PYTHON_PREFER_32_BIT=no VERSIONER_PYTHON_VERSION=2.7 XPCOM_DEBUG_BREAK=warn XPC_FLAGS=0x0 XPC_SERVICE_NAME=0 __CF_USER_TEXT_ENCODING=0x1C:0:0 using PTY: False blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99bddc9cb4357b32ec7fdd044901270b1913e2c1a529163673dc9c9356c81e63fa36e10456cce30c75af279c30312fc5a2618cac6d0a2afc94c34463f1d1a525", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e3e9a631252238c2d7ec6b2b956bcdaf2f6a638c3aa6a5e96afd0f03bec84d116da5963dd3e0d9cdbaaaaa69eb626e8e62f007709e57ead32fbec079f3dbf23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eb23a9119ba280fad352c6a65548377ff06a0e09126a46fb529e4afc2746321a6ff617426be9a7988f30bfdf1808fa27dc47b7ebc34f155b7936a0b78f6cc86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27b8b61e8dac9663470fec22c6e31de135af8deeb6b3cec6771f972c613283f3e566baeff6602e193544a991b2e0b8c133b0068c6c096923dca1a2273af85466"} build_url:https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010847 build_url: 'https://queue.taskcluster.net/v1/task/S3Fa9mg7SUqpYoiWpqlb0w/artifacts/public/build/firefox-53.0.en-US.mac.dmg' blobber_files: '{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/99bddc9cb4357b32ec7fdd044901270b1913e2c1a529163673dc9c9356c81e63fa36e10456cce30c75af279c30312fc5a2618cac6d0a2afc94c34463f1d1a525", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/9e3e9a631252238c2d7ec6b2b956bcdaf2f6a638c3aa6a5e96afd0f03bec84d116da5963dd3e0d9cdbaaaaa69eb626e8e62f007709e57ead32fbec079f3dbf23", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/1eb23a9119ba280fad352c6a65548377ff06a0e09126a46fb529e4afc2746321a6ff617426be9a7988f30bfdf1808fa27dc47b7ebc34f155b7936a0b78f6cc86", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/27b8b61e8dac9663470fec22c6e31de135af8deeb6b3cec6771f972c613283f3e566baeff6602e193544a991b2e0b8c133b0068c6c096923dca1a2273af85466"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-21 05:48:09.583637) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:48:09.583999) ========= 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.0PXMFxzfPU/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.AGXttl26qH/Listeners TMPDIR=/var/folders/jj/387lhx_94cxf0qmq_s0q96_000000w/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.005044 ========= master_lag: 0.04 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-21 05:48:09.632744) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:48:09.633082) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-21 05:48:09.633399) ========= ========= Total master_lag: -0.20 =========