builder: mozilla-beta_yosemite_r7_test-dromaeojs-e10s slave: t-yosemite-r7-0009 starttime: 1489015464.81 results: success (0) buildid: 20170308135723 builduid: 09decd5b7b484d8ba73eb7a1dfdd27cf revision: fb836ee72ce6953b44ec4538d72a2cf27c106fc7 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:24.815002) ========= master: http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:24.815456) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:24.815726) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:24.842266) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:24.842622) ========= 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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-08 15:24:24-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org... 63.245.215.102, 63.245.215.25 Connecting to hg.mozilla.org|63.245.215.102|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: 'archiver_client.py' 0K .......... . 100% 13.8M=0.001s 2017-03-08 15:24:25 (13.8 MB/s) - 'archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.150229 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:25.015595) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:25.015909) ========= 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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.031889 ========= master_lag: 0.02 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:25.072068) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 15:24:25.072536) ========= bash -c 'python archiver_client.py mozharness --repo releases/mozilla-beta --rev fb836ee72ce6953b44ec4538d72a2cf27c106fc7 --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 fb836ee72ce6953b44ec4538d72a2cf27c106fc7 --destination scripts --debug'] environment: Apple_PubSub_Socket_Render=/private/tmp/com.apple.launchd.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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-08 15:24:25,177 truncating revision to first 12 chars 2017-03-08 15:24:25,177 Setting DEBUG logging. 2017-03-08 15:24:25,177 attempt 1/10 2017-03-08 15:24:25,177 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/releases/mozilla-beta/fb836ee72ce6?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-08 15:24:26,050 unpacking tar archive at: mozilla-beta-fb836ee72ce6/testing/mozharness/ program finished with exit code 0 elapsedTime=1.136641 ========= master_lag: 0.02 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-08 15:24:26.232448) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:26.232763) ========= script_repo_revision: fb836ee72ce6953b44ec4538d72a2cf27c106fc7 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:26.233172) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:26.233432) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-08 15:24:26.253377) ========= ========= Started '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-08 15:24:26.253733) ========= /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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:24:26 INFO - MultiFileLogger online at 20170308 15:24:26 in /builds/slave/test 15:24:26 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 15:24:26 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 15:24:26 INFO - {'append_to_log': False, 15:24:26 INFO - 'base_work_dir': '/builds/slave/test', 15:24:26 INFO - 'blob_upload_branch': 'Mozilla-Beta', 15:24:26 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 15:24:26 INFO - 'branch': 'Mozilla-Beta', 15:24:26 INFO - 'buildbot_json_path': 'buildprops.json', 15:24:26 INFO - 'config_files': ('talos/mac_config.py',), 15:24:26 INFO - 'default_actions': ('clobber', 15:24:26 INFO - 'read-buildbot-config', 15:24:26 INFO - 'download-and-extract', 15:24:26 INFO - 'populate-webroot', 15:24:26 INFO - 'create-virtualenv', 15:24:26 INFO - 'install', 15:24:26 INFO - 'run-tests'), 15:24:26 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 15:24:26 INFO - 'download_minidump_stackwalk': True, 15:24:26 INFO - 'download_symbols': 'ondemand', 15:24:26 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 15:24:26 INFO - 'tooltool.py': '/tools/tooltool.py', 15:24:26 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 15:24:26 INFO - '/tools/misc-python/virtualenv.py')}, 15:24:26 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 15:24:26 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 15:24:26 INFO - 'installer_path': 'installer.exe', 15:24:26 INFO - 'log_level': 'info', 15:24:26 INFO - 'log_name': 'talos', 15:24:26 INFO - 'log_to_console': True, 15:24:26 INFO - 'minidump_stackwalk_path': 'macosx64-minidump_stackwalk', 15:24:26 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/macosx64/releng.manifest', 15:24:26 INFO - 'opt_config_files': (), 15:24:26 INFO - 'pip_index': False, 15:24:26 INFO - 'postflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:24:26 INFO - 'cmd': ('bash', 15:24:26 INFO - '-c', 15:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:24:26 INFO - 'enabled': True, 15:24:26 INFO - 'halt_on_failure': False, 15:24:26 INFO - 'name': 'check_screen_resolution'},), 15:24:26 INFO - 'preflight_run_cmd_suites': ({'architectures': ('32bit', '64bit'), 15:24:26 INFO - 'cmd': ('bash', 15:24:26 INFO - '-c', 15:24:26 INFO - 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'), 15:24:26 INFO - 'enabled': True, 15:24:26 INFO - 'halt_on_failure': False, 15:24:26 INFO - 'name': 'check_screen_resolution'},), 15:24:26 INFO - 'run_cmd_checks_enabled': True, 15:24:26 INFO - 'sps_profile': False, 15:24:26 INFO - 'sps_profile_interval': 0, 15:24:26 INFO - 'suite': 'dromaeojs-e10s', 15:24:26 INFO - 'system_bits': '32', 15:24:26 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 15:24:26 INFO - 'title': 't-yosemite-r7-0009', 15:24:26 INFO - 'tooltool_cache': '/builds/tooltool_cache', 15:24:26 INFO - 'use_talos_json': True, 15:24:26 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 15:24:26 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 15:24:26 INFO - 'work_dir': 'build'} 15:24:26 INFO - [mozharness: 2017-03-08 23:24:26.469754Z] Running clobber step. 15:24:26 INFO - Running pre-action listener: _resource_record_pre_action 15:24:26 INFO - Running main action method: clobber 15:24:26 INFO - rmtree: /builds/slave/test/build 15:24:26 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 15:24:28 INFO - Running post-action listener: _resource_record_post_action 15:24:28 INFO - [mozharness: 2017-03-08 23:24:28.915997Z] Finished clobber step (success) 15:24:28 INFO - [mozharness: 2017-03-08 23:24:28.916126Z] Running read-buildbot-config step. 15:24:28 INFO - Running pre-action listener: _resource_record_pre_action 15:24:28 INFO - Running main action method: read_buildbot_config 15:24:28 INFO - Using buildbot properties: 15:24:28 INFO - { 15:24:28 INFO - "project": "", 15:24:28 INFO - "product": "firefox", 15:24:28 INFO - "script_repo_revision": "production", 15:24:28 INFO - "builddir": "mozilla-beta_yosemite_r7_test-dromaeojs-e10s", 15:24:28 INFO - "repository": "", 15:24:28 INFO - "buildername": "Rev7 MacOSX Yosemite 10.10.5 mozilla-beta talos dromaeojs-e10s", 15:24:28 INFO - "buildid": "20170308135723", 15:24:28 INFO - "pgo_build": "False", 15:24:28 INFO - "basedir": "/builds/slave/test", 15:24:28 INFO - "buildnumber": 18, 15:24:28 INFO - "slavename": "t-yosemite-r7-0009", 15:24:28 INFO - "master": "http://buildbot-master106.bb.releng.scl3.mozilla.com:8201/", 15:24:28 INFO - "platform": "yosemite_r7", 15:24:28 INFO - "scheduler": "tests-mozilla-beta-macosx64-talos", 15:24:28 INFO - "branch": "mozilla-beta", 15:24:28 INFO - "repo_path": "releases/mozilla-beta", 15:24:28 INFO - "revision": "fb836ee72ce6953b44ec4538d72a2cf27c106fc7", 15:24:28 INFO - "stage_platform": "macosx64", 15:24:28 INFO - "builduid": "09decd5b7b484d8ba73eb7a1dfdd27cf", 15:24:28 INFO - "slavebuilddir": "test" 15:24:28 INFO - } 15:24:28 INFO - Found installer url https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg. 15:24:28 INFO - Running post-action listener: _resource_record_post_action 15:24:28 INFO - [mozharness: 2017-03-08 23:24:28.919451Z] Finished read-buildbot-config step (success) 15:24:28 INFO - [mozharness: 2017-03-08 23:24:28.919580Z] Running download-and-extract step. 15:24:28 INFO - Running pre-action listener: _resource_record_pre_action 15:24:28 INFO - Running main action method: download_and_extract 15:24:28 INFO - mkdir: /builds/slave/test/build/tests 15:24:28 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:28 INFO - trying https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.test_packages.json 15:24:28 INFO - Downloading https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/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 15:24:28 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/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 15:24:30 INFO - Downloaded 1165 bytes. 15:24:30 INFO - Reading from file /builds/slave/test/build/firefox-53.0.en-US.mac.test_packages.json 15:24:30 INFO - Using the following test package requirements: 15:24:30 INFO - {u'common': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:24:30 INFO - u'cppunittest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.cppunittest.tests.zip'], 15:24:30 INFO - u'gtest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.gtest.tests.zip'], 15:24:30 INFO - u'jittest': [u'firefox-53.0.en-US.mac.common.tests.zip', u'jsshell-mac.zip'], 15:24:30 INFO - u'mochitest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.mochitest.tests.zip'], 15:24:30 INFO - u'mozbase': [u'firefox-53.0.en-US.mac.common.tests.zip'], 15:24:30 INFO - u'reftest': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.reftest.tests.zip'], 15:24:30 INFO - u'talos': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.talos.tests.zip'], 15:24:30 INFO - u'web-platform': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.web-platform.tests.zip'], 15:24:30 INFO - u'xpcshell': [u'firefox-53.0.en-US.mac.common.tests.zip', 15:24:30 INFO - u'firefox-53.0.en-US.mac.xpcshell.tests.zip']} 15:24:30 INFO - Downloading packages: [u'firefox-53.0.en-US.mac.common.tests.zip'] for test suite category: common 15:24:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:24:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:24:30 INFO - Fetch https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:24:31 INFO - Expected file size: 16103538 15:24:31 INFO - Obtained file size: 16103538 15:24:37 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 15:24:37 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip 15:24:37 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip'}, attempt #1 15:24:37 INFO - Fetch https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.common.tests.zip into memory 15:24:38 INFO - Expected file size: 16103538 15:24:38 INFO - Obtained file size: 16103538 15:24:45 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip 15:24:45 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip'}, attempt #1 15:24:45 INFO - Fetch https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.talos.tests.zip into memory 15:24:46 INFO - Expected file size: 14403650 15:24:46 INFO - Obtained file size: 14403650 15:24:46 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:46 INFO - trying https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:24:46 INFO - Downloading https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg to /builds/slave/test/build/firefox-53.0.en-US.mac.dmg 15:24:46 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/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 15:24:48 INFO - Downloaded 58693073 bytes. 15:24:48 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:24:48 INFO - mkdir: /builds/slave/test/properties 15:24:48 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 15:24:48 INFO - Writing to file /builds/slave/test/properties/build_url 15:24:48 INFO - Contents: 15:24:48 INFO - build_url:https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg 15:24:48 INFO - Running post-action listener: _resource_record_post_action 15:24:48 INFO - Running post-action listener: set_extra_try_arguments 15:24:48 INFO - [mozharness: 2017-03-08 23:24:48.928572Z] Finished download-and-extract step (success) 15:24:48 INFO - [mozharness: 2017-03-08 23:24:48.928722Z] Running populate-webroot step. 15:24:48 INFO - Running pre-action listener: _resource_record_pre_action 15:24:48 INFO - Running main action method: populate_webroot 15:24:48 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 15:24:48 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 15:24:48 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 15:24:48 INFO - '--tppagecycles', 15:24:48 INFO - '7'], 15:24:48 INFO - 'tests': ['tsvgm']}}, 15:24:48 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['tresize', 'tcanvasmark']}, 15:24:48 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 15:24:48 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['dromaeo_css', 'kraken']}, 15:24:48 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 15:24:48 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:48 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 15:24:48 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['damp', 'tps']}, 15:24:48 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'tests': ['damp', 'tps']}, 15:24:48 INFO - 'g3': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['dromaeo_dom']}, 15:24:48 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 15:24:48 INFO - 'g4': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['basic_compositor_video']}, 15:24:48 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 15:24:48 INFO - 'other': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'other-e10s': {'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'other-e10s_l64': {'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['a11yr', 15:24:48 INFO - 'ts_paint', 15:24:48 INFO - 'tpaint', 15:24:48 INFO - 'sessionrestore', 15:24:48 INFO - 'sessionrestore_no_auto_restore', 15:24:48 INFO - 'tabpaint']}, 15:24:48 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['tsvgx', 15:24:48 INFO - 'tsvgr_opacity', 15:24:48 INFO - 'tart', 15:24:48 INFO - 'tscrollx', 15:24:48 INFO - 'cart', 15:24:48 INFO - 'tsvg_static']}, 15:24:48 INFO - 'svgr-e10s': {'tests': ['tsvgx', 15:24:48 INFO - 'tsvgr_opacity', 15:24:48 INFO - 'tart', 15:24:48 INFO - 'tscrollx', 15:24:48 INFO - 'cart', 15:24:48 INFO - 'tsvg_static']}, 15:24:48 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'talos_options': ['--disable-e10s'], 15:24:48 INFO - 'tests': ['tp5o']}, 15:24:48 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 15:24:48 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'talos_options': ['--disable-e10s', 15:24:48 INFO - '--xperf_path', 15:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:48 INFO - 'tests': ['tp5n']}, 15:24:48 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 15:24:48 INFO - 'talos_options': ['--xperf_path', 15:24:48 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 15:24:48 INFO - 'tests': ['tp5n']}}, 15:24:48 INFO - 'talos.zip': {'path': '', 15:24:48 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 15:24:48 INFO - Running post-action listener: _resource_record_post_action 15:24:48 INFO - [mozharness: 2017-03-08 23:24:48.947331Z] Finished populate-webroot step (success) 15:24:48 INFO - [mozharness: 2017-03-08 23:24:48.947467Z] Running create-virtualenv step. 15:24:48 INFO - Running pre-action listener: _resource_record_pre_action 15:24:48 INFO - Running main action method: create_virtualenv 15:24:48 INFO - Creating virtualenv /builds/slave/test/build/venv 15:24:48 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 15:24:48 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 15:24:49 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 15:24:49 INFO - Using real prefix '/tools/python27' 15:24:49 INFO - New python executable in /builds/slave/test/build/venv/bin/python 15:24:49 INFO - Installing distribute.............................................................................................................................................................................................done. 15:24:52 INFO - Installing pip.................done. 15:24:52 INFO - Return code: 0 15:24:52 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 15:24:52 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 15:24:53 INFO - Reading from file tmpfile_stdout 15:24:53 INFO - Output received: 15:24:53 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) 15:24:53 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:24:53 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 15:24:53 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 15:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:53 INFO - 'HOME': '/Users/cltbld', 15:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:53 INFO - 'LOGNAME': 'cltbld', 15:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:53 INFO - 'MOZ_NO_REMOTE': '1', 15:24:53 INFO - 'NO_EM_RESTART': '1', 15:24:53 INFO - 'PAGER': '/bin/cat', 15:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:53 INFO - 'PWD': '/builds/slave/test', 15:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:53 INFO - 'SHELL': '/bin/bash', 15:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:24:53 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:53 INFO - 'USER': 'cltbld', 15:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:53 INFO - 'XPC_FLAGS': '0x0', 15:24:53 INFO - 'XPC_SERVICE_NAME': '0', 15:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:53 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 15:24:53 INFO - Cleaning up... 15:24:53 INFO - Return code: 0 15:24:53 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:53 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:53 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:53 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:53 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:24:53 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 15:24:53 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 15:24:53 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:24:53 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:53 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:53 INFO - 'HOME': '/Users/cltbld', 15:24:53 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:53 INFO - 'LOGNAME': 'cltbld', 15:24:53 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:53 INFO - 'MOZ_NO_REMOTE': '1', 15:24:53 INFO - 'NO_EM_RESTART': '1', 15:24:53 INFO - 'PAGER': '/bin/cat', 15:24:53 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:53 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:53 INFO - 'PWD': '/builds/slave/test', 15:24:53 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:53 INFO - 'SHELL': '/bin/bash', 15:24:53 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:24:53 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:24:53 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:53 INFO - 'USER': 'cltbld', 15:24:53 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:53 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:53 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:53 INFO - 'XPC_FLAGS': '0x0', 15:24:53 INFO - 'XPC_SERVICE_NAME': '0', 15:24:53 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:53 INFO - Downloading/unpacking psutil>=3.1.1 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:53 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 15:24:56 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 15:24:57 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:57 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:57 INFO - Installing collected packages: psutil 15:24:57 INFO - Running setup.py install for psutil 15:24:57 INFO - building 'psutil._psutil_osx' extension 15:24:57 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 15:24:57 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 15:24:57 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 15:24:57 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 15:24:58 INFO - building 'psutil._psutil_posix' extension 15:24:58 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 15:24:58 WARNING - psutil/_psutil_posix.c:403:11: warning: implicit declaration of function 'ioctl' is invalid in C99 [-Wimplicit-function-declaration] 15:24:58 INFO - ret = ioctl(sock, SIOCGIFFLAGS, &ifr); 15:24:58 INFO - ^ 15:24:58 INFO - 1 warning generated. 15:24:58 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 15:24:58 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 15:24:58 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 15:24:58 INFO - Successfully installed psutil 15:24:58 INFO - Cleaning up... 15:24:58 INFO - Return code: 0 15:24:58 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 15:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:24:58 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:58 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:24:58 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:24:58 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:24:58 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 15:24:58 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 15:24:58 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:24:58 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:24:58 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:24:58 INFO - 'HOME': '/Users/cltbld', 15:24:58 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:24:58 INFO - 'LOGNAME': 'cltbld', 15:24:58 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:24:58 INFO - 'MOZ_NO_REMOTE': '1', 15:24:58 INFO - 'NO_EM_RESTART': '1', 15:24:58 INFO - 'PAGER': '/bin/cat', 15:24:58 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:24:58 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:24:58 INFO - 'PWD': '/builds/slave/test', 15:24:58 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:24:58 INFO - 'SHELL': '/bin/bash', 15:24:58 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:24:58 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:24:58 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:24:58 INFO - 'USER': 'cltbld', 15:24:58 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:24:58 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:24:58 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:24:58 INFO - 'XPC_FLAGS': '0x0', 15:24:58 INFO - 'XPC_SERVICE_NAME': '0', 15:24:58 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:24:58 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:24:58 INFO - Downloading/unpacking mozsystemmonitor==0.3 15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:24:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:24:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:01 INFO - Downloading mozsystemmonitor-0.3.tar.gz 15:25:01 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 15:25:01 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 15:25:01 INFO - Installing collected packages: mozsystemmonitor 15:25:01 INFO - Running setup.py install for mozsystemmonitor 15:25:01 INFO - Successfully installed mozsystemmonitor 15:25:01 INFO - Cleaning up... 15:25:01 INFO - Return code: 0 15:25:01 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 15:25: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')]} 15:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:01 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:01 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'jsonschema==2.5.1'] in /builds/slave/test/build 15:25:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub jsonschema==2.5.1 15:25:01 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:01 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:01 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:01 INFO - 'HOME': '/Users/cltbld', 15:25:01 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:01 INFO - 'LOGNAME': 'cltbld', 15:25:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:01 INFO - 'MOZ_NO_REMOTE': '1', 15:25:01 INFO - 'NO_EM_RESTART': '1', 15:25:01 INFO - 'PAGER': '/bin/cat', 15:25:01 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:01 INFO - 'PWD': '/builds/slave/test', 15:25:01 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:01 INFO - 'SHELL': '/bin/bash', 15:25:01 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:01 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:01 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:01 INFO - 'USER': 'cltbld', 15:25:01 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:01 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:01 INFO - 'XPC_FLAGS': '0x0', 15:25:01 INFO - 'XPC_SERVICE_NAME': '0', 15:25:01 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:01 INFO - Downloading/unpacking jsonschema==2.5.1 15:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:01 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:01 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:01 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:01 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:04 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 15:25:04 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 15:25:04 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 15:25:04 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 15:25:04 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 15:25:04 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 15:25:04 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 15:25:04 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 15:25:04 INFO - Downloading functools32-3.2.3-2.tar.gz 15:25:04 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 15:25:04 INFO - warning: no files found matching '*.txt' 15:25:04 INFO - no previously-included directories found matching 'build' 15:25:04 INFO - no previously-included directories found matching 'dist' 15:25:04 INFO - no previously-included directories found matching '.git*' 15:25:04 INFO - Installing collected packages: jsonschema, functools32 15:25:05 INFO - Running setup.py install for functools32 15:25:05 INFO - warning: no files found matching '*.txt' 15:25:05 INFO - no previously-included directories found matching 'build' 15:25:05 INFO - no previously-included directories found matching 'dist' 15:25:05 INFO - no previously-included directories found matching '.git*' 15:25:05 INFO - Successfully installed jsonschema functools32 15:25:05 INFO - Cleaning up... 15:25:05 INFO - Return code: 0 15:25:05 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 15:25: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')]} 15:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:05 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:05 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 15:25:05 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 15:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:05 INFO - 'HOME': '/Users/cltbld', 15:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:05 INFO - 'LOGNAME': 'cltbld', 15:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:05 INFO - 'MOZ_NO_REMOTE': '1', 15:25:05 INFO - 'NO_EM_RESTART': '1', 15:25:05 INFO - 'PAGER': '/bin/cat', 15:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:05 INFO - 'PWD': '/builds/slave/test', 15:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:05 INFO - 'SHELL': '/bin/bash', 15:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:05 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:05 INFO - 'USER': 'cltbld', 15:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:05 INFO - 'XPC_FLAGS': '0x0', 15:25:05 INFO - 'XPC_SERVICE_NAME': '0', 15:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:05 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 15:25:05 INFO - Cleaning up... 15:25:05 INFO - Return code: 0 15:25:05 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 15:25: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')]} 15:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:05 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:05 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:05 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:05 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:05 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 15:25:05 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 15:25:05 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:05 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:05 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:05 INFO - 'HOME': '/Users/cltbld', 15:25:05 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:05 INFO - 'LOGNAME': 'cltbld', 15:25:05 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:05 INFO - 'MOZ_NO_REMOTE': '1', 15:25:05 INFO - 'NO_EM_RESTART': '1', 15:25:05 INFO - 'PAGER': '/bin/cat', 15:25:05 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:05 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:05 INFO - 'PWD': '/builds/slave/test', 15:25:05 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:05 INFO - 'SHELL': '/bin/bash', 15:25:05 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:05 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:05 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:05 INFO - 'USER': 'cltbld', 15:25:05 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:05 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:05 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:05 INFO - 'XPC_FLAGS': '0x0', 15:25:05 INFO - 'XPC_SERVICE_NAME': '0', 15:25:05 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:05 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:05 INFO - Downloading/unpacking blobuploader==1.2.4 15:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:05 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:05 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 15:25:05 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 15:25:05 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 15:25:08 INFO - Downloading blobuploader-1.2.4.tar.gz 15:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 15:25:08 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25: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 15:25:08 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 15:25:09 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 15:25:09 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 15:25:09 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 15:25:09 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 15:25:09 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 15:25:09 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 15:25:09 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 15:25:09 INFO - Downloading docopt-0.6.1.tar.gz 15:25:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 15:25:09 INFO - Installing collected packages: blobuploader, requests, docopt 15:25:09 INFO - Running setup.py install for blobuploader 15:25:09 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 15:25:09 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 15:25:09 INFO - Running setup.py install for requests 15:25:10 INFO - Running setup.py install for docopt 15:25:10 INFO - Successfully installed blobuploader requests docopt 15:25:10 INFO - Cleaning up... 15:25:10 INFO - Return code: 0 15:25:10 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:10 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')]} 15:25:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:10 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')]} 15:25:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:10 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:10 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 15:25:10 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 15:25:10 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:10 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:10 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:10 INFO - 'HOME': '/Users/cltbld', 15:25:10 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:10 INFO - 'LOGNAME': 'cltbld', 15:25:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:10 INFO - 'MOZ_NO_REMOTE': '1', 15:25:10 INFO - 'NO_EM_RESTART': '1', 15:25:10 INFO - 'PAGER': '/bin/cat', 15:25:10 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:10 INFO - 'PWD': '/builds/slave/test', 15:25:10 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:10 INFO - 'SHELL': '/bin/bash', 15:25:10 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:10 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:10 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:10 INFO - 'USER': 'cltbld', 15:25:10 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:10 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:10 INFO - 'XPC_FLAGS': '0x0', 15:25:10 INFO - 'XPC_SERVICE_NAME': '0', 15:25:10 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8SngPm-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:10 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:10 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-GPvdb4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-UoZiw9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-QueHk5-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-lu1bYL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-OJQA15-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-kevrdL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-KrgSaY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-eLuAoe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-1g39Wk-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-TDr93I-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:11 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:11 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-uGfSom-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:12 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-XF0GVq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:12 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-kLmmaK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:12 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ZFxjRq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:12 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-rlRKeq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:12 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:12 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-83_ekb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:12 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 15:25:12 INFO - Running setup.py install for manifestparser 15:25:12 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 15:25:12 INFO - Running setup.py install for mozcrash 15:25:12 INFO - Running setup.py install for mozdebug 15:25:13 INFO - Running setup.py install for mozdevice 15:25:13 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Installing dm script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Running setup.py install for mozfile 15:25:13 INFO - Running setup.py install for mozhttpd 15:25:13 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Running setup.py install for mozinfo 15:25:13 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Running setup.py install for mozInstall 15:25:13 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 15:25:13 INFO - Running setup.py install for mozleak 15:25:13 INFO - Running setup.py install for mozlog 15:25:14 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Running setup.py install for moznetwork 15:25:14 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Running setup.py install for mozprocess 15:25:14 INFO - Running setup.py install for mozprofile 15:25:14 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Running setup.py install for mozrunner 15:25:14 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 15:25:14 INFO - Running setup.py install for mozscreenshot 15:25:14 INFO - Running setup.py install for moztest 15:25:15 INFO - Running setup.py install for mozversion 15:25:15 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 15:25:15 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 15:25:15 INFO - Cleaning up... 15:25:15 INFO - Return code: 0 15:25:15 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25:15 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')]} 15:25:15 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:15 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:15 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')]} 15:25:15 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:15 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:15 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:15 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 15:25:15 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 15:25:15 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:15 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:15 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:15 INFO - 'HOME': '/Users/cltbld', 15:25:15 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:15 INFO - 'LOGNAME': 'cltbld', 15:25:15 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:15 INFO - 'MOZ_NO_REMOTE': '1', 15:25:15 INFO - 'NO_EM_RESTART': '1', 15:25:15 INFO - 'PAGER': '/bin/cat', 15:25:15 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:15 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:15 INFO - 'PWD': '/builds/slave/test', 15:25:15 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:15 INFO - 'SHELL': '/bin/bash', 15:25:15 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:15 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:15 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:15 INFO - 'USER': 'cltbld', 15:25:15 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:15 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:15 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:15 INFO - 'XPC_FLAGS': '0x0', 15:25:15 INFO - 'XPC_SERVICE_NAME': '0', 15:25:15 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:15 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-Y_cht2-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 15:25:15 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)) 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-fFRY6D-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 15:25:15 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)) 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-EuY7lc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 15:25:15 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)) 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-YyCxBf-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 15:25:15 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)) 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-ngxQcQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 15:25:15 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)) 15:25:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 15:25:15 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-v6RNtq-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-8qFSL7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-6JtN0n-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-5wcdaJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-UHEbxj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-4Gp8ti-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-D5KYwe-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-d3WBb9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-hrqR6Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 15:25:16 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)) 15:25:16 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:16 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-4bAMq_-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 15:25:17 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)) 15:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 15:25:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-LQXntt-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 15:25:17 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)) 15:25:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 15:25:17 INFO - Running setup.py (path:/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/pip-TdbA3y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 15:25:17 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)) 15:25:17 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)) 15:25:17 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)) 15:25:17 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)) 15:25:17 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)) 15:25:17 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)) 15:25:17 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 15:25:17 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 15:25:17 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 15:25:17 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 15:25:17 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 15:25:17 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 15:25:17 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 15:25:19 INFO - Downloading blessings-1.6.tar.gz 15:25:19 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 15:25:20 INFO - Installing collected packages: blessings 15:25:20 INFO - Running setup.py install for blessings 15:25:20 INFO - Successfully installed blessings 15:25:20 INFO - Cleaning up... 15:25:20 INFO - Return code: 0 15:25:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 15:25:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:20 INFO - Reading from file tmpfile_stdout 15:25:20 INFO - Current package versions: 15:25:20 INFO - blessings == 1.6 15:25:20 INFO - blobuploader == 1.2.4 15:25:20 INFO - docopt == 0.6.1 15:25:20 INFO - functools32 == 3.2.3-2 15:25:20 INFO - jsonschema == 2.5.1 15:25:20 INFO - manifestparser == 1.1 15:25:20 INFO - mozInstall == 1.12 15:25:20 INFO - mozcrash == 1.0 15:25:20 INFO - mozdebug == 0.1 15:25:20 INFO - mozdevice == 0.49 15:25:20 INFO - mozfile == 1.2 15:25:20 INFO - mozhttpd == 0.7 15:25:20 INFO - mozinfo == 0.9 15:25:20 INFO - mozleak == 0.1 15:25:20 INFO - mozlog == 3.4 15:25:20 INFO - moznetwork == 0.27 15:25:20 INFO - mozprocess == 0.24 15:25:20 INFO - mozprofile == 0.28 15:25:20 INFO - mozrunner == 6.13 15:25:20 INFO - mozscreenshot == 0.1 15:25:20 INFO - mozsystemmonitor == 0.3 15:25:20 INFO - moztest == 0.8 15:25:20 INFO - mozversion == 1.4 15:25:20 INFO - psutil == 3.1.1 15:25:20 INFO - requests == 1.2.3 15:25:20 INFO - wsgiref == 0.1.2 15:25:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 15:25: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')]} 15:25:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25: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')]} 15:25:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:21 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:21 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 15:25:21 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 15:25:21 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:21 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:21 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:21 INFO - 'HOME': '/Users/cltbld', 15:25:21 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:21 INFO - 'LOGNAME': 'cltbld', 15:25:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:21 INFO - 'MOZ_NO_REMOTE': '1', 15:25:21 INFO - 'NO_EM_RESTART': '1', 15:25:21 INFO - 'PAGER': '/bin/cat', 15:25:21 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:21 INFO - 'PWD': '/builds/slave/test', 15:25:21 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:21 INFO - 'SHELL': '/bin/bash', 15:25:21 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:21 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:21 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:21 INFO - 'USER': 'cltbld', 15:25:21 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:21 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:21 INFO - 'XPC_FLAGS': '0x0', 15:25:21 INFO - 'XPC_SERVICE_NAME': '0', 15:25:21 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 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)) 15:25:21 INFO - Cleaning up... 15:25:21 INFO - Return code: 0 15:25:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 15:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 15:25:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:21 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 15:25:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 15:25:22 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 15:25:22 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 0x101a9ab70>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101a9d768>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x7fc930d90d20>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, '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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners', 'SHELL': '/bin/bash', 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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 15:25:22 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 15:25:22 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 15:25:22 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:22 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:22 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:22 INFO - 'HOME': '/Users/cltbld', 15:25:22 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:22 INFO - 'LOGNAME': 'cltbld', 15:25:22 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:22 INFO - 'MOZ_NO_REMOTE': '1', 15:25:22 INFO - 'NO_EM_RESTART': '1', 15:25:22 INFO - 'PAGER': '/bin/cat', 15:25:22 INFO - 'PATH': '/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:22 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:22 INFO - 'PWD': '/builds/slave/test', 15:25:22 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:22 INFO - 'SHELL': '/bin/bash', 15:25:22 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:22 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:22 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:22 INFO - 'USER': 'cltbld', 15:25:22 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:22 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:22 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:22 INFO - 'XPC_FLAGS': '0x0', 15:25:22 INFO - 'XPC_SERVICE_NAME': '0', 15:25:22 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:22 INFO - Ignoring indexes: https://pypi.python.org/simple/ 15:25:22 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 15:25:22 INFO - Cleaning up... 15:25:22 INFO - Return code: 0 15:25:22 INFO - Running post-action listener: _resource_record_post_action 15:25:22 INFO - Running post-action listener: _start_resource_monitoring 15:25:22 INFO - Starting resource monitoring. 15:25:22 INFO - [mozharness: 2017-03-08 23:25:22.651776Z] Finished create-virtualenv step (success) 15:25:22 INFO - [mozharness: 2017-03-08 23:25:22.652516Z] Running install step. 15:25:22 INFO - Running pre-action listener: _resource_record_pre_action 15:25:22 INFO - Running main action method: install 15:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 15:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 15:25:22 INFO - Reading from file tmpfile_stdout 15:25:22 INFO - Detecting whether we're running mozinstall >=1.0... 15:25:22 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 15:25:22 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 15:25:23 INFO - Reading from file tmpfile_stdout 15:25:23 INFO - Output received: 15:25:23 INFO - Usage: mozinstall [options] installer 15:25:23 INFO - Options: 15:25:23 INFO - -h, --help show this help message and exit 15:25:23 INFO - -d DEST, --destination=DEST 15:25:23 INFO - Directory to install application into. [default: 15:25:23 INFO - "/builds/slave/test"] 15:25:23 INFO - --app=APP Application being installed. [default: firefox] 15:25:23 INFO - mkdir: /builds/slave/test/build/application 15:25:23 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'] 15:25:23 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 15:25:37 INFO - Reading from file tmpfile_stdout 15:25:37 INFO - Output received: 15:25:37 INFO - /builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox 15:25:37 INFO - Running post-action listener: _resource_record_post_action 15:25:37 INFO - [mozharness: 2017-03-08 23:25:37.686648Z] Finished install step (success) 15:25:37 INFO - [mozharness: 2017-03-08 23:25:37.686801Z] Running run-tests step. 15:25:37 INFO - Running pre-action listener: _resource_record_pre_action 15:25:37 INFO - Running main action method: run_tests 15:25:37 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:25:37 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:25:37 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:25:37 INFO - 2017-03-08 15:25:37.701 screenresolution[1628:7306] starting screenresolution argv=screenresolution get 15:25:37 INFO - 2017-03-08 15:25:37.703 screenresolution[1628:7306] Display 0: 1600x1200x32@60 15:25:37 INFO - 2017-03-08 15:25:37.709 screenresolution[1629:7308] starting screenresolution argv=screenresolution list 15:25:37 INFO - Available Modes on Display 0 15:25:37 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:25:37 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:25:37 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:25:37 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:25:37 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:25:37 INFO - Intel Iris: 15:25:37 INFO - Chipset Model: Intel Iris 15:25:37 INFO - Type: GPU 15:25:37 INFO - Bus: Built-In 15:25:37 INFO - VRAM (Dynamic, Max): 1536 MB 15:25:37 INFO - Vendor: Intel (0x8086) 15:25:37 INFO - Device ID: 0x0a2e 15:25:37 INFO - Revision ID: 0x0009 15:25:37 INFO - Displays: 15:25:37 INFO - Display: 15:25:37 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:25:37 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:25:37 INFO - Display Serial Number: 1600x1200 60 15:25:37 INFO - Main Display: Yes 15:25:37 INFO - Mirror: Off 15:25:37 INFO - Online: Yes 15:25:37 INFO - Rotation: Supported 15:25:37 INFO - Return code: 0 15:25:37 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 15:25:37 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 15:25:37 INFO - Python 2.7.3 15:25:37 INFO - Return code: 0 15:25:37 INFO - grabbing minidump binary from tooltool 15:25: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')]} 15:25:37 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 0x101878d30>, '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 0x10179fdb0>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x10186dbe8>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 15:25:37 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 15:25:37 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 15:25:37 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 15:25:38 INFO - INFO - File macosx64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 15:25:38 INFO - Return code: 0 15:25:38 INFO - Chmoding /builds/slave/test/build/macosx64-minidump_stackwalk to 0755 15:25:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 15:25:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 15:25:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/macosx64-minidump_stackwalk 15:25:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 15:25:38 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/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 15:25:38 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/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip --title t-yosemite-r7-0009 --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 15:25:38 INFO - Using env: {'Apple_PubSub_Socket_Render': '/private/tmp/com.apple.launchd.x4TsDNPvxQ/Render', 15:25:38 INFO - 'GIT_SHARE_BASE_DIR': '/builds/git-shared', 15:25:38 INFO - 'HG_SHARE_BASE_DIR': '/builds/hg-shared', 15:25:38 INFO - 'HOME': '/Users/cltbld', 15:25:38 INFO - 'IDLEIZER_DISABLE_SHUTDOWN': 'true', 15:25:38 INFO - 'LOGNAME': 'cltbld', 15:25:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 15:25:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/macosx64-minidump_stackwalk', 15:25:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 15:25:38 INFO - 'MOZ_NO_REMOTE': '1', 15:25:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 15:25:38 INFO - 'NO_EM_RESTART': '1', 15:25:38 INFO - 'PAGER': '/bin/cat', 15:25:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/sbin:/usr/local/bin:/usr/sbin:/usr/bin:/sbin:/bin:/usr/bin/X11', 15:25:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 15:25:38 INFO - 'PWD': '/builds/slave/test', 15:25:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 15:25:38 INFO - 'RUNNER_CONFIG_CMD': '/opt/runner/bin/python2.7 /opt/runner/bin/runner -c /opt/runner/runner.cfg', 15:25:38 INFO - 'SHELL': '/bin/bash', 15:25:38 INFO - 'SSH_AUTH_SOCK': '/private/tmp/com.apple.launchd.SjoU33lzMC/Listeners', 15:25:38 INFO - 'TMPDIR': '/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/', 15:25:38 INFO - 'TWISTD_LOG_PATH': '/builds/slave/twistd.log', 15:25:38 INFO - 'USER': 'cltbld', 15:25:38 INFO - 'VERSIONER_PYTHON_PREFER_32_BIT': 'no', 15:25:38 INFO - 'VERSIONER_PYTHON_VERSION': '2.7', 15:25:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 15:25:38 INFO - 'XPC_FLAGS': '0x0', 15:25:38 INFO - 'XPC_SERVICE_NAME': '0', 15:25:38 INFO - '__CF_USER_TEXT_ENCODING': '0x1C:0:0'} 15:25:38 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/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.crashreporter-symbols.zip', '--title', 't-yosemite-r7-0009', '--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 15:25:38 INFO - mozversion application_buildid: 20170308135723 15:25:38 INFO - mozversion application_changeset: fb836ee72ce6953b44ec4538d72a2cf27c106fc7 15:25:38 INFO - mozversion application_display_name: Firefox 15:25:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 15:25:38 INFO - mozversion application_name: Firefox 15:25:38 INFO - mozversion application_remotingname: firefox 15:25:38 INFO - mozversion application_repository: https://hg.mozilla.org/releases/mozilla-beta 15:25:38 INFO - mozversion application_vendor: Mozilla 15:25:38 INFO - mozversion application_version: 53.0 15:25:38 INFO - mozversion platform_buildid: 20170308135723 15:25:38 INFO - mozversion platform_changeset: fb836ee72ce6953b44ec4538d72a2cf27c106fc7 15:25:38 INFO - mozversion platform_repository: https://hg.mozilla.org/releases/mozilla-beta 15:25:38 INFO - mozversion platform_version: 53.0 15:25:38 INFO - using testdate: 1489015538 15:25:38 INFO - actual date: 1489015538 15:25:38 INFO - starting webserver on 'localhost:49247' 15:25:38 INFO - SUITE-START | Running 2 tests 15:25:38 INFO - TEST-START | dromaeo_css 15:25:38 INFO - operating with platform_type : mac_ 15:25:38 INFO - Initialising browser for dromaeo_css test... 15:25:38 INFO - TEST-INFO | started process 1639 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp_wSfOc/profile http://localhost:49247/getInfo.html) 15:25:41 INFO - PROCESS | 1639 | 2017-03-08 15:25:41.283 plugin-container[1642:7478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8e4b, name = 'com.apple.tsm.portname' 15:25:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:41 INFO - PROCESS | 1639 | 2017-03-08 15:25:41.283 plugin-container[1642:7478] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4c13, name = 'com.apple.CFPasteboardClient' 15:25:41 INFO - PROCESS | 1639 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:41 INFO - PROCESS | 1639 | 2017-03-08 15:25:41.283 plugin-container[1642:7478] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:25:41 INFO - PROCESS | 1639 | __metrics Screen width/height:1600/1200 15:25:41 INFO - PROCESS | 1639 | colorDepth:24 15:25:41 INFO - PROCESS | 1639 | Browser inner width/height: 1024/689 15:25:41 INFO - PROCESS | 1639 | __metrics 15:25:41 ERROR - PROCESS | 1639 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:25:41 INFO - TEST-INFO | 1639: exit 0 15:25:41 INFO - Browser initialized. 15:25:41 INFO - Running cycle 1/1 for dromaeo_css test... 15:25:41 INFO - TEST-INFO | started process 1643 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp_wSfOc/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:25:44 INFO - PROCESS | 1643 | 2017-03-08 15:25:44.312 plugin-container[1644:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x4447, name = 'com.apple.tsm.portname' 15:25:44 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:44 INFO - PROCESS | 1643 | 2017-03-08 15:25:44.312 plugin-container[1644:7599] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8b0b, name = 'com.apple.CFPasteboardClient' 15:25:44 INFO - PROCESS | 1643 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:25:44 INFO - PROCESS | 1643 | 2017-03-08 15:25:44.312 plugin-container[1644:7599] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:25:54 INFO - PROCESS | 1643 | RSS: Main: 172392448 15:25:54 INFO - PROCESS | 1643 | 15:28:55 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-dojo.html (next: http://localhost:49247/tests/dromaeo/cssquery-ext.html) 15:28:57 INFO - PROCESS | 1643 | RSS: Main: 190132224 15:28:57 INFO - PROCESS | 1643 | 15:31:59 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-ext.html (next: http://localhost:49247/tests/dromaeo/cssquery-jquery.html) 15:32:00 INFO - PROCESS | 1643 | RSS: Main: 203997184 15:32:00 INFO - PROCESS | 1643 | 15:35:02 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-jquery.html (next: http://localhost:49247/tests/dromaeo/cssquery-mootools.html) 15:35:02 INFO - PROCESS | 1643 | RSS: Main: 205295616 15:35:02 INFO - PROCESS | 1643 | 15:38:04 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-mootools.html (next: http://localhost:49247/tests/dromaeo/cssquery-prototype.html) 15:38:05 INFO - PROCESS | 1643 | RSS: Main: 208502784 15:38:05 INFO - PROCESS | 1643 | 15:41:08 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-prototype.html (next: http://localhost:49247/tests/dromaeo/cssquery-yui.html) 15:41:08 INFO - PROCESS | 1643 | RSS: Main: 210243584 15:41:08 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | Cycle 1(1): loaded http://localhost:49247/tests/dromaeo/cssquery-yui.html (next: http://localhost:49247/tests/dromaeo/cssquery-dojo.html) 15:44:11 INFO - PROCESS | 1643 | RSS: Main: 210137088 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | __start_tp_report 15:44:11 INFO - PROCESS | 1643 | _x_x_mozilla_page_load 15:44:11 INFO - PROCESS | 1643 | _x_x_mozilla_page_load_details 15:44:11 INFO - PROCESS | 1643 | |i|pagename|runs| 15:44:11 INFO - PROCESS | 1643 | |0;dojo.html;2834.9291267718304;3408.386490431721;3470.8438120284586;3471.1148657092454;3502.317048175606;5748.885022299552;5749.683767392792;5856.121581762738;5877.9412205877925;5908.350081491043;5790.826275211751;5817.941820581793;5859.6484210947265;5868.853278668024;5878.647281163123;5243.606729495284;5259.99999999999;5271.15661494161;5312.415634280226;5335.813246536372;5305.549028332577;5316.76074576645;5341.332333458317;5344.839654810362;5351.973240133794;3819.1216020315346;5216.217567364888;5217.921731174036;5327.2808170897015;5330.280412144369;5269.578433725312;5303.124984377573;5323.920141197885;5365;5420.620556561043;5269.894602107975;5324.334458192726;5341.919871201935;5349.625526213166;5370.22131790892;5764.020116580149;5874.530037596982;5986.580939334249;6040.939590604125;6053.667048312341;5047.64666473347;6127.31169931711;6212.87574248517;6243.37566243372;6248.187735594343;6347.87304253917;6353.555251132422;6365.204349456318;6366.554341196118;6378;6119.2350956130485;6129.448349648508;6137.9386206137815;6138.877222455526;6158.815235542851;5302.522772950376;6150.907736383958;6162.506999440034;6312.52656050798;6334.208223972004;6221.844453888599;6249.187605611241;6251.437370636664;6276.717547710364;6290.370962903673;6329.177206963065;6331.746730130835;6336.651484168415;6343.365663433712;6354.332795056545;6155.384461553809;6181.381861813873;6197.442230199304;6244.250689917239;6263.185785847902;6157.445829875243;6224.813255602339;6240.594361366497;6252.749890004349;6269.467095296951;5066.797328106834;5075.923861142086;5077.39071311445;5103.362079740033;5114.337840201949;3821.808909554511;4411.867643970686;4471.105778844179;4561.749103799257;4565.589096981287;5912.586118971631;5931.7627294907725;5947.762089516457;5962.761489540457;6065.54508411862;6325.462335701425;6363.713632886531;6366.554341196072;6383.2978372379;6399.072134540465;5862.267216597926;5951.226340575698;6027.849303767441;6043.667598282137;6076.513878889678;5563.276774019432;5657.264555607745;5663.9716801415725;5677.460641239159;5690.260266165372;6411;6413.005984072476;6442.61344319333;6450.419462248233;6457.709403076873;6138.664296166039;6143.788236234863;6241.469475094668;6280.371962803866;6378.808635740936;6420.871582568416;6425.164728585254;6433.485321174201;6438.066480360415;6447.323031081669;6260.843478912881;6280.7487700491465;6291.402316779899;6294.716737746904;6298.874022519432;6161.815145545641;6166.136740856374;6171.753129874754;6327.493800495858;6394.96802516003;5997.790077347272;6157.722902469489;6250.0937364082865;6258.280297765815;6258.718357673824;6179.0422484514975;6195.318514963441;6236.064590311489;6251.031090181029;6265.060240963892;4971.353724015855;6001.4298641628975;6116.8227554565365;6157.907631385622;6174.567780255339;6113.719560719409;6148.661823599745;6154.953657878261;6208.472279856082;6225.3774622537385;6230.096635987847;6234.594751341148;6234.906476402948;6242.563020588696;6258.812235632939 15:44:11 INFO - PROCESS | 1643 | |1;ext.html;456.1378993701904;469.92716129;537.6612733977594;543.4864053469471;556.8914061757959;40657.3901391479;43448.56551434489;43500;43559;43683;44837.55162448374;45532;45534.31698524525;45612;45633;9560.521973901294;9807;9817.95091024544;11803.822942655866;12281.894629483344;11880.227785193978;12006;12008.69978250542;12020.098492613046;12073.456694448727;11976.820347694791;12016.459259333307;12029.157958942878;12032.33822139782;12055.035597152208;11918.702032449171;11972.64082077539;11985.40072996351;11992.760144797143;11996.940015299955;11782.293062416282;11826.881731182664;11836;11862.288262704265;11887.46506407205;32802;34734.9579512615;39083.21833563313;39109.608903910885;39598;3784.6026167252317;4009;4031.8387264509383;4151.647109995654;4402.207602631525;28511;28797.85601072002;29115;29118;29150.562741558893;3012.367402845405;3253.5933008373954;3309.420851351004;3333.599888047026;3421.9144521386916;401139;420598.9999999969;444474;445620;447050;53172;53957.46042539524;59215.40784592185;59437.702811485666;59508.7024564883;63729;63917.68041159764;63928.68035659885;64025;64125.67937160378;16945.83054169467;17094.914525427543;17158;17185.484435466955;17263.82736172622;12094.576689815814;12396.194247373893;13019.544315949086;13201.141925774986;13279.468821247236;18913.148908298885;18914.24343026291;18939.147738351807;18968.715469267972;19107.808921910604;8540.487570745776;8585.742427727178;8654.87017694735;8722.127787221227;8754.299656027502;905.4476769170932;910.1944778870748;912.2656261709387;937.2548823685187;947.5357075033322;31117.68882311148;32051;32168;32549.51175732366;32891;1076.262760009396;1137.7781332640056;1148.2536351371675;1158.901493373056;1271.1991445389347;15017;18187.818121818877;18601.06994650262;18618.81381186198;18681.29121277407;634888;646201;647241;650220;650224;68850.31149688539;69624;69672;69798.9530157038;70018.64990675014;1474.1007985129063;1521.9162946037852;1523.5581681312517;1525.5728396049124;1532.4483186053012;31636;32823.83588082044;32998.5050224242;33109.17227069342;33484.330313394086;55082.89834203374;55682.72158639181;55756.72121639366;55802.72098639481;55848;580093;581329;582057;582069;582898;30587.694123059373;39541.60458395494;45724.54275457203;45942.31086533771;46705.76647116743;50745.23882141697;51979.48020519746;53419.465805341446;54767;55412.72293638667;40537.59462405338;41729;41945.79027104845;42435.36346954737;43472;41424.792876035426;41738.58261417469;41928;42236.36645450382;42873.14253714846;40812.18375632411;41007.58992410038;41121.58878411177;41356.58643413648;41370;29381.559276611297;33009.174770629965;34446;36328.63671363253;37224.441633374976;22863.88568057149;26556;26619.7338026625;26806.329841754112;27089.1873243803 15:44:11 INFO - PROCESS | 1643 | |2;jquery.html;364.49881413057045;406.86370066027877;409.846307634637;424.537254392712;437.85988483685213;20468.69296960545;21239.787602123994;21402.25092121776;21852.016659250334;21951.90240487975;20786.792132078674;21327;21329.786702132973;21541.24605638803;21737.782622173774;5785.103308987103;6055.757769689207;6088.269407671086;6095.542834287424;6100.4814590759615;5824.155497452867;5964.13520039594;6018.759249630009;6020.879582408349;6067.878642427148;5946.672932988706;5952.107183922403;5953.910691339634;5959.970200148993;5960.731767070471;5295.8146464873735;5896.587238893279;5899.1446240295345;5942.316633587128;5984.311804142492;5253.9737301313635;5286.65636733615;5293.86765330866;5297.364316282071;5302.522772950414;26668;27013.324666883287;27155.728442715517;27178.45643087147;27443.86278068617;2277.0322612889454;2748.8900443982216;2914.2714321419644;2924.342023044819;2950.0264912579014;15816.52550423489;16908.492745217663;17342.393016244496;17721.55696107595;17735;1648.6043349596011;2002.9499262518555;2018.315818748885;2035.0943829995658;2045.5806559655234;132661;132768;147751.52248477485;157107.2144639281;176043;22650.43373915665;22708.772912270666;24778.876105619358;25626.97492100295;25792.61311080335;43596.56403435925;43749;43957.56042439599;44691.77654111774;44733.32900006502;8864.42381245217;8961.507117108622;9355.48544830041;9402.529873506297;9515.524223788922;9171.77070573241;9218.86171707425;9236.676716314896;9399;9403.294752893424;4978.755311172207;5025.84922452327;5034.446210916796;5050.191969284897;5063.924041139386;1762.3038899634573;1769.2038582637865;2034.5117171878642;2056.4036429435296;2160.9351719448127;879.7844528090659;905.298393744853;907.120093509295;910.8497097978892;943.8909281594099;17135.05757183342;18962;19528.218871245273;22072.896355182496;22109.66835497469;757.8673732096861;921.709661456639;930.4510338900081;947.9099485548862;956.3353469338742;11013.745961842906;11472.196946213851;11502.022328102037;11519.827202591969;11564.826527601925;377958;378787;568859;761558;769395.3060469324;113712.43143784229;124879;160276;162746.18626907025;168691;951.362587066667;1023.3450591621369;1065.3394895165047;1093.9343639381664;1094.3379255550437;19795.406137815888;20361.185552577732;20769;20788.480287992923;20885.06017229198;60900.69549652401;63967.680161598895;64734.676326618064;65010;66065.66967165134;94483.05516945018;95548;97457;98829.01170988198;98865.01134988846;8669.219770220458;8675.91324086751;8740.606672699616;9020.954895225483;9114.863277050717;9452.243820494206;9620.855687164838;13091.476340946636;13473.191608503521;13622.659433514242;11387.715307117389;11431.657050288504;11703.063754899758;11744.060475161796;11806.409679515818;11357.716057098638;11439.59961401347;11459.48432320531;11894;11963;6010.789622363196;9264.583093760662;9480.909695385119;11715;11941.402929853299;7870.252326029018;8375.32997360222;8653.567321634018;8861.689840855539;8871.911280887109;10114.696559103239;11441.313521188755;11546.48040838181;11599.420028998686;11773.88226117728 15:44:11 INFO - PROCESS | 1643 | |3;mootools.html;1371.9931400342996;1463.817022872141;1495.7136862298428;1501.3169008101315;1505.789189513468;6779.745747036795;7705.345045671111;7951.681932722684;8323.87514187287;8615.396922215448;8122.553259570721;8387.832243355144;8423.91576084239;8503.447275927061;8555.871661925075;2376.5246950609903;2404.699412573428;2407.0492155598527;2409.1447536124597;2419.6128619420897;2400.663907053014;2416.226807421626;2432.416220107179;2432.5134973005383;2433.7931275841574;2375.7743014413604;2393.9760602393926;2412.372783076404;2421.4188594737225;2423.200343886522;2372.0630351011428;2373.774491423321;2384.4515761374896;2395.9041638334447;2402.6756387887585;2348.471593891378;2396.029608008759;2401.591729406005;2432.902683892642;2434.2697190842678;8460.703875364332;8482.406231563793;8507.914920850773;8531;8549.444286121445;5161.071007218699;5320.6275560710765;5329.014132385482;5330;5334.973325133349;3178.7933784303946;3225.000249922508;3261.2825178460703;3484.5470088888537;3596.3346780845627;1221.5480272299217;1227.098082909061;1254.4856608790353;1278.6995056161795;1287.2791236907362;10929.945350273198;10956.890431095666;11007.504662290135;11045.889541104567;11059.391733454737;4332.696711230184;4610;4640.141573808856;4640.419947506562;4675.485696573374;8907.688230912017;8919.10808919103;8919.286457083417;8937.285017198608;8947.910520894708;5247.790088396498;5251.317328747238;5255.448177941261;5261.237120617566;5264.473552644781;4312.654987600984;4457.576530229623;4463.218936686067;4872.805087796449;4880.682755620945;3275.983620081932;3796.3925771876366;3867.284552357823;3869.555001174901;3934.6458818706456;1011.2617789013914;1035.9741006474749;1193.7075416523007;1214.2046959241711;1216.2459275249403;874.1782724239195;935.8455854783884;941.261110028638;949.1315446366498;960.9471479068578;5627.296587926509;5759.280089988752;5764.596478246483;5806.21616081832;5898.380670029671;984.5667906121283;987.4420952162005;1002.1281485107856;1017.7506510904876;1034.2294990232326;3545.2377738785767;3720.4605332226674;3790.3556395412847;3839.2705385976574;3932.0366510204612;9966.80066398668;10278.486075696186;10293.588256469808;10320.277580569438;10330.741731456774;9805.607775688892;9970.351927124713;10257.128144107834;10258.487075646337;10262.384256944608;7922.4850384724805;8107.473014254055;8108.229718176882;8142.226488483585;8157.75526734199;5518.951399234133;5539.529140023144;5573.1918871762555;5694.686792226467;5725.456081672234;7999.720044792805;9310.627574896927;9388.859167112636;9457.10157535033;9482.478463684287;7892.881606776018;9251.491167985825;9468.810623787624;9494.667686630935;9639.518024098907;5616.971915140398;6369.426751592379;6854.108965834609;6909.343612356818;6970.442364610718;8165.550894700848;8230.053543842534;8235.752927412188;8241.093479717107;8244.711435099744;7229.819254518679;7298.635068246673;7357.558546487228;7445.181030086795;7471.701131954661;7068.71725130989;7098.36114749675;7152.463565232732;7230;7253.963730181315;6387.169667943138;6528.37980391862;6531.314212007671;6540.280569137486;6545.705443254969;4895.143349913822;4984.92824042818;5020.49795020495;5066.240063990431;5086.415062267739;5060.822871199491;5074.3657042869645;5196.194589838581;5222.712750798591;5227.006868694936 15:44:11 INFO - PROCESS | 1643 | |4;prototype.html;367.21049743052436;371.3445768219093;380.8990617486365;382.4091778202676;385.01244308349084;4176.350341615061;4909.919817640118;5605.355384130826;5625.83122506325;5706.28671416073;5402.243685883969;5506.284183056197;5535.975844468776;5546.500814926666;5627.409122042188;4329;4367.519572846984;4388.266634679299;4425.601695847373;4434.046679963807;3732.1789206374556;3760.6991440684815;4362.607365337119;4422.341621891791;4442.800073996678;4147.8963025924295;4149.273877071516;4165.66674666026;4195.62239398454;4209.284421648327;3365.7980521168565;4188.204241194194;4197.706160568761;4200.852970146046;4207.915841683164;3637.399829028207;3651.379265524867;3662.249238906018;3665.5784584772828;3700.962990370089;4697.436307643071;4966.255061740768;5339.893202135974;5456.836294911159;5502.642328248651;4092.930302273295;4209.831606735757;4585.816567337302;4872.317875497433;4906.435759887623;4976.407549584134;5788.82633520995;5837;5890.528757699374;5918.40815918409;3288.8982190966053;3713.275911197318;3730.7501986834445;3919.647231749156;3951.723379363446;5353.848922481685;5681.857953551194;5841.883162336772;6115.6330620162935;6220.813375598694;3587.300476407128;4043.7169398141846;4075.1645912587846;4096.549379568245;4105.199486100182;3970.9205815884097;4035.334169861999;4078.7144899857317;4092.897677558085;4102.466679331727;4766.809327626856;4798.736069516141;4803.687760295569;4853.781579828917;4872.97667489824;5211.446391268327;5334.839954801362;5552.972700050504;5679.746234732856;5856.736446859902;1182.763447310524;1243.6579940516249;1244.3902487780922;1285.807128930668;1339.5244688135767;775.7827808213597;876.9517676527852;904.2359206470653;906.207068814787;922.5156792683895;5163.974180129076;5189.9879523492555;5198.506141916512;5222.843314700565;5229.581633469314;5802.214936830345;5845.649261044266;5863.618864773776;5928.199693041384;5969.641821490724;4670.9171946515135;4775.379200703886;4811.663183577116;5117.360329958755;5132.871678208;2691.690455597592;2719.6872359678496;2777.777777777773;2823.774098072149;3025.6520500142324;5060.620453465931;5509.614326997072;5861.710423706778;5904.630573375757;5970.402959703995;5556.499915007668;5828.04760405269;5898.233229680114;5909.881802363929;6039.939600604026;4918.6556941014505;5089.618278629117;5781.935574145794;5812.070068788974;5821.359650438449;4868.223098569068;5127.8206012616565;5302.867428314169;5623.803166889139;5705.572082093777;4890.046440943982;5115.477765848573;5312.1766126250495;5666.320041595035;5719.656820590778;5639.6334238274385;5722.253318407104;5830.737616807339;5833.737481813413;5864.882702346014;3706.4625629283064;3785.2051069275763;3796.449514820391;3866.555346135118;3884.8820250533395;5177.715654952047;5200.97399513;5312.9734351328;5619.747111379916;5688.971555142198;4442.267025940813;5530.225768392348;5538.889222215614;5671.291088613923;5860.619059761102;5199.0105689228085;5812.593118481835;5814.273215848019;5930.258717660293;5937.821865344046;4965.726885021359;5539.0890142900635;5653.434656534314;5704.543636508986;5729.997250481232;4847.612191024639;5457.9727101365825;5885.941140588539;5948.137520059654;5952.148998566155;5626.943730562805;5826.796062137805;5868.5305175585;5874.559408044328;5965.522758179249 15:44:11 INFO - PROCESS | 1643 | |5;yui.html;732.6410059071056;739.81504623844;777.6111944027986;845.374422927034;859.4327743689164;12884;14884.25578721065;15075.92462037691;15076.396944122222;15865.920670396647;14538.34577444015;14880.181590012571;14924.626884327898;15019.624509387271;15039.172845493493;874.5539774714878;898.6899519665704;901.265468643057;902.2601466797229;905.9275257979345;867.6442658510013;883.2007033634571;894.6242578117174;895.2345744388551;895.38218629146;783.8158032862273;964.84080126779;966.2077096780642;966.2608104799833;969.06969309463;952.7046615549167;959.836827739286;960.6925783749203;963.494165563078;963.8313295173386;715.9713611455536;719.5826420675995;721.5418209436992;721.7618185998608;721.9747308844165;13386.464541418332;13942.884569234437;16097.873148879584;16570.83429165705;16716.99698018123;11466.48400821965;13465.557922251555;13729.588112356645;14781.039232449857;14908;11733.354665493394;11959.701007474883;12069.57756478519;12269.466316710412;12636.747265054648;3789.886303410902;3807.3527500325013;3837.347650899354;3864.14988702485;3979.4030895365636;99662.5016874911;118883.4055829724;119684.40157799242;120701;121307;11149.609763658233;11193.384363859903;11331.490082946288;11411.60059397917;11465.656030319104;22784.31647050591;23539.17612883541;24133.637995430083;24144.517109657707;24204.39489012789;7565.6217189140325;8047;8087.353011759045;8090.433669643068;8233.135520770353;4336.436263285816;4391.956080439219;4451.421315228999;4467.061916997402;4490.865274041784;3412.941987983733;3581.6776490116013;3596.1369271374683;3690.5202323698286;4147.730397524151;629.770133901132;639.7824739588469;641.7753786174801;645.2579533536377;652.7356420649644;685.2941470285614;686.3719696477667;718.2292961949764;731.1883808972037;748.9101307842984;10658.28850952989;10708.73228169302;10839.295445795999;10905.291156074829;11023.393713345842;696.314130581377;729.2853004056054;739.6006156675335;741.1217707017202;742.9665665045086;5075.746212689351;5193.909279051366;5317.388500322435;5333.733313334318;5343.19852022192;88929.11070889208;89901.55049224843;96154.51922740342;97011.51494242484;98050;86237.1376286229;88180;89248.21503570022;89871;91262;77633.61183194161;78003.21996780073;78998.02504937307;80272.59863700643;80815.19184808194;10097.949510252402;10101.44442055694;10131.107272309693;10166.847497287694;10256.53845576936;49750.502494974586;50435.243471347225;51382.48617513927;52779.73610131925;52860.47139528555;53932.460675392744;55982.16026759683;57539.71230143822;64461.35538644553;65445.03664890061;11106.278091924;11762;11901.642950711492;12016.33910134951;12219.51121955112;12968;13337.266450345323;13469;13683.315834208051;14072.366743496772;12364.134510584172;12720.872791272339;12752.68118297013;12886.484540618645;13148;12119.939400302943;12657.810132848199;12660.240385576895;13093.934530327288;13096.607101786962;11708.473118709508;12215;12402.627921162379;12410.75178496443;12615.96837316462;8558.78603034929;8962.552493601528;9779;10259.640912568024;10292.485375731334;8977.7755556109;9359.672411465566;10196.643117490852;10240.18078553699;10347.793044139225 15:44:11 INFO - PROCESS | 1643 | __end_tp_report 15:44:11 INFO - PROCESS | 1643 | __start_cc_report 15:44:11 INFO - PROCESS | 1643 | _x_x_mozilla_cycle_collect,374 15:44:11 INFO - PROCESS | 1643 | __end_cc_report 15:44:11 INFO - PROCESS | 1643 | __startTimestamp1489016651727__endTimestamp 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | ------- Summary: start ------- 15:44:11 INFO - PROCESS | 1643 | Number of tests: 6 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#0] dojo.html Cycles:165 Average:5834.81 Median:6138.88 stddev:680.83 (11.1%) stddev-sans-first:640.96 15:44:11 INFO - PROCESS | 1643 | Values: 2834.9 3408.4 3470.8 3471.1 3502.3 5748.9 5749.7 5856.1 5877.9 5908.4 5790.8 5817.9 5859.6 5868.9 5878.6 5243.6 5260.0 5271.2 5312.4 5335.8 5305.5 5316.8 5341.3 5344.8 5352.0 3819.1 5216.2 5217.9 5327.3 5330.3 5269.6 5303.1 5323.9 5365.0 5420.6 5269.9 5324.3 5341.9 5349.6 5370.2 5764.0 5874.5 5986.6 6040.9 6053.7 5047.6 6127.3 6212.9 6243.4 6248.2 6347.9 6353.6 6365.2 6366.6 6378.0 6119.2 6129.4 6137.9 6138.9 6158.8 5302.5 6150.9 6162.5 6312.5 6334.2 6221.8 6249.2 6251.4 6276.7 6290.4 6329.2 6331.7 6336.7 6343.4 6354.3 6155.4 6181.4 6197.4 6244.3 6263.2 6157.4 6224.8 6240.6 6252.7 6269.5 5066.8 5075.9 5077.4 5103.4 5114.3 3821.8 4411.9 4471.1 4561.7 4565.6 5912.6 5931.8 5947.8 5962.8 6065.5 6325.5 6363.7 6366.6 6383.3 6399.1 5862.3 5951.2 6027.8 6043.7 6076.5 5563.3 5657.3 5664.0 5677.5 5690.3 6411.0 6413.0 6442.6 6450.4 6457.7 6138.7 6143.8 6241.5 6280.4 6378.8 6420.9 6425.2 6433.5 6438.1 6447.3 6260.8 6280.7 6291.4 6294.7 6298.9 6161.8 6166.1 6171.8 6327.5 6395.0 5997.8 6157.7 6250.1 6258.3 6258.7 6179.0 6195.3 6236.1 6251.0 6265.1 4971.4 6001.4 6116.8 6157.9 6174.6 6113.7 6148.7 6155.0 6208.5 6225.4 6230.1 6234.6 6234.9 6242.6 6258.8 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#1] ext.html Cycles:180 Average:70843.20 Median:202574.00 stddev:149359.33 (73.7%) stddev-sans-first:149684.83 15:44:11 INFO - PROCESS | 1643 | Values: 456.1 469.9 537.7 543.5 556.9 40657.4 43448.6 43500.0 43559.0 43683.0 44837.6 45532.0 45534.3 45612.0 45633.0 9560.5 9807.0 9818.0 11803.8 12281.9 11880.2 12006.0 12008.7 12020.1 12073.5 11976.8 12016.5 12029.2 12032.3 12055.0 11918.7 11972.6 11985.4 11992.8 11996.9 11782.3 11826.9 11836.0 11862.3 11887.5 32802.0 34735.0 39083.2 39109.6 39598.0 3784.6 4009.0 4031.8 4151.6 4402.2 28511.0 28797.9 29115.0 29118.0 29150.6 3012.4 3253.6 3309.4 3333.6 3421.9 401139.0 420599.0 444474.0 445620.0 447050.0 53172.0 53957.5 59215.4 59437.7 59508.7 63729.0 63917.7 63928.7 64025.0 64125.7 16945.8 17094.9 17158.0 17185.5 17263.8 12094.6 12396.2 13019.5 13201.1 13279.5 18913.1 18914.2 18939.1 18968.7 19107.8 8540.5 8585.7 8654.9 8722.1 8754.3 905.4 910.2 912.3 937.3 947.5 31117.7 32051.0 32168.0 32549.5 32891.0 1076.3 1137.8 1148.3 1158.9 1271.2 15017.0 18187.8 18601.1 18618.8 18681.3 634888.0 646201.0 647241.0 650220.0 650224.0 68850.3 69624.0 69672.0 69799.0 70018.6 1474.1 1521.9 1523.6 1525.6 1532.4 31636.0 32823.8 32998.5 33109.2 33484.3 55082.9 55682.7 55756.7 55802.7 55848.0 580093.0 581329.0 582057.0 582069.0 582898.0 30587.7 39541.6 45724.5 45942.3 46705.8 50745.2 51979.5 53419.5 54767.0 55412.7 40537.6 41729.0 41945.8 42435.4 43472.0 41424.8 41738.6 41928.0 42236.4 42873.1 40812.2 41007.6 41121.6 41356.6 41370.0 29381.6 33009.2 34446.0 36328.6 37224.4 22863.9 26556.0 26619.7 26806.3 27089.2 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#2] jquery.html Cycles:180 Average:38084.67 Median:378372.50 stddev:101481.11 (26.8%) stddev-sans-first:101726.27 15:44:11 INFO - PROCESS | 1643 | Values: 364.5 406.9 409.8 424.5 437.9 20468.7 21239.8 21402.3 21852.0 21951.9 20786.8 21327.0 21329.8 21541.2 21737.8 5785.1 6055.8 6088.3 6095.5 6100.5 5824.2 5964.1 6018.8 6020.9 6067.9 5946.7 5952.1 5953.9 5960.0 5960.7 5295.8 5896.6 5899.1 5942.3 5984.3 5254.0 5286.7 5293.9 5297.4 5302.5 26668.0 27013.3 27155.7 27178.5 27443.9 2277.0 2748.9 2914.3 2924.3 2950.0 15816.5 16908.5 17342.4 17721.6 17735.0 1648.6 2002.9 2018.3 2035.1 2045.6 132661.0 132768.0 147751.5 157107.2 176043.0 22650.4 22708.8 24778.9 25627.0 25792.6 43596.6 43749.0 43957.6 44691.8 44733.3 8864.4 8961.5 9355.5 9402.5 9515.5 9171.8 9218.9 9236.7 9399.0 9403.3 4978.8 5025.8 5034.4 5050.2 5063.9 1762.3 1769.2 2034.5 2056.4 2160.9 879.8 905.3 907.1 910.8 943.9 17135.1 18962.0 19528.2 22072.9 22109.7 757.9 921.7 930.5 947.9 956.3 11013.7 11472.2 11502.0 11519.8 11564.8 377958.0 378787.0 568859.0 761558.0 769395.3 113712.4 124879.0 160276.0 162746.2 168691.0 951.4 1023.3 1065.3 1093.9 1094.3 19795.4 20361.2 20769.0 20788.5 20885.1 60900.7 63967.7 64734.7 65010.0 66065.7 94483.1 95548.0 97457.0 98829.0 98865.0 8669.2 8675.9 8740.6 9021.0 9114.9 9452.2 9620.9 13091.5 13473.2 13622.7 11387.7 11431.7 11703.1 11744.1 11806.4 11357.7 11439.6 11459.5 11894.0 11963.0 6010.8 9264.6 9480.9 11715.0 11941.4 7870.3 8375.3 8653.6 8861.7 8871.9 10114.7 11441.3 11546.5 11599.4 11773.9 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#3] mootools.html Cycles:180 Average:5476.92 Median:5237.40 stddev:2964.56 (56.6%) stddev-sans-first:2956.82 15:44:11 INFO - PROCESS | 1643 | Values: 1372.0 1463.8 1495.7 1501.3 1505.8 6779.7 7705.3 7951.7 8323.9 8615.4 8122.6 8387.8 8423.9 8503.4 8555.9 2376.5 2404.7 2407.0 2409.1 2419.6 2400.7 2416.2 2432.4 2432.5 2433.8 2375.8 2394.0 2412.4 2421.4 2423.2 2372.1 2373.8 2384.5 2395.9 2402.7 2348.5 2396.0 2401.6 2432.9 2434.3 8460.7 8482.4 8507.9 8531.0 8549.4 5161.1 5320.6 5329.0 5330.0 5335.0 3178.8 3225.0 3261.3 3484.5 3596.3 1221.5 1227.1 1254.5 1278.7 1287.3 10929.9 10956.9 11007.5 11045.9 11059.4 4332.7 4610.0 4640.1 4640.4 4675.5 8907.7 8919.1 8919.3 8937.3 8947.9 5247.8 5251.3 5255.4 5261.2 5264.5 4312.7 4457.6 4463.2 4872.8 4880.7 3276.0 3796.4 3867.3 3869.6 3934.6 1011.3 1036.0 1193.7 1214.2 1216.2 874.2 935.8 941.3 949.1 960.9 5627.3 5759.3 5764.6 5806.2 5898.4 984.6 987.4 1002.1 1017.8 1034.2 3545.2 3720.5 3790.4 3839.3 3932.0 9966.8 10278.5 10293.6 10320.3 10330.7 9805.6 9970.4 10257.1 10258.5 10262.4 7922.5 8107.5 8108.2 8142.2 8157.8 5519.0 5539.5 5573.2 5694.7 5725.5 7999.7 9310.6 9388.9 9457.1 9482.5 7892.9 9251.5 9468.8 9494.7 9639.5 5617.0 6369.4 6854.1 6909.3 6970.4 8165.6 8230.1 8235.8 8241.1 8244.7 7229.8 7298.6 7357.6 7445.2 7471.7 7068.7 7098.4 7152.5 7230.0 7254.0 6387.2 6528.4 6531.3 6540.3 6545.7 4895.1 4984.9 5020.5 5066.2 5086.4 5060.8 5074.4 5196.2 5222.7 5227.0 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#4] prototype.html Cycles:180 Average:4626.52 Median:5170.84 stddev:1411.52 (27.3%) stddev-sans-first:1378.80 15:44:11 INFO - PROCESS | 1643 | Values: 367.2 371.3 380.9 382.4 385.0 4176.4 4909.9 5605.4 5625.8 5706.3 5402.2 5506.3 5536.0 5546.5 5627.4 4329.0 4367.5 4388.3 4425.6 4434.0 3732.2 3760.7 4362.6 4422.3 4442.8 4147.9 4149.3 4165.7 4195.6 4209.3 3365.8 4188.2 4197.7 4200.9 4207.9 3637.4 3651.4 3662.2 3665.6 3701.0 4697.4 4966.3 5339.9 5456.8 5502.6 4092.9 4209.8 4585.8 4872.3 4906.4 4976.4 5788.8 5837.0 5890.5 5918.4 3288.9 3713.3 3730.8 3919.6 3951.7 5353.8 5681.9 5841.9 6115.6 6220.8 3587.3 4043.7 4075.2 4096.5 4105.2 3970.9 4035.3 4078.7 4092.9 4102.5 4766.8 4798.7 4803.7 4853.8 4873.0 5211.4 5334.8 5553.0 5679.7 5856.7 1182.8 1243.7 1244.4 1285.8 1339.5 775.8 877.0 904.2 906.2 922.5 5164.0 5190.0 5198.5 5222.8 5229.6 5802.2 5845.6 5863.6 5928.2 5969.6 4670.9 4775.4 4811.7 5117.4 5132.9 2691.7 2719.7 2777.8 2823.8 3025.7 5060.6 5509.6 5861.7 5904.6 5970.4 5556.5 5828.0 5898.2 5909.9 6039.9 4918.7 5089.6 5781.9 5812.1 5821.4 4868.2 5127.8 5302.9 5623.8 5705.6 4890.0 5115.5 5312.2 5666.3 5719.7 5639.6 5722.3 5830.7 5833.7 5864.9 3706.5 3785.2 3796.4 3866.6 3884.9 5177.7 5201.0 5313.0 5619.7 5689.0 4442.3 5530.2 5538.9 5671.3 5860.6 5199.0 5812.6 5814.3 5930.3 5937.8 4965.7 5539.1 5653.4 5704.5 5730.0 4847.6 5458.0 5885.9 5948.1 5952.1 5626.9 5826.8 5868.5 5874.6 5965.5 15:44:11 INFO - PROCESS | 1643 | 15:44:11 INFO - PROCESS | 1643 | [#5] yui.html Cycles:180 Average:20302.19 Median:4421.69 stddev:29424.67 (665.5%) stddev-sans-first:29470.52 15:44:11 INFO - PROCESS | 1643 | Values: 732.6 739.8 777.6 845.4 859.4 12884.0 14884.3 15075.9 15076.4 15865.9 14538.3 14880.2 14924.6 15019.6 15039.2 874.6 898.7 901.3 902.3 905.9 867.6 883.2 894.6 895.2 895.4 783.8 964.8 966.2 966.3 969.1 952.7 959.8 960.7 963.5 963.8 716.0 719.6 721.5 721.8 722.0 13386.5 13942.9 16097.9 16570.8 16717.0 11466.5 13465.6 13729.6 14781.0 14908.0 11733.4 11959.7 12069.6 12269.5 12636.7 3789.9 3807.4 3837.3 3864.1 3979.4 99662.5 118883.4 119684.4 120701.0 121307.0 11149.6 11193.4 11331.5 11411.6 11465.7 22784.3 23539.2 24133.6 24144.5 24204.4 7565.6 8047.0 8087.4 8090.4 8233.1 4336.4 4392.0 4451.4 4467.1 4490.9 3412.9 3581.7 3596.1 3690.5 4147.7 629.8 639.8 641.8 645.3 652.7 685.3 686.4 718.2 731.2 748.9 10658.3 10708.7 10839.3 10905.3 11023.4 696.3 729.3 739.6 741.1 743.0 5075.7 5193.9 5317.4 5333.7 5343.2 88929.1 89901.6 96154.5 97011.5 98050.0 86237.1 88180.0 89248.2 89871.0 91262.0 77633.6 78003.2 78998.0 80272.6 80815.2 10097.9 10101.4 10131.1 10166.8 10256.5 49750.5 50435.2 51382.5 52779.7 52860.5 53932.5 55982.2 57539.7 64461.4 65445.0 11106.3 11762.0 11901.6 12016.3 12219.5 12968.0 13337.3 13469.0 13683.3 14072.4 12364.1 12720.9 12752.7 12886.5 13148.0 12119.9 12657.8 12660.2 13093.9 13096.6 11708.5 12215.0 12402.6 12410.8 12616.0 8558.8 8962.6 9779.0 10259.6 10292.5 8977.8 9359.7 10196.6 10240.2 10347.8 15:44:11 INFO - PROCESS | 1643 | -------- Summary: end -------- 15:44:11 INFO - PROCESS | 1643 | 15:44:12 INFO - TEST-INFO | 1643: exit 0 15:44:12 INFO - TEST-OK | dromaeo_css | took 1113978ms 15:44:12 INFO - TEST-START | kraken 15:44:12 INFO - operating with platform_type : mac_ 15:44:12 INFO - Initialising browser for kraken test... 15:44:12 INFO - TEST-INFO | started process 1656 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp5lxsnJ/profile http://localhost:49247/getInfo.html) 15:44:14 INFO - PROCESS | 1656 | 2017-03-08 15:44:14.868 plugin-container[1658:9869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x8537, name = 'com.apple.tsm.portname' 15:44:14 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:14 INFO - PROCESS | 1656 | 2017-03-08 15:44:14.869 plugin-container[1658:9869] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x881b, name = 'com.apple.CFPasteboardClient' 15:44:14 INFO - PROCESS | 1656 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:14 INFO - PROCESS | 1656 | 2017-03-08 15:44:14.869 plugin-container[1658:9869] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:44:14 INFO - PROCESS | 1656 | __metrics Screen width/height:1600/1200 15:44:14 INFO - PROCESS | 1656 | colorDepth:24 15:44:14 INFO - PROCESS | 1656 | Browser inner width/height: 1024/689 15:44:14 INFO - PROCESS | 1656 | __metrics 15:44:15 ERROR - PROCESS | 1656 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3277: TypeError: this.tabs is undefined 15:44:15 INFO - TEST-INFO | 1656: exit 0 15:44:15 INFO - Browser initialized. 15:44:15 INFO - Running cycle 1/1 for kraken test... 15:44:15 INFO - TEST-INFO | started process 1659 (/builds/slave/test/build/application/Firefox.app/Contents/MacOS/firefox -foreground -profile /var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/T/tmp5lxsnJ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 15:44:18 INFO - PROCESS | 1659 | 2017-03-08 15:44:18.094 plugin-container[1660:9989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x823f, name = 'com.apple.tsm.portname' 15:44:18 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:18 INFO - PROCESS | 1659 | 2017-03-08 15:44:18.095 plugin-container[1660:9989] *** CFMessagePort: bootstrap_register(): failed 1100 (0x44c) 'Permission denied', port = 0x880b, name = 'com.apple.CFPasteboardClient' 15:44:18 INFO - PROCESS | 1659 | See /usr/include/servers/bootstrap_defs.h for the error codes. 15:44:18 INFO - PROCESS | 1659 | 2017-03-08 15:44:18.095 plugin-container[1660:9989] Failed to allocate communication port for com.apple.CFPasteboardClient; this is likely due to sandbox restrictions 15:44:27 INFO - PROCESS | 1659 | RSS: Main: 172449792 15:44:27 INFO - PROCESS | 1659 | 15:44:31 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection) 15:44:31 INFO - PROCESS | 1659 | RSS: Main: 185774080 15:44:31 INFO - PROCESS | 1659 | 15:44:34 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-dft) 15:44:35 INFO - PROCESS | 1659 | RSS: Main: 193765376 15:44:35 INFO - PROCESS | 1659 | 15:44:38 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-fft) 15:44:39 INFO - PROCESS | 1659 | RSS: Main: 197513216 15:44:39 INFO - PROCESS | 1659 | 15:44:41 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator) 15:44:41 INFO - PROCESS | 1659 | RSS: Main: 197791744 15:44:41 INFO - PROCESS | 1659 | 15:44:43 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur) 15:44:44 INFO - PROCESS | 1659 | RSS: Main: 197750784 15:44:44 INFO - PROCESS | 1659 | 15:44:47 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom) 15:44:47 INFO - PROCESS | 1659 | RSS: Main: 203149312 15:44:47 INFO - PROCESS | 1659 | 15:44:51 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate) 15:44:51 INFO - PROCESS | 1659 | RSS: Main: 202252288 15:44:51 INFO - PROCESS | 1659 | 15:44:54 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial) 15:44:55 INFO - PROCESS | 1659 | RSS: Main: 202403840 15:44:55 INFO - PROCESS | 1659 | 15:44:57 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox) 15:44:57 INFO - PROCESS | 1659 | RSS: Main: 201912320 15:44:57 INFO - PROCESS | 1659 | 15:44:59 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes) 15:45:00 INFO - PROCESS | 1659 | RSS: Main: 201908224 15:45:00 INFO - PROCESS | 1659 | 15:45:02 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm) 15:45:02 INFO - PROCESS | 1659 | RSS: Main: 201895936 15:45:02 INFO - PROCESS | 1659 | 15:45:05 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 15:45:06 INFO - PROCESS | 1659 | RSS: Main: 202248192 15:45:06 INFO - PROCESS | 1659 | 15:45:09 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 15:45:09 INFO - PROCESS | 1659 | RSS: Main: 202780672 15:45:09 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | Cycle 1(1): loaded http://localhost:49247/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:49247/tests/kraken/driver.html?testName=ai-astar) 15:45:11 INFO - PROCESS | 1659 | RSS: Main: 204279808 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | __start_tp_report 15:45:11 INFO - PROCESS | 1659 | _x_x_mozilla_page_load 15:45:11 INFO - PROCESS | 1659 | _x_x_mozilla_page_load_details 15:45:11 INFO - PROCESS | 1659 | |i|pagename|runs| 15:45:11 INFO - PROCESS | 1659 | |0;ai-astar;104;118;118;119;96;171;170;154;174;142 15:45:11 INFO - PROCESS | 1659 | |1;audio-beat-detection;138;109;111;145;112;118;141;96;157;140 15:45:11 INFO - PROCESS | 1659 | |2;audio-dft;194;199;198;199;196;194;195;196;199;214 15:45:11 INFO - PROCESS | 1659 | |3;audio-fft;82;58;60;88;61;53;69;86;94;84 15:45:11 INFO - PROCESS | 1659 | |4;audio-oscillator;84;69;83;73;69;71;81;70;68;82 15:45:11 INFO - PROCESS | 1659 | |5;imaging-gaussian-blur;93;116;87;89;121;106;137;131;90;99 15:45:11 INFO - PROCESS | 1659 | |6;imaging-darkroom;98;101;128;180;135;122;107;178;133;167 15:45:11 INFO - PROCESS | 1659 | |7;imaging-desaturate;141;78;136;148;126;91;92;93;92;92 15:45:11 INFO - PROCESS | 1659 | |8;json-parse-financial;60;59;57;62;61;62;62;62;62;61 15:45:11 INFO - PROCESS | 1659 | |9;json-stringify-tinderbox;69;76;82;76;76;70;70;71;71;72 15:45:11 INFO - PROCESS | 1659 | |10;stanford-crypto-aes;82;82;87;83;85;84;84;84;83;82 15:45:11 INFO - PROCESS | 1659 | |11;stanford-crypto-ccm;148;132;133;131;140;137;132;136;189;180 15:45:11 INFO - PROCESS | 1659 | |12;stanford-crypto-pbkdf2;169;170;165;167;163;166;165;167;165;163 15:45:11 INFO - PROCESS | 1659 | |13;stanford-crypto-sha256-iterative;67;67;66;69;68;88;82;62;74;85 15:45:11 INFO - PROCESS | 1659 | __end_tp_report 15:45:11 INFO - PROCESS | 1659 | __start_cc_report 15:45:11 INFO - PROCESS | 1659 | _x_x_mozilla_cycle_collect,929 15:45:11 INFO - PROCESS | 1659 | __end_cc_report 15:45:11 INFO - PROCESS | 1659 | __startTimestamp1489016711955__endTimestamp 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | ------- Summary: start ------- 15:45:11 INFO - PROCESS | 1659 | Number of tests: 14 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#0] ai-astar Cycles:10 Average:136.60 Median:162.00 stddev:29.33 (18.1%) stddev-sans-first:28.64 15:45:11 INFO - PROCESS | 1659 | Values: 104.0 118.0 118.0 119.0 96.0 171.0 170.0 154.0 174.0 142.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#1] audio-beat-detection Cycles:10 Average:126.70 Median:140.50 stddev:19.88 (14.1%) stddev-sans-first:20.66 15:45:11 INFO - PROCESS | 1659 | Values: 138.0 109.0 111.0 145.0 112.0 118.0 141.0 96.0 157.0 140.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#2] audio-dft Cycles:10 Average:198.40 Median:198.50 stddev:5.83 (2.9%) stddev-sans-first:5.97 15:45:11 INFO - PROCESS | 1659 | Values: 194.0 199.0 198.0 199.0 196.0 194.0 195.0 196.0 199.0 214.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#3] audio-fft Cycles:10 Average:73.50 Median:83.00 stddev:14.86 (17.9%) stddev-sans-first:15.44 15:45:11 INFO - PROCESS | 1659 | Values: 82.0 58.0 60.0 88.0 61.0 53.0 69.0 86.0 94.0 84.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#4] audio-oscillator Cycles:10 Average:75.00 Median:77.00 stddev:6.63 (8.6%) stddev-sans-first:6.18 15:45:11 INFO - PROCESS | 1659 | Values: 84.0 69.0 83.0 73.0 69.0 71.0 81.0 70.0 68.0 82.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#5] imaging-gaussian-blur Cycles:10 Average:106.90 Median:88.00 stddev:18.34 (20.8%) stddev-sans-first:18.75 15:45:11 INFO - PROCESS | 1659 | Values: 93.0 116.0 87.0 89.0 121.0 106.0 137.0 131.0 90.0 99.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#6] imaging-darkroom Cycles:10 Average:134.90 Median:151.00 stddev:30.60 (20.3%) stddev-sans-first:29.40 15:45:11 INFO - PROCESS | 1659 | Values: 98.0 101.0 128.0 180.0 135.0 122.0 107.0 178.0 133.0 167.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#7] imaging-desaturate Cycles:10 Average:108.90 Median:91.50 stddev:25.76 (28.1%) stddev-sans-first:24.56 15:45:11 INFO - PROCESS | 1659 | Values: 141.0 78.0 136.0 148.0 126.0 91.0 92.0 93.0 92.0 92.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#8] json-parse-financial Cycles:10 Average:60.80 Median:62.00 stddev:1.69 (2.7%) stddev-sans-first:1.76 15:45:11 INFO - PROCESS | 1659 | Values: 60.0 59.0 57.0 62.0 61.0 62.0 62.0 62.0 62.0 61.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#9] json-stringify-tinderbox Cycles:10 Average:73.30 Median:74.00 stddev:4.08 (5.5%) stddev-sans-first:4.02 15:45:11 INFO - PROCESS | 1659 | Values: 69.0 76.0 82.0 76.0 76.0 70.0 70.0 71.0 71.0 72.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#10] stanford-crypto-aes Cycles:10 Average:83.60 Median:84.00 stddev:1.58 (1.9%) stddev-sans-first:1.56 15:45:11 INFO - PROCESS | 1659 | Values: 82.0 82.0 87.0 83.0 85.0 84.0 84.0 84.0 83.0 82.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#11] stanford-crypto-ccm Cycles:10 Average:145.80 Median:138.50 stddev:21.11 (15.2%) stddev-sans-first:22.38 15:45:11 INFO - PROCESS | 1659 | Values: 148.0 132.0 133.0 131.0 140.0 137.0 132.0 136.0 189.0 180.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:166.00 Median:166.50 stddev:2.31 (1.4%) stddev-sans-first:2.18 15:45:11 INFO - PROCESS | 1659 | Values: 169.0 170.0 165.0 167.0 163.0 166.0 165.0 167.0 165.0 163.0 15:45:11 INFO - PROCESS | 1659 | 15:45:11 INFO - PROCESS | 1659 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:72.80 Median:71.50 stddev:9.03 (12.6%) stddev-sans-first:9.33 15:45:11 INFO - PROCESS | 1659 | Values: 67.0 67.0 66.0 69.0 68.0 88.0 82.0 62.0 74.0 85.0 15:45:11 INFO - PROCESS | 1659 | -------- Summary: end -------- 15:45:11 INFO - PROCESS | 1659 | 15:45:12 INFO - TEST-INFO | 1659: exit 0 15:45:12 INFO - TEST-OK | kraken | took 60508ms 15:45:12 INFO - SUITE-END | took 1174s 15:45:13 INFO - Completed test suite (00:19:34) 15:45:13 INFO - javascript benchmark 15:45:13 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7578.792923081282, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2834.9291267718304, 3408.386490431721, 3470.8438120284586, 3471.1148657092454, 3502.317048175606, 5748.885022299552, 5749.683767392792, 5856.121581762738, 5877.9412205877925, 5908.350081491043, 5790.826275211751, 5817.941820581793, 5859.6484210947265, 5868.853278668024, 5878.647281163123, 5243.606729495284, 5259.99999999999, 5271.15661494161, 5312.415634280226, 5335.813246536372, 5305.549028332577, 5316.76074576645, 5341.332333458317, 5344.839654810362, 5351.973240133794, 3819.1216020315346, 5216.217567364888, 5217.921731174036, 5327.2808170897015, 5330.280412144369, 5269.578433725312, 5303.124984377573, 5323.920141197885, 5365.0, 5420.620556561043, 5269.894602107975, 5324.334458192726, 5341.919871201935, 5349.625526213166, 5370.22131790892, 5764.020116580149, 5874.530037596982, 5986.580939334249, 6040.939590604125, 6053.667048312341, 5047.64666473347, 6127.31169931711, 6212.87574248517, 6243.37566243372, 6248.187735594343, 6347.87304253917, 6353.555251132422, 6365.204349456318, 6366.554341196118, 6378.0, 6119.2350956130485, 6129.448349648508, 6137.9386206137815, 6138.877222455526, 6158.815235542851, 5302.522772950376, 6150.907736383958, 6162.506999440034, 6312.52656050798, 6334.208223972004, 6221.844453888599, 6249.187605611241, 6251.437370636664, 6276.717547710364, 6290.370962903673, 6329.177206963065, 6331.746730130835, 6336.651484168415, 6343.365663433712, 6354.332795056545, 6155.384461553809, 6181.381861813873, 6197.442230199304, 6244.250689917239, 6263.185785847902, 6157.445829875243, 6224.813255602339, 6240.594361366497, 6252.749890004349, 6269.467095296951, 5066.797328106834, 5075.923861142086, 5077.39071311445, 5103.362079740033, 5114.337840201949, 3821.808909554511, 4411.867643970686, 4471.105778844179, 4561.749103799257, 4565.589096981287, 5912.586118971631, 5931.7627294907725, 5947.762089516457, 5962.761489540457, 6065.54508411862, 6325.462335701425, 6363.713632886531, 6366.554341196072, 6383.2978372379, 6399.072134540465, 5862.267216597926, 5951.226340575698, 6027.849303767441, 6043.667598282137, 6076.513878889678, 5563.276774019432, 5657.264555607745, 5663.9716801415725, 5677.460641239159, 5690.260266165372, 6411.0, 6413.005984072476, 6442.61344319333, 6450.419462248233, 6457.709403076873, 6138.664296166039, 6143.788236234863, 6241.469475094668, 6280.371962803866, 6378.808635740936, 6420.871582568416, 6425.164728585254, 6433.485321174201, 6438.066480360415, 6447.323031081669, 6260.843478912881, 6280.7487700491465, 6291.402316779899, 6294.716737746904, 6298.874022519432, 6161.815145545641, 6166.136740856374, 6171.753129874754, 6327.493800495858, 6394.96802516003, 5997.790077347272, 6157.722902469489, 6250.0937364082865, 6258.280297765815, 6258.718357673824, 6179.0422484514975, 6195.318514963441, 6236.064590311489, 6251.031090181029, 6265.060240963892, 4971.353724015855, 6001.4298641628975, 6116.8227554565365, 6157.907631385622, 6174.567780255339, 6113.719560719409, 6148.661823599745, 6154.953657878261, 6208.472279856082, 6225.3774622537385, 6230.096635987847, 6234.594751341148, 6234.906476402948, 6242.563020588696, 6258.812235632939], "value": 5790.279558731125, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [456.1378993701904, 469.92716129, 537.6612733977594, 543.4864053469471, 556.8914061757959, 40657.3901391479, 43448.56551434489, 43500.0, 43559.0, 43683.0, 44837.55162448374, 45532.0, 45534.31698524525, 45612.0, 45633.0, 9560.521973901294, 9807.0, 9817.95091024544, 11803.822942655866, 12281.894629483344, 11880.227785193978, 12006.0, 12008.69978250542, 12020.098492613046, 12073.456694448727, 11976.820347694791, 12016.459259333307, 12029.157958942878, 12032.33822139782, 12055.035597152208, 11918.702032449171, 11972.64082077539, 11985.40072996351, 11992.760144797143, 11996.940015299955, 11782.293062416282, 11826.881731182664, 11836.0, 11862.288262704265, 11887.46506407205, 32802.0, 34734.9579512615, 39083.21833563313, 39109.608903910885, 39598.0, 3784.6026167252317, 4009.0, 4031.8387264509383, 4151.647109995654, 4402.207602631525, 28511.0, 28797.85601072002, 29115.0, 29118.0, 29150.562741558893, 3012.367402845405, 3253.5933008373954, 3309.420851351004, 3333.599888047026, 3421.9144521386916, 401139.0, 420598.9999999969, 444474.0, 445620.0, 447050.0, 53172.0, 53957.46042539524, 59215.40784592185, 59437.702811485666, 59508.7024564883, 63729.0, 63917.68041159764, 63928.68035659885, 64025.0, 64125.67937160378, 16945.83054169467, 17094.914525427543, 17158.0, 17185.484435466955, 17263.82736172622, 12094.576689815814, 12396.194247373893, 13019.544315949086, 13201.141925774986, 13279.468821247236, 18913.148908298885, 18914.24343026291, 18939.147738351807, 18968.715469267972, 19107.808921910604, 8540.487570745776, 8585.742427727178, 8654.87017694735, 8722.127787221227, 8754.299656027502, 905.4476769170932, 910.1944778870748, 912.2656261709387, 937.2548823685187, 947.5357075033322, 31117.68882311148, 32051.0, 32168.0, 32549.51175732366, 32891.0, 1076.262760009396, 1137.7781332640056, 1148.2536351371675, 1158.901493373056, 1271.1991445389347, 15017.0, 18187.818121818877, 18601.06994650262, 18618.81381186198, 18681.29121277407, 634888.0, 646201.0, 647241.0, 650220.0, 650224.0, 68850.31149688539, 69624.0, 69672.0, 69798.9530157038, 70018.64990675014, 1474.1007985129063, 1521.9162946037852, 1523.5581681312517, 1525.5728396049124, 1532.4483186053012, 31636.0, 32823.83588082044, 32998.5050224242, 33109.17227069342, 33484.330313394086, 55082.89834203374, 55682.72158639181, 55756.72121639366, 55802.72098639481, 55848.0, 580093.0, 581329.0, 582057.0, 582069.0, 582898.0, 30587.694123059373, 39541.60458395494, 45724.54275457203, 45942.31086533771, 46705.76647116743, 50745.23882141697, 51979.48020519746, 53419.465805341446, 54767.0, 55412.72293638667, 40537.59462405338, 41729.0, 41945.79027104845, 42435.36346954737, 43472.0, 41424.792876035426, 41738.58261417469, 41928.0, 42236.36645450382, 42873.14253714846, 40812.18375632411, 41007.58992410038, 41121.58878411177, 41356.58643413648, 41370.0, 29381.559276611297, 33009.174770629965, 34446.0, 36328.63671363253, 37224.441633374976, 22863.88568057149, 26556.0, 26619.7338026625, 26806.329841754112, 27089.1873243803], "value": 21613.631189000647, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [364.49881413057045, 406.86370066027877, 409.846307634637, 424.537254392712, 437.85988483685213, 20468.69296960545, 21239.787602123994, 21402.25092121776, 21852.016659250334, 21951.90240487975, 20786.792132078674, 21327.0, 21329.786702132973, 21541.24605638803, 21737.782622173774, 5785.103308987103, 6055.757769689207, 6088.269407671086, 6095.542834287424, 6100.4814590759615, 5824.155497452867, 5964.13520039594, 6018.759249630009, 6020.879582408349, 6067.878642427148, 5946.672932988706, 5952.107183922403, 5953.910691339634, 5959.970200148993, 5960.731767070471, 5295.8146464873735, 5896.587238893279, 5899.1446240295345, 5942.316633587128, 5984.311804142492, 5253.9737301313635, 5286.65636733615, 5293.86765330866, 5297.364316282071, 5302.522772950414, 26668.0, 27013.324666883287, 27155.728442715517, 27178.45643087147, 27443.86278068617, 2277.0322612889454, 2748.8900443982216, 2914.2714321419644, 2924.342023044819, 2950.0264912579014, 15816.52550423489, 16908.492745217663, 17342.393016244496, 17721.55696107595, 17735.0, 1648.6043349596011, 2002.9499262518555, 2018.315818748885, 2035.0943829995658, 2045.5806559655234, 132661.0, 132768.0, 147751.52248477485, 157107.2144639281, 176043.0, 22650.43373915665, 22708.772912270666, 24778.876105619358, 25626.97492100295, 25792.61311080335, 43596.56403435925, 43749.0, 43957.56042439599, 44691.77654111774, 44733.32900006502, 8864.42381245217, 8961.507117108622, 9355.48544830041, 9402.529873506297, 9515.524223788922, 9171.77070573241, 9218.86171707425, 9236.676716314896, 9399.0, 9403.294752893424, 4978.755311172207, 5025.84922452327, 5034.446210916796, 5050.191969284897, 5063.924041139386, 1762.3038899634573, 1769.2038582637865, 2034.5117171878642, 2056.4036429435296, 2160.9351719448127, 879.7844528090659, 905.298393744853, 907.120093509295, 910.8497097978892, 943.8909281594099, 17135.05757183342, 18962.0, 19528.218871245273, 22072.896355182496, 22109.66835497469, 757.8673732096861, 921.709661456639, 930.4510338900081, 947.9099485548862, 956.3353469338742, 11013.745961842906, 11472.196946213851, 11502.022328102037, 11519.827202591969, 11564.826527601925, 377958.0, 378787.0, 568859.0, 761558.0, 769395.3060469324, 113712.43143784229, 124879.0, 160276.0, 162746.18626907025, 168691.0, 951.362587066667, 1023.3450591621369, 1065.3394895165047, 1093.9343639381664, 1094.3379255550437, 19795.406137815888, 20361.185552577732, 20769.0, 20788.480287992923, 20885.06017229198, 60900.69549652401, 63967.680161598895, 64734.676326618064, 65010.0, 66065.66967165134, 94483.05516945018, 95548.0, 97457.0, 98829.01170988198, 98865.01134988846, 8669.219770220458, 8675.91324086751, 8740.606672699616, 9020.954895225483, 9114.863277050717, 9452.243820494206, 9620.855687164838, 13091.476340946636, 13473.191608503521, 13622.659433514242, 11387.715307117389, 11431.657050288504, 11703.063754899758, 11744.060475161796, 11806.409679515818, 11357.716057098638, 11439.59961401347, 11459.48432320531, 11894.0, 11963.0, 6010.789622363196, 9264.583093760662, 9480.909695385119, 11715.0, 11941.402929853299, 7870.252326029018, 8375.32997360222, 8653.567321634018, 8861.689840855539, 8871.911280887109, 10114.696559103239, 11441.313521188755, 11546.48040838181, 11599.420028998686, 11773.88226117728], "value": 10700.783854638887, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1371.9931400342996, 1463.817022872141, 1495.7136862298428, 1501.3169008101315, 1505.789189513468, 6779.745747036795, 7705.345045671111, 7951.681932722684, 8323.87514187287, 8615.396922215448, 8122.553259570721, 8387.832243355144, 8423.91576084239, 8503.447275927061, 8555.871661925075, 2376.5246950609903, 2404.699412573428, 2407.0492155598527, 2409.1447536124597, 2419.6128619420897, 2400.663907053014, 2416.226807421626, 2432.416220107179, 2432.5134973005383, 2433.7931275841574, 2375.7743014413604, 2393.9760602393926, 2412.372783076404, 2421.4188594737225, 2423.200343886522, 2372.0630351011428, 2373.774491423321, 2384.4515761374896, 2395.9041638334447, 2402.6756387887585, 2348.471593891378, 2396.029608008759, 2401.591729406005, 2432.902683892642, 2434.2697190842678, 8460.703875364332, 8482.406231563793, 8507.914920850773, 8531.0, 8549.444286121445, 5161.071007218699, 5320.6275560710765, 5329.014132385482, 5330.0, 5334.973325133349, 3178.7933784303946, 3225.000249922508, 3261.2825178460703, 3484.5470088888537, 3596.3346780845627, 1221.5480272299217, 1227.098082909061, 1254.4856608790353, 1278.6995056161795, 1287.2791236907362, 10929.945350273198, 10956.890431095666, 11007.504662290135, 11045.889541104567, 11059.391733454737, 4332.696711230184, 4610.0, 4640.141573808856, 4640.419947506562, 4675.485696573374, 8907.688230912017, 8919.10808919103, 8919.286457083417, 8937.285017198608, 8947.910520894708, 5247.790088396498, 5251.317328747238, 5255.448177941261, 5261.237120617566, 5264.473552644781, 4312.654987600984, 4457.576530229623, 4463.218936686067, 4872.805087796449, 4880.682755620945, 3275.983620081932, 3796.3925771876366, 3867.284552357823, 3869.555001174901, 3934.6458818706456, 1011.2617789013914, 1035.9741006474749, 1193.7075416523007, 1214.2046959241711, 1216.2459275249403, 874.1782724239195, 935.8455854783884, 941.261110028638, 949.1315446366498, 960.9471479068578, 5627.296587926509, 5759.280089988752, 5764.596478246483, 5806.21616081832, 5898.380670029671, 984.5667906121283, 987.4420952162005, 1002.1281485107856, 1017.7506510904876, 1034.2294990232326, 3545.2377738785767, 3720.4605332226674, 3790.3556395412847, 3839.2705385976574, 3932.0366510204612, 9966.80066398668, 10278.486075696186, 10293.588256469808, 10320.277580569438, 10330.741731456774, 9805.607775688892, 9970.351927124713, 10257.128144107834, 10258.487075646337, 10262.384256944608, 7922.4850384724805, 8107.473014254055, 8108.229718176882, 8142.226488483585, 8157.75526734199, 5518.951399234133, 5539.529140023144, 5573.1918871762555, 5694.686792226467, 5725.456081672234, 7999.720044792805, 9310.627574896927, 9388.859167112636, 9457.10157535033, 9482.478463684287, 7892.881606776018, 9251.491167985825, 9468.810623787624, 9494.667686630935, 9639.518024098907, 5616.971915140398, 6369.426751592379, 6854.108965834609, 6909.343612356818, 6970.442364610718, 8165.550894700848, 8230.053543842534, 8235.752927412188, 8241.093479717107, 8244.711435099744, 7229.819254518679, 7298.635068246673, 7357.558546487228, 7445.181030086795, 7471.701131954661, 7068.71725130989, 7098.36114749675, 7152.463565232732, 7230.0, 7253.963730181315, 6387.169667943138, 6528.37980391862, 6531.314212007671, 6540.280569137486, 6545.705443254969, 4895.143349913822, 4984.92824042818, 5020.49795020495, 5066.240063990431, 5086.415062267739, 5060.822871199491, 5074.3657042869645, 5196.194589838581, 5222.712750798591, 5227.006868694936], "value": 4471.195829569278, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [367.21049743052436, 371.3445768219093, 380.8990617486365, 382.4091778202676, 385.01244308349084, 4176.350341615061, 4909.919817640118, 5605.355384130826, 5625.83122506325, 5706.28671416073, 5402.243685883969, 5506.284183056197, 5535.975844468776, 5546.500814926666, 5627.409122042188, 4329.0, 4367.519572846984, 4388.266634679299, 4425.601695847373, 4434.046679963807, 3732.1789206374556, 3760.6991440684815, 4362.607365337119, 4422.341621891791, 4442.800073996678, 4147.8963025924295, 4149.273877071516, 4165.66674666026, 4195.62239398454, 4209.284421648327, 3365.7980521168565, 4188.204241194194, 4197.706160568761, 4200.852970146046, 4207.915841683164, 3637.399829028207, 3651.379265524867, 3662.249238906018, 3665.5784584772828, 3700.962990370089, 4697.436307643071, 4966.255061740768, 5339.893202135974, 5456.836294911159, 5502.642328248651, 4092.930302273295, 4209.831606735757, 4585.816567337302, 4872.317875497433, 4906.435759887623, 4976.407549584134, 5788.82633520995, 5837.0, 5890.528757699374, 5918.40815918409, 3288.8982190966053, 3713.275911197318, 3730.7501986834445, 3919.647231749156, 3951.723379363446, 5353.848922481685, 5681.857953551194, 5841.883162336772, 6115.6330620162935, 6220.813375598694, 3587.300476407128, 4043.7169398141846, 4075.1645912587846, 4096.549379568245, 4105.199486100182, 3970.9205815884097, 4035.334169861999, 4078.7144899857317, 4092.897677558085, 4102.466679331727, 4766.809327626856, 4798.736069516141, 4803.687760295569, 4853.781579828917, 4872.97667489824, 5211.446391268327, 5334.839954801362, 5552.972700050504, 5679.746234732856, 5856.736446859902, 1182.763447310524, 1243.6579940516249, 1244.3902487780922, 1285.807128930668, 1339.5244688135767, 775.7827808213597, 876.9517676527852, 904.2359206470653, 906.207068814787, 922.5156792683895, 5163.974180129076, 5189.9879523492555, 5198.506141916512, 5222.843314700565, 5229.581633469314, 5802.214936830345, 5845.649261044266, 5863.618864773776, 5928.199693041384, 5969.641821490724, 4670.9171946515135, 4775.379200703886, 4811.663183577116, 5117.360329958755, 5132.871678208, 2691.690455597592, 2719.6872359678496, 2777.777777777773, 2823.774098072149, 3025.6520500142324, 5060.620453465931, 5509.614326997072, 5861.710423706778, 5904.630573375757, 5970.402959703995, 5556.499915007668, 5828.04760405269, 5898.233229680114, 5909.881802363929, 6039.939600604026, 4918.6556941014505, 5089.618278629117, 5781.935574145794, 5812.070068788974, 5821.359650438449, 4868.223098569068, 5127.8206012616565, 5302.867428314169, 5623.803166889139, 5705.572082093777, 4890.046440943982, 5115.477765848573, 5312.1766126250495, 5666.320041595035, 5719.656820590778, 5639.6334238274385, 5722.253318407104, 5830.737616807339, 5833.737481813413, 5864.882702346014, 3706.4625629283064, 3785.2051069275763, 3796.449514820391, 3866.555346135118, 3884.8820250533395, 5177.715654952047, 5200.97399513, 5312.9734351328, 5619.747111379916, 5688.971555142198, 4442.267025940813, 5530.225768392348, 5538.889222215614, 5671.291088613923, 5860.619059761102, 5199.0105689228085, 5812.593118481835, 5814.273215848019, 5930.258717660293, 5937.821865344046, 4965.726885021359, 5539.0890142900635, 5653.434656534314, 5704.543636508986, 5729.997250481232, 4847.612191024639, 5457.9727101365825, 5885.941140588539, 5948.137520059654, 5952.148998566155, 5626.943730562805, 5826.796062137805, 5868.5305175585, 5874.559408044328, 5965.522758179249], "value": 4186.888894826165, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [732.6410059071056, 739.81504623844, 777.6111944027986, 845.374422927034, 859.4327743689164, 12884.0, 14884.25578721065, 15075.92462037691, 15076.396944122222, 15865.920670396647, 14538.34577444015, 14880.181590012571, 14924.626884327898, 15019.624509387271, 15039.172845493493, 874.5539774714878, 898.6899519665704, 901.265468643057, 902.2601466797229, 905.9275257979345, 867.6442658510013, 883.2007033634571, 894.6242578117174, 895.2345744388551, 895.38218629146, 783.8158032862273, 964.84080126779, 966.2077096780642, 966.2608104799833, 969.06969309463, 952.7046615549167, 959.836827739286, 960.6925783749203, 963.494165563078, 963.8313295173386, 715.9713611455536, 719.5826420675995, 721.5418209436992, 721.7618185998608, 721.9747308844165, 13386.464541418332, 13942.884569234437, 16097.873148879584, 16570.83429165705, 16716.99698018123, 11466.48400821965, 13465.557922251555, 13729.588112356645, 14781.039232449857, 14908.0, 11733.354665493394, 11959.701007474883, 12069.57756478519, 12269.466316710412, 12636.747265054648, 3789.886303410902, 3807.3527500325013, 3837.347650899354, 3864.14988702485, 3979.4030895365636, 99662.5016874911, 118883.4055829724, 119684.40157799242, 120701.0, 121307.0, 11149.609763658233, 11193.384363859903, 11331.490082946288, 11411.60059397917, 11465.656030319104, 22784.31647050591, 23539.17612883541, 24133.637995430083, 24144.517109657707, 24204.39489012789, 7565.6217189140325, 8047.0, 8087.353011759045, 8090.433669643068, 8233.135520770353, 4336.436263285816, 4391.956080439219, 4451.421315228999, 4467.061916997402, 4490.865274041784, 3412.941987983733, 3581.6776490116013, 3596.1369271374683, 3690.5202323698286, 4147.730397524151, 629.770133901132, 639.7824739588469, 641.7753786174801, 645.2579533536377, 652.7356420649644, 685.2941470285614, 686.3719696477667, 718.2292961949764, 731.1883808972037, 748.9101307842984, 10658.28850952989, 10708.73228169302, 10839.295445795999, 10905.291156074829, 11023.393713345842, 696.314130581377, 729.2853004056054, 739.6006156675335, 741.1217707017202, 742.9665665045086, 5075.746212689351, 5193.909279051366, 5317.388500322435, 5333.733313334318, 5343.19852022192, 88929.11070889208, 89901.55049224843, 96154.51922740342, 97011.51494242484, 98050.0, 86237.1376286229, 88180.0, 89248.21503570022, 89871.0, 91262.0, 77633.61183194161, 78003.21996780073, 78998.02504937307, 80272.59863700643, 80815.19184808194, 10097.949510252402, 10101.44442055694, 10131.107272309693, 10166.847497287694, 10256.53845576936, 49750.502494974586, 50435.243471347225, 51382.48617513927, 52779.73610131925, 52860.47139528555, 53932.460675392744, 55982.16026759683, 57539.71230143822, 64461.35538644553, 65445.03664890061, 11106.278091924, 11762.0, 11901.642950711492, 12016.33910134951, 12219.51121955112, 12968.0, 13337.266450345323, 13469.0, 13683.315834208051, 14072.366743496772, 12364.134510584172, 12720.872791272339, 12752.68118297013, 12886.484540618645, 13148.0, 12119.939400302943, 12657.810132848199, 12660.240385576895, 13093.934530327288, 13096.607101786962, 11708.473118709508, 12215.0, 12402.627921162379, 12410.75178496443, 12615.96837316462, 8558.78603034929, 8962.552493601528, 9779.0, 10259.640912568024, 10292.485375731334, 8977.7755556109, 9359.672411465566, 10196.643117490852, 10240.18078553699, 10347.793044139225], "value": 7557.742454042921, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1563.1999999999998, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 118.0, 118.0, 119.0, 96.0, 171.0, 170.0, 154.0, 174.0, 142.0], "value": 136.6, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [138.0, 109.0, 111.0, 145.0, 112.0, 118.0, 141.0, 96.0, 157.0, 140.0], "value": 126.7, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [194.0, 199.0, 198.0, 199.0, 196.0, 194.0, 195.0, 196.0, 199.0, 214.0], "value": 198.4, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 58.0, 60.0, 88.0, 61.0, 53.0, 69.0, 86.0, 94.0, 84.0], "value": 73.5, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 69.0, 83.0, 73.0, 69.0, 71.0, 81.0, 70.0, 68.0, 82.0], "value": 75.0, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [93.0, 116.0, 87.0, 89.0, 121.0, 106.0, 137.0, 131.0, 90.0, 99.0], "value": 106.9, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [98.0, 101.0, 128.0, 180.0, 135.0, 122.0, 107.0, 178.0, 133.0, 167.0], "value": 134.9, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [141.0, 78.0, 136.0, 148.0, 126.0, 91.0, 92.0, 93.0, 92.0, 92.0], "value": 108.9, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [60.0, 59.0, 57.0, 62.0, 61.0, 62.0, 62.0, 62.0, 62.0, 61.0], "value": 60.8, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [69.0, 76.0, 82.0, 76.0, 76.0, 70.0, 70.0, 71.0, 71.0, 72.0], "value": 73.3, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [82.0, 82.0, 87.0, 83.0, 85.0, 84.0, 84.0, 84.0, 83.0, 82.0], "value": 83.6, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [148.0, 132.0, 133.0, 131.0, 140.0, 137.0, 132.0, 136.0, 189.0, 180.0], "value": 145.8, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [169.0, 170.0, 165.0, 167.0, 163.0, 166.0, 165.0, 167.0, 165.0, 163.0], "value": 166.0, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 67.0, 66.0, 69.0, 68.0, 88.0, 82.0, 62.0, 74.0, 85.0], "value": 72.8, "unit": "score"}]}]} 15:45:13 INFO - Return code: 0 15:45:13 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 15:45:13 ERROR - # TBPL SUCCESS # 15:45:13 INFO - Running pre test command check_screen_resolution with 'bash -c screenresolution get && screenresolution list && system_profiler SPDisplaysDataType' 15:45:13 INFO - Running command: ['bash', '-c', 'screenresolution get && screenresolution list && system_profiler SPDisplaysDataType'] in /builds/slave/test/build 15:45:13 INFO - Copy/paste: bash -c "screenresolution get && screenresolution list && system_profiler SPDisplaysDataType" 15:45:13 INFO - 2017-03-08 15:45:13.236 screenresolution[1664:10387] starting screenresolution argv=screenresolution get 15:45:13 INFO - 2017-03-08 15:45:13.238 screenresolution[1664:10387] Display 0: 1600x1200x32@60 15:45:13 INFO - 2017-03-08 15:45:13.244 screenresolution[1665:10390] starting screenresolution argv=screenresolution list 15:45:13 INFO - Available Modes on Display 0 15:45:13 INFO - 1600x1200x32@60 1152x870x32@75 1280x1024x32@75 15:45:13 INFO - 1024x768x32@75 1024x768x32@70 1024x768x32@60 15:45:13 INFO - 800x600x32@75 800x600x32@60 640x480x32@75 15:45:13 INFO - 640x480x32@60 1280x1024x32@60 1280x960x32@60 15:45:13 INFO - 1280x960x32@75 1344x1008x32@60 Graphics/Displays: 15:45:13 INFO - Intel Iris: 15:45:13 INFO - Chipset Model: Intel Iris 15:45:13 INFO - Type: GPU 15:45:13 INFO - Bus: Built-In 15:45:13 INFO - VRAM (Dynamic, Max): 1536 MB 15:45:13 INFO - Vendor: Intel (0x8086) 15:45:13 INFO - Device ID: 0x0a2e 15:45:13 INFO - Revision ID: 0x0009 15:45:13 INFO - Displays: 15:45:13 INFO - Display: 15:45:13 INFO - Resolution: 1600 x 1200 @ 60 Hz 15:45:13 INFO - Pixel Depth: 32-Bit Color (ARGB8888) 15:45:13 INFO - Display Serial Number: 1600x1200 60 15:45:13 INFO - Main Display: Yes 15:45:13 INFO - Mirror: Off 15:45:13 INFO - Online: Yes 15:45:13 INFO - Rotation: Supported 15:45:13 INFO - Return code: 0 15:45:13 INFO - Running post-action listener: _resource_record_post_action 15:45:13 INFO - [mozharness: 2017-03-08 23:45:13.482352Z] Finished run-tests step (success) 15:45:13 INFO - Running post-run listener: _resource_record_post_run 15:45:13 INFO - Total resource usage - Wall time: 1190s; CPU: 54.0%; Read bytes: 40544256; Write bytes: 427051520; Read time: 408; Write time: 1513 15:45:13 INFO - TinderboxPrint: CPU usage
54.4% 15:45:13 INFO - TinderboxPrint: I/O read bytes / time
40,544,256 / 408 15:45:13 INFO - TinderboxPrint: I/O write bytes / time
427,051,520 / 1,513 15:45:13 INFO - TinderboxPrint: CPU idle
2,171.5 (45.6%) 15:45:13 INFO - TinderboxPrint: CPU system
959.0 (20.1%) 15:45:13 INFO - TinderboxPrint: CPU user
1,631.3 (34.3%) 15:45:13 INFO - TinderboxPrint: Swap in / out
162,754,560 / 0 15:45:13 INFO - install - Wall time: 15s; CPU: 54.0%; Read bytes: 122991104; Write bytes: 124352512; Read time: 11139; Write time: 306 15:45:13 INFO - run-tests - Wall time: 1176s; CPU: 54.0%; Read bytes: 35837952; Write bytes: 301314560; Read time: 361; Write time: 1202 15:45:13 INFO - Running post-run listener: _upload_blobber_files 15:45:13 INFO - Blob upload gear active. 15:45:13 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 15:45:13 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 15:45:13 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'] 15:45:13 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 15:45:14 INFO - (blobuploader) - INFO - Open directory for files ... 15:45:14 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 15:45:14 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:14 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:15 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 15:45:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:15 INFO - (blobuploader) - INFO - Done attempting. 15:45:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 15:45:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 15:45:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:16 INFO - (blobuploader) - INFO - Done attempting. 15:45:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 15:45:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 15:45:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:17 INFO - (blobuploader) - INFO - Done attempting. 15:45:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 15:45:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 15:45:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 15:45:19 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 15:45:19 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 15:45:19 INFO - (blobuploader) - INFO - Done attempting. 15:45:19 INFO - (blobuploader) - INFO - Iteration through files over. 15:45:19 INFO - Return code: 0 15:45:19 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 15:45:19 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 15:45:19 INFO - Setting buildbot property blobber_files to {"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ce9260ac3a1a349f717bc0402242c0fc8a6773d9b3276cc0f342350eb7e2afa09406a40aff1d02c651dcede006c6b66fdde7c14e37de9e0170e8e8650ffb283", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8171a324ae6a62fd977f0ba4b388ce7665f649d5e26008b2dce8044aa7330e1486bccf516514a309451a674709e923f84157c7ef093fd27afd1a60b26c0d4cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dfe5258990be75484f37e5fbd6b617783e4a89d055fecc8abf7afffff88ddc47ed92722c8c729fa67fe844a93a16d8c227a70c7c042fc638dfc305a7af72c77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1afc8f350296323104ab5b2e04149a5aa384d000fa37b0e98cb1445828d7f4e40a67c645e5577244f5f103bb1fdaf58e64a4804d50e26ed0680924d1d5f5d76"} 15:45:19 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 15:45:19 INFO - Writing to file /builds/slave/test/properties/blobber_files 15:45:19 INFO - Contents: 15:45:19 INFO - blobber_files:{"dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/0ce9260ac3a1a349f717bc0402242c0fc8a6773d9b3276cc0f342350eb7e2afa09406a40aff1d02c651dcede006c6b66fdde7c14e37de9e0170e8e8650ffb283", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8171a324ae6a62fd977f0ba4b388ce7665f649d5e26008b2dce8044aa7330e1486bccf516514a309451a674709e923f84157c7ef093fd27afd1a60b26c0d4cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dfe5258990be75484f37e5fbd6b617783e4a89d055fecc8abf7afffff88ddc47ed92722c8c729fa67fe844a93a16d8c227a70c7c042fc638dfc305a7af72c77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1afc8f350296323104ab5b2e04149a5aa384d000fa37b0e98cb1445828d7f4e40a67c645e5577244f5f103bb1fdaf58e64a4804d50e26ed0680924d1d5f5d76"} 15:45:19 INFO - Running post-run listener: copy_logs_to_upload_dir 15:45:19 INFO - Copying logs to upload dir... 15:45:19 INFO - mkdir: /builds/slave/test/build/upload/logs 15:45:19 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1253.317705 ========= master_lag: 0.23 ========= ========= Finished '/tools/buildbot/bin/python -u ...' (results: 0, elapsed: 20 mins, 53 secs) (at 2017-03-08 15:45:19.796791) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 15:45:19.800437) ========= 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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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/0ce9260ac3a1a349f717bc0402242c0fc8a6773d9b3276cc0f342350eb7e2afa09406a40aff1d02c651dcede006c6b66fdde7c14e37de9e0170e8e8650ffb283", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8171a324ae6a62fd977f0ba4b388ce7665f649d5e26008b2dce8044aa7330e1486bccf516514a309451a674709e923f84157c7ef093fd27afd1a60b26c0d4cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dfe5258990be75484f37e5fbd6b617783e4a89d055fecc8abf7afffff88ddc47ed92722c8c729fa67fe844a93a16d8c227a70c7c042fc638dfc305a7af72c77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1afc8f350296323104ab5b2e04149a5aa384d000fa37b0e98cb1445828d7f4e40a67c645e5577244f5f103bb1fdaf58e64a4804d50e26ed0680924d1d5f5d76"} build_url:https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/artifacts/public/build/firefox-53.0.en-US.mac.dmg program finished with exit code 0 elapsedTime=0.010961 build_url: 'https://queue.taskcluster.net/v1/task/ALwn6zGcR7mIAxR4HlUq6A/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/0ce9260ac3a1a349f717bc0402242c0fc8a6773d9b3276cc0f342350eb7e2afa09406a40aff1d02c651dcede006c6b66fdde7c14e37de9e0170e8e8650ffb283", "perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/b8171a324ae6a62fd977f0ba4b388ce7665f649d5e26008b2dce8044aa7330e1486bccf516514a309451a674709e923f84157c7ef093fd27afd1a60b26c0d4cf", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/8dfe5258990be75484f37e5fbd6b617783e4a89d055fecc8abf7afffff88ddc47ed92722c8c729fa67fe844a93a16d8c227a70c7c042fc638dfc305a7af72c77", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Mozilla-Beta/sha512/a1afc8f350296323104ab5b2e04149a5aa384d000fa37b0e98cb1445828d7f4e40a67c645e5577244f5f103bb1fdaf58e64a4804d50e26ed0680924d1d5f5d76"}' ========= master_lag: 0.30 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-08 15:45:20.113383) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:45:20.113759) ========= 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.x4TsDNPvxQ/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.SjoU33lzMC/Listeners TMPDIR=/var/folders/1r/5dhvqs_52pbg86_h8rkrnzj800000w/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.005223 ========= master_lag: 0.02 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-08 15:45:20.141342) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 15:45:20.141706) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-08 15:45:20.142134) ========= ========= Total master_lag: 0.62 =========